{"level":"warn","ts":"2026-02-26T01:44:45.034665Z","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-26T01:44:45.034772Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-26T01:44:45.034852Z","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-26T01:44:45.034864Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-26T01:44:45.034898Z","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-26T01:44:45.036018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-02-26T01:44:45.036121Z","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":8,"max-cpu-available":8,"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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-26T01:44:45.041612Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.205915ms"} {"level":"info","ts":"2026-02-26T01:44:45.058802Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-02-26T01:44:45.058909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T01:44:45.058942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-02-26T01:44:45.058968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T01:44:45.058981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-02-26T01:44:45.059033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-02-26T01:44:45.06975Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T01:44:45.071393Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T01:44:45.072412Z","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-26T01:44:45.074185Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T01:44:45.07749Z","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-26T01:44:45.078009Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T01:44:45.078731Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T01:44:45.078766Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T01:44:45.078872Z","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-26T01:44:45.078935Z","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-26T01:44:45.078945Z","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-26T01:44:45.079034Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-26T01:44:45.079055Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-26T01:44:45.08025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-02-26T01:44:45.08033Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-26T01:44:45.459988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T01:44:45.460056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T01:44:45.460098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-02-26T01:44:45.460116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-02-26T01:44:45.460123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-26T01:44:45.460136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-02-26T01:44:45.460147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-26T01:44:45.461115Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:44:45.462266Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:44:45.462266Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T01:44:45.462434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:44:45.462823Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:44:45.462802Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T01:44:45.464896Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T01:44:45.46299Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:44:45.467332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-02-26T01:44:45.467356Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T01:44:45.467532Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:44:47.019472Z","caller":"traceutil/trace.go:171","msg":"trace[717915673] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"205.408883ms","start":"2026-02-26T01:44:46.814029Z","end":"2026-02-26T01:44:47.019438Z","steps":["trace[717915673] 'read index received' (duration: 205.30416ms)","trace[717915673] 'applied index is now lower than readState.Index' (duration: 103.503µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:44:47.019597Z","caller":"traceutil/trace.go:171","msg":"trace[1945665794] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"206.947593ms","start":"2026-02-26T01:44:46.81261Z","end":"2026-02-26T01:44:47.019558Z","steps":["trace[1945665794] 'process raft request' (duration: 206.589184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:47.020197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.127412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:47.020272Z","caller":"traceutil/trace.go:171","msg":"trace[674217226] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2; }","duration":"206.239324ms","start":"2026-02-26T01:44:46.814018Z","end":"2026-02-26T01:44:47.020257Z","steps":["trace[674217226] 'agreement among raft nodes before linearized reading' (duration: 205.636179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:47.520408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935612279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:44:48.02086Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935612279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:44:48.102662Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083261335s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:44:48.103551Z","caller":"traceutil/trace.go:171","msg":"trace[709589262] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.208769449s","start":"2026-02-26T01:44:46.894706Z","end":"2026-02-26T01:44:48.103475Z","steps":["trace[709589262] 'process raft request' (duration: 1.208592115s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:48.103642Z","caller":"traceutil/trace.go:171","msg":"trace[1095435036] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.208511553s","start":"2026-02-26T01:44:46.895091Z","end":"2026-02-26T01:44:48.103603Z","steps":["trace[1095435036] 'process raft request' (duration: 1.208286717s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:48.103678Z","caller":"traceutil/trace.go:171","msg":"trace[2098807684] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.208228776s","start":"2026-02-26T01:44:46.895413Z","end":"2026-02-26T01:44:48.103642Z","steps":["trace[2098807684] 'process raft request' (duration: 1.208190865s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:48.104054Z","caller":"traceutil/trace.go:171","msg":"trace[664864005] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.208873802s","start":"2026-02-26T01:44:46.895165Z","end":"2026-02-26T01:44:48.104039Z","steps":["trace[664864005] 'process raft request' (duration: 1.208377199s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:48.104034Z","caller":"traceutil/trace.go:171","msg":"trace[1329694091] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.208824751s","start":"2026-02-26T01:44:46.894907Z","end":"2026-02-26T01:44:48.103732Z","steps":["trace[1329694091] 'process raft request' (duration: 1.208428351s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:48.10365Z","caller":"traceutil/trace.go:171","msg":"trace[956721109] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.288911184s","start":"2026-02-26T01:44:46.81469Z","end":"2026-02-26T01:44:48.103601Z","steps":["trace[956721109] 'process raft request' (duration: 1.28838148s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:48.104299Z","caller":"traceutil/trace.go:171","msg":"trace[727645626] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.208927654s","start":"2026-02-26T01:44:46.895115Z","end":"2026-02-26T01:44:48.104042Z","steps":["trace[727645626] 'process raft request' (duration: 1.208339879s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:48.104373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.895028Z","time spent":"1.208722059s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:48.104516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.814662Z","time spent":"1.289573551s","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:48.104686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.895092Z","time spent":"1.209403626s","remote":"127.0.0.1:33090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:48.104826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.894895Z","time spent":"1.209304553s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:48.105504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.895383Z","time spent":"1.208524064s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:48.105628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.895109Z","time spent":"1.208987806s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:48.10519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.894682Z","time spent":"1.209097608s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:49.269127Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.166063228s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:44:49.273087Z","caller":"traceutil/trace.go:171","msg":"trace[455506999] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"2.253476332s","start":"2026-02-26T01:44:47.019574Z","end":"2026-02-26T01:44:49.27305Z","steps":["trace[455506999] 'read index received' (duration: 1.083510722s)","trace[455506999] 'applied index is now lower than readState.Index' (duration: 1.16996459s)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:44:49.2732Z","caller":"traceutil/trace.go:171","msg":"trace[2142200638] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"2.242523816s","start":"2026-02-26T01:44:47.030634Z","end":"2026-02-26T01:44:49.273158Z","steps":["trace[2142200638] 'process raft request' (duration: 2.238873231s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.273281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381377918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.273318Z","caller":"traceutil/trace.go:171","msg":"trace[712203356] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:0; response_revision:10; }","duration":"2.381495471s","start":"2026-02-26T01:44:46.89181Z","end":"2026-02-26T01:44:49.273306Z","steps":["trace[712203356] 'agreement among raft nodes before linearized reading' (duration: 2.381356378s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.273352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.891782Z","time spent":"2.381561052s","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-02-26T01:44:49.273355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.458913154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.273464Z","caller":"traceutil/trace.go:171","msg":"trace[459238507] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"2.459076949s","start":"2026-02-26T01:44:46.814358Z","end":"2026-02-26T01:44:49.273435Z","steps":["trace[459238507] 'agreement among raft nodes before linearized reading' (duration: 2.458870213s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.273465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.325760381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T01:44:49.273519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.81434Z","time spent":"2.459168772s","remote":"127.0.0.1:33040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-26T01:44:49.273601Z","caller":"traceutil/trace.go:171","msg":"trace[338614171] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"2.325904145s","start":"2026-02-26T01:44:46.947647Z","end":"2026-02-26T01:44:49.273552Z","steps":["trace[338614171] 'agreement among raft nodes before linearized reading' (duration: 2.325645248s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.27366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.947621Z","time spent":"2.326029878s","remote":"127.0.0.1:60948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-26T01:44:49.273702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:47.03061Z","time spent":"2.242658079s","remote":"127.0.0.1:32800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:44:49.27368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.459566742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T01:44:49.273756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.459403188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.273789Z","caller":"traceutil/trace.go:171","msg":"trace[421121823] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:10; }","duration":"2.45947713s","start":"2026-02-26T01:44:46.814302Z","end":"2026-02-26T01:44:49.273779Z","steps":["trace[421121823] 'agreement among raft nodes before linearized reading' (duration: 2.459429038s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:49.273787Z","caller":"traceutil/trace.go:171","msg":"trace[257329528] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:10; }","duration":"2.459706415s","start":"2026-02-26T01:44:46.814055Z","end":"2026-02-26T01:44:49.273761Z","steps":["trace[257329528] 'agreement among raft nodes before linearized reading' (duration: 2.459545271s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.273815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.814273Z","time spent":"2.45953624s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-26T01:44:49.273836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.814052Z","time spent":"2.459773487s","remote":"127.0.0.1:32816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-02-26T01:44:49.27394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.379290983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T01:44:49.274007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.405588387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.274055Z","caller":"traceutil/trace.go:171","msg":"trace[1149398420] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"2.40568184s","start":"2026-02-26T01:44:46.86836Z","end":"2026-02-26T01:44:49.274042Z","steps":["trace[1149398420] 'agreement among raft nodes before linearized reading' (duration: 2.405608058s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:49.274049Z","caller":"traceutil/trace.go:171","msg":"trace[2131583749] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"2.379455228s","start":"2026-02-26T01:44:46.894565Z","end":"2026-02-26T01:44:49.27402Z","steps":["trace[2131583749] 'agreement among raft nodes before linearized reading' (duration: 2.379178101s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.274086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.867969Z","time spent":"2.406110332s","remote":"127.0.0.1:60948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-26T01:44:49.274115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.89454Z","time spent":"2.379564141s","remote":"127.0.0.1:60904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-26T01:44:49.274482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.380972518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.274533Z","caller":"traceutil/trace.go:171","msg":"trace[1360468703] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"2.381026329s","start":"2026-02-26T01:44:46.893495Z","end":"2026-02-26T01:44:49.274522Z","steps":["trace[1360468703] 'agreement among raft nodes before linearized reading' (duration: 2.379588792s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.274563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:46.893474Z","time spent":"2.38108223s","remote":"127.0.0.1:60948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-26T01:44:49.281271Z","caller":"traceutil/trace.go:171","msg":"trace[690847798] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.172335151s","start":"2026-02-26T01:44:48.10892Z","end":"2026-02-26T01:44:49.281255Z","steps":["trace[690847798] 'process raft request' (duration: 1.172151946s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.281421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:48.108895Z","time spent":"1.172442124s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:44:49.281443Z","caller":"traceutil/trace.go:171","msg":"trace[132476561] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.172270709s","start":"2026-02-26T01:44:48.109156Z","end":"2026-02-26T01:44:49.281427Z","steps":["trace[132476561] 'process raft request' (duration: 1.172031943s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:49.281476Z","caller":"traceutil/trace.go:171","msg":"trace[2098008584] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.171758196s","start":"2026-02-26T01:44:48.109701Z","end":"2026-02-26T01:44:49.281459Z","steps":["trace[2098008584] 'process raft request' (duration: 1.1715253s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.281541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:48.109147Z","time spent":"1.172335601s","remote":"127.0.0.1:33090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:49.281599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:48.109666Z","time spent":"1.171857319s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:44:49.281693Z","caller":"traceutil/trace.go:171","msg":"trace[1007062457] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.171053528s","start":"2026-02-26T01:44:48.110618Z","end":"2026-02-26T01:44:49.281671Z","steps":["trace[1007062457] 'process raft request' (duration: 1.170636087s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.281777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:48.110574Z","time spent":"1.171178221s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:44:49.28195Z","caller":"traceutil/trace.go:171","msg":"trace[1472847288] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.171232933s","start":"2026-02-26T01:44:48.110705Z","end":"2026-02-26T01:44:49.281938Z","steps":["trace[1472847288] 'process raft request' (duration: 1.170569475s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.281996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:48.110689Z","time spent":"1.171284814s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:49.282157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48949029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.282194Z","caller":"traceutil/trace.go:171","msg":"trace[1139806313] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:16; }","duration":"1.489569542s","start":"2026-02-26T01:44:47.792615Z","end":"2026-02-26T01:44:49.282184Z","steps":["trace[1139806313] 'agreement among raft nodes before linearized reading' (duration: 1.48948172s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.282222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:47.792588Z","time spent":"1.489627904s","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-26T01:44:49.282336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.259536839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.282362Z","caller":"traceutil/trace.go:171","msg":"trace[903202981] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:16; }","duration":"2.259612041s","start":"2026-02-26T01:44:47.022744Z","end":"2026-02-26T01:44:49.282356Z","steps":["trace[903202981] 'agreement among raft nodes before linearized reading' (duration: 2.25957329s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.282385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:47.022713Z","time spent":"2.259667102s","remote":"127.0.0.1:60860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-02-26T01:44:49.282638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173190764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-57269\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-26T01:44:49.282689Z","caller":"traceutil/trace.go:171","msg":"trace[822390754] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-57269; range_end:; response_count:1; response_revision:16; }","duration":"1.173285186s","start":"2026-02-26T01:44:48.109392Z","end":"2026-02-26T01:44:49.282677Z","steps":["trace[822390754] 'agreement among raft nodes before linearized reading' (duration: 1.173168673s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.28272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:48.109373Z","time spent":"1.173341757s","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-57269\" "} {"level":"info","ts":"2026-02-26T01:44:49.282841Z","caller":"traceutil/trace.go:171","msg":"trace[266442586] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.171746176s","start":"2026-02-26T01:44:48.111085Z","end":"2026-02-26T01:44:49.282831Z","steps":["trace[266442586] 'process raft request' (duration: 1.170768871s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.282894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:48.111072Z","time spent":"1.171797797s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:44:49.282941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244275932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.282973Z","caller":"traceutil/trace.go:171","msg":"trace[689354690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16; }","duration":"1.245115903s","start":"2026-02-26T01:44:48.037849Z","end":"2026-02-26T01:44:49.282965Z","steps":["trace[689354690] 'agreement among raft nodes before linearized reading' (duration: 1.245067412s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.283005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:48.037821Z","time spent":"1.245180205s","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:44:49.282716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489782308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:44:49.284496Z","caller":"traceutil/trace.go:171","msg":"trace[897209203] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:16; }","duration":"1.491613346s","start":"2026-02-26T01:44:47.792856Z","end":"2026-02-26T01:44:49.28447Z","steps":["trace[897209203] 'agreement among raft nodes before linearized reading' (duration: 1.489818529s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:49.284528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:47.792818Z","time spent":"1.491699538s","remote":"127.0.0.1:32978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-02-26T01:45:17.303253Z","caller":"traceutil/trace.go:171","msg":"trace[1884999031] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"115.138824ms","start":"2026-02-26T01:45:17.188077Z","end":"2026-02-26T01:45:17.303216Z","steps":["trace[1884999031] 'process raft request' (duration: 114.905728ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:17.699932Z","caller":"traceutil/trace.go:171","msg":"trace[1952576051] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"130.021171ms","start":"2026-02-26T01:45:17.569882Z","end":"2026-02-26T01:45:17.699903Z","steps":["trace[1952576051] 'read index received' (duration: 126.188501ms)","trace[1952576051] 'applied index is now lower than readState.Index' (duration: 3.83135ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:17.700192Z","caller":"traceutil/trace.go:171","msg":"trace[1000133949] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"392.078416ms","start":"2026-02-26T01:45:17.308101Z","end":"2026-02-26T01:45:17.70018Z","steps":["trace[1000133949] 'process raft request' (duration: 388.075122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:17.700348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:17.308083Z","time spent":"392.144678ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:17.700343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.666803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:45:17.700456Z","caller":"traceutil/trace.go:171","msg":"trace[1173090568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:473; }","duration":"126.864149ms","start":"2026-02-26T01:45:17.573565Z","end":"2026-02-26T01:45:17.700429Z","steps":["trace[1173090568] 'agreement among raft nodes before linearized reading' (duration: 126.664533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:17.700474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.581585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:45:17.700576Z","caller":"traceutil/trace.go:171","msg":"trace[2095050949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"130.74838ms","start":"2026-02-26T01:45:17.569803Z","end":"2026-02-26T01:45:17.700551Z","steps":["trace[2095050949] 'agreement among raft nodes before linearized reading' (duration: 130.453292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:20.628537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.095887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:45:20.628702Z","caller":"traceutil/trace.go:171","msg":"trace[704427940] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"519.682547ms","start":"2026-02-26T01:45:20.108979Z","end":"2026-02-26T01:45:20.628662Z","steps":["trace[704427940] 'read index received' (duration: 287.149319ms)","trace[704427940] 'applied index is now lower than readState.Index' (duration: 232.531608ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:20.628705Z","caller":"traceutil/trace.go:171","msg":"trace[179317232] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"658.371434ms","start":"2026-02-26T01:45:19.970299Z","end":"2026-02-26T01:45:20.62867Z","steps":["trace[179317232] 'process raft request' (duration: 425.802705ms)","trace[179317232] 'compare' (duration: 231.914242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:20.628878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.696253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:45:20.628883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:19.970276Z","time spent":"658.504778ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:45:20.628918Z","caller":"traceutil/trace.go:171","msg":"trace[852248509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"355.777524ms","start":"2026-02-26T01:45:20.273128Z","end":"2026-02-26T01:45:20.628905Z","steps":["trace[852248509] 'agreement among raft nodes before linearized reading' (duration: 355.707603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:20.628963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:20.273096Z","time spent":"355.856857ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:45:20.628935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.903913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T01:45:20.62906Z","caller":"traceutil/trace.go:171","msg":"trace[1862307987] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:481; }","duration":"520.095508ms","start":"2026-02-26T01:45:20.108939Z","end":"2026-02-26T01:45:20.629034Z","steps":["trace[1862307987] 'agreement among raft nodes before linearized reading' (duration: 519.822161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:20.629127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:20.108922Z","time spent":"520.19309ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-02-26T01:45:21.130863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935613211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:45:21.459203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.392283ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-26T01:45:21.45935Z","caller":"traceutil/trace.go:171","msg":"trace[595277302] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"828.719961ms","start":"2026-02-26T01:45:20.630605Z","end":"2026-02-26T01:45:21.459325Z","steps":["trace[595277302] 'read index received' (duration: 681.195815ms)","trace[595277302] 'applied index is now lower than readState.Index' (duration: 147.521836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:21.459383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:20.630525Z","time spent":"828.840124ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T01:45:21.459486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.849554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:21.459614Z","caller":"traceutil/trace.go:171","msg":"trace[351531136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"829.04692ms","start":"2026-02-26T01:45:20.630535Z","end":"2026-02-26T01:45:21.459582Z","steps":["trace[351531136] 'agreement among raft nodes before linearized reading' (duration: 828.849295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:21.459631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.653132ms","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-26T01:45:21.459666Z","caller":"traceutil/trace.go:171","msg":"trace[594685594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"139.758185ms","start":"2026-02-26T01:45:21.319898Z","end":"2026-02-26T01:45:21.459656Z","steps":["trace[594685594] 'agreement among raft nodes before linearized reading' (duration: 139.537149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:21.459668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:20.6305Z","time spent":"829.157563ms","remote":"127.0.0.1:60778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:45:25.392474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.761008ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T01:45:25.39263Z","caller":"traceutil/trace.go:171","msg":"trace[930616332] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"135.530344ms","start":"2026-02-26T01:45:25.257076Z","end":"2026-02-26T01:45:25.392606Z","steps":["trace[930616332] 'read index received' (duration: 83.292µs)","trace[930616332] 'applied index is now lower than readState.Index' (duration: 135.445532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:25.392834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.75697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2zlft\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-26T01:45:25.392873Z","caller":"traceutil/trace.go:171","msg":"trace[1604376053] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2zlft; range_end:; response_count:1; response_revision:494; }","duration":"135.844573ms","start":"2026-02-26T01:45:25.257015Z","end":"2026-02-26T01:45:25.39286Z","steps":["trace[1604376053] 'agreement among raft nodes before linearized reading' (duration: 135.649167ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:25.544509Z","caller":"traceutil/trace.go:171","msg":"trace[1537363721] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"135.620369ms","start":"2026-02-26T01:45:25.408822Z","end":"2026-02-26T01:45:25.544443Z","steps":["trace[1537363721] 'process raft request' (duration: 135.182337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:26.149133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.340771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:45:26.149335Z","caller":"traceutil/trace.go:171","msg":"trace[1839733300] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"416.401912ms","start":"2026-02-26T01:45:25.732851Z","end":"2026-02-26T01:45:26.149253Z","steps":["trace[1839733300] 'read index received' (duration: 55.361µs)","trace[1839733300] 'applied index is now lower than readState.Index' (duration: 416.344461ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:26.149342Z","caller":"traceutil/trace.go:171","msg":"trace[1817768549] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"599.758911ms","start":"2026-02-26T01:45:25.549537Z","end":"2026-02-26T01:45:26.149296Z","steps":["trace[1817768549] 'process raft request' (duration: 107.469195ms)","trace[1817768549] 'compare' (duration: 490.92154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:26.149565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:25.54947Z","time spent":"599.967856ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:26.149772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.916356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T01:45:26.149884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.086075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:45:26.14983Z","caller":"traceutil/trace.go:171","msg":"trace[840951247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"417.033558ms","start":"2026-02-26T01:45:25.732779Z","end":"2026-02-26T01:45:26.149813Z","steps":["trace[840951247] 'agreement among raft nodes before linearized reading' (duration: 416.754801ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:26.149993Z","caller":"traceutil/trace.go:171","msg":"trace[18015925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"403.2827ms","start":"2026-02-26T01:45:25.746685Z","end":"2026-02-26T01:45:26.149968Z","steps":["trace[18015925] 'agreement among raft nodes before linearized reading' (duration: 402.988243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:26.149999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:25.732751Z","time spent":"417.235574ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:45:26.150055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:25.746656Z","time spent":"403.386983ms","remote":"127.0.0.1:32882","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-26T01:45:26.150053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.188758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:45:26.150121Z","caller":"traceutil/trace.go:171","msg":"trace[1081669540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"403.297531ms","start":"2026-02-26T01:45:25.746805Z","end":"2026-02-26T01:45:26.150103Z","steps":["trace[1081669540] 'agreement among raft nodes before linearized reading' (duration: 403.035194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:26.150169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:25.746772Z","time spent":"403.388543ms","remote":"127.0.0.1:32882","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-26T01:45:26.338283Z","caller":"traceutil/trace.go:171","msg":"trace[508774452] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"182.915428ms","start":"2026-02-26T01:45:26.155339Z","end":"2026-02-26T01:45:26.338254Z","steps":["trace[508774452] 'process raft request' (duration: 146.152012ms)","trace[508774452] 'compare' (duration: 36.584341ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:26.338552Z","caller":"traceutil/trace.go:171","msg":"trace[657229595] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"182.456906ms","start":"2026-02-26T01:45:26.156083Z","end":"2026-02-26T01:45:26.33854Z","steps":["trace[657229595] 'process raft request' (duration: 182.123288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:28.66902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935613259,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:45:28.743331Z","caller":"traceutil/trace.go:171","msg":"trace[831942682] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"575.379285ms","start":"2026-02-26T01:45:28.167921Z","end":"2026-02-26T01:45:28.7433Z","steps":["trace[831942682] 'read index received' (duration: 575.124968ms)","trace[831942682] 'applied index is now lower than readState.Index' (duration: 251.477µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:28.743443Z","caller":"traceutil/trace.go:171","msg":"trace[199965746] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"583.6536ms","start":"2026-02-26T01:45:28.159754Z","end":"2026-02-26T01:45:28.743407Z","steps":["trace[199965746] 'process raft request' (duration: 583.303201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:28.743561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.9928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:45:28.743612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.645612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T01:45:28.743635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:28.159713Z","time spent":"583.801874ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:45:28.743702Z","caller":"traceutil/trace.go:171","msg":"trace[1479437242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:500; }","duration":"575.773055ms","start":"2026-02-26T01:45:28.167905Z","end":"2026-02-26T01:45:28.743678Z","steps":["trace[1479437242] 'agreement among raft nodes before linearized reading' (duration: 575.502308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:28.743691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.286049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:45:28.74363Z","caller":"traceutil/trace.go:171","msg":"trace[325352605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"458.112683ms","start":"2026-02-26T01:45:28.285496Z","end":"2026-02-26T01:45:28.743609Z","steps":["trace[325352605] 'agreement among raft nodes before linearized reading' (duration: 457.99945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:28.74375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:28.167897Z","time spent":"575.845877ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T01:45:28.743767Z","caller":"traceutil/trace.go:171","msg":"trace[1638951184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"400.394273ms","start":"2026-02-26T01:45:28.343351Z","end":"2026-02-26T01:45:28.743745Z","steps":["trace[1638951184] 'agreement among raft nodes before linearized reading' (duration: 400.171337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:28.743803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:28.343344Z","time spent":"400.452644ms","remote":"127.0.0.1:32882","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-26T01:45:28.743793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:28.285472Z","time spent":"458.294908ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:45:28.743914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.67504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:45:28.744036Z","caller":"traceutil/trace.go:171","msg":"trace[287472519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"400.822314ms","start":"2026-02-26T01:45:28.343188Z","end":"2026-02-26T01:45:28.74401Z","steps":["trace[287472519] 'agreement among raft nodes before linearized reading' (duration: 400.451464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:28.744083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:28.343123Z","time spent":"400.951786ms","remote":"127.0.0.1:32882","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-26T01:45:28.862715Z","caller":"traceutil/trace.go:171","msg":"trace[570925531] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"112.948988ms","start":"2026-02-26T01:45:28.74936Z","end":"2026-02-26T01:45:28.862309Z","steps":["trace[570925531] 'process raft request' (duration: 109.684283ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:28.864657Z","caller":"traceutil/trace.go:171","msg":"trace[237622568] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"115.071744ms","start":"2026-02-26T01:45:28.749557Z","end":"2026-02-26T01:45:28.864629Z","steps":["trace[237622568] 'process raft request' (duration: 115.014942ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:28.864691Z","caller":"traceutil/trace.go:171","msg":"trace[1540653532] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"115.274899ms","start":"2026-02-26T01:45:28.749381Z","end":"2026-02-26T01:45:28.864655Z","steps":["trace[1540653532] 'process raft request' (duration: 115.023153ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:31.086675Z","caller":"traceutil/trace.go:171","msg":"trace[103599926] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"687.502042ms","start":"2026-02-26T01:45:30.399152Z","end":"2026-02-26T01:45:31.086654Z","steps":["trace[103599926] 'process raft request' (duration: 687.344427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:31.086812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:30.399111Z","time spent":"687.621795ms","remote":"127.0.0.1:60854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:45:31.092346Z","caller":"traceutil/trace.go:171","msg":"trace[202948620] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"343.270479ms","start":"2026-02-26T01:45:30.749063Z","end":"2026-02-26T01:45:31.092333Z","steps":["trace[202948620] 'read index received' (duration: 337.457108ms)","trace[202948620] 'applied index is now lower than readState.Index' (duration: 5.812481ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:31.092557Z","caller":"traceutil/trace.go:171","msg":"trace[89867273] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"534.717878ms","start":"2026-02-26T01:45:30.557832Z","end":"2026-02-26T01:45:31.09255Z","steps":["trace[89867273] 'process raft request' (duration: 534.357519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:31.092631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:30.557773Z","time spent":"534.824851ms","remote":"127.0.0.1:60854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:45:31.092827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.258092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-bn9n6\" ","response":"range_response_count:1 size:4888"} {"level":"warn","ts":"2026-02-26T01:45:31.092887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.441912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:45:31.093483Z","caller":"traceutil/trace.go:171","msg":"trace[407842705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"223.997267ms","start":"2026-02-26T01:45:30.869422Z","end":"2026-02-26T01:45:31.093419Z","steps":["trace[407842705] 'agreement among raft nodes before linearized reading' (duration: 223.422512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:31.092935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.433474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T01:45:31.093641Z","caller":"traceutil/trace.go:171","msg":"trace[493044357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"226.162042ms","start":"2026-02-26T01:45:30.86747Z","end":"2026-02-26T01:45:31.093632Z","steps":["trace[493044357] 'agreement among raft nodes before linearized reading' (duration: 225.428463ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:31.09297Z","caller":"traceutil/trace.go:171","msg":"trace[1211607669] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"135.801423ms","start":"2026-02-26T01:45:30.957159Z","end":"2026-02-26T01:45:31.092961Z","steps":["trace[1211607669] 'process raft request' (duration: 135.107745ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:31.092993Z","caller":"traceutil/trace.go:171","msg":"trace[1864657850] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"335.420744ms","start":"2026-02-26T01:45:30.757566Z","end":"2026-02-26T01:45:31.092986Z","steps":["trace[1864657850] 'process raft request' (duration: 334.679865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:31.093866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:30.757527Z","time spent":"336.268846ms","remote":"127.0.0.1:60854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:45:31.092986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.909716ms","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-26T01:45:31.093944Z","caller":"traceutil/trace.go:171","msg":"trace[2104909446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"344.947603ms","start":"2026-02-26T01:45:30.748988Z","end":"2026-02-26T01:45:31.093936Z","steps":["trace[2104909446] 'agreement among raft nodes before linearized reading' (duration: 343.812694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:31.09398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:30.748958Z","time spent":"345.006384ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:45:31.093015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.34702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:45:31.094048Z","caller":"traceutil/trace.go:171","msg":"trace[885235898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"224.471829ms","start":"2026-02-26T01:45:30.869569Z","end":"2026-02-26T01:45:31.09404Z","steps":["trace[885235898] 'agreement among raft nodes before linearized reading' (duration: 223.237927ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:31.096284Z","caller":"traceutil/trace.go:171","msg":"trace[891305975] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-bn9n6; range_end:; response_count:1; response_revision:526; }","duration":"277.823086ms","start":"2026-02-26T01:45:30.815509Z","end":"2026-02-26T01:45:31.093332Z","steps":["trace[891305975] 'agreement among raft nodes before linearized reading' (duration: 277.18065ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:35.322428Z","caller":"traceutil/trace.go:171","msg":"trace[1215054370] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"181.685835ms","start":"2026-02-26T01:45:35.140692Z","end":"2026-02-26T01:45:35.322378Z","steps":["trace[1215054370] 'process raft request' (duration: 90.026952ms)","trace[1215054370] 'compare' (duration: 91.378456ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:35.354675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.344209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:35.354756Z","caller":"traceutil/trace.go:171","msg":"trace[900504262] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:591; }","duration":"108.456051ms","start":"2026-02-26T01:45:35.246278Z","end":"2026-02-26T01:45:35.354734Z","steps":["trace[900504262] 'agreement among raft nodes before linearized reading' (duration: 108.322768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:35.354962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.428781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:35.354999Z","caller":"traceutil/trace.go:171","msg":"trace[1814768417] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:591; }","duration":"108.483322ms","start":"2026-02-26T01:45:35.246507Z","end":"2026-02-26T01:45:35.35499Z","steps":["trace[1814768417] 'agreement among raft nodes before linearized reading' (duration: 108.4282ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:35.469912Z","caller":"traceutil/trace.go:171","msg":"trace[90796135] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"101.49231ms","start":"2026-02-26T01:45:35.368393Z","end":"2026-02-26T01:45:35.469885Z","steps":["trace[90796135] 'process raft request' (duration: 101.225593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:35.547957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.131836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:45:35.548051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.450259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:35.548254Z","caller":"traceutil/trace.go:171","msg":"trace[1092143522] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:596; }","duration":"111.714425ms","start":"2026-02-26T01:45:35.436521Z","end":"2026-02-26T01:45:35.548236Z","steps":["trace[1092143522] 'agreement among raft nodes before linearized reading' (duration: 111.423338ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:35.548134Z","caller":"traceutil/trace.go:171","msg":"trace[274568769] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:596; }","duration":"112.356732ms","start":"2026-02-26T01:45:35.43575Z","end":"2026-02-26T01:45:35.548107Z","steps":["trace[274568769] 'agreement among raft nodes before linearized reading' (duration: 112.117666ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:35.752613Z","caller":"traceutil/trace.go:171","msg":"trace[742803106] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"133.107323ms","start":"2026-02-26T01:45:35.619483Z","end":"2026-02-26T01:45:35.75259Z","steps":["trace[742803106] 'read index received' (duration: 39.407855ms)","trace[742803106] 'applied index is now lower than readState.Index' (duration: 93.697988ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:35.752708Z","caller":"traceutil/trace.go:171","msg":"trace[1085573560] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"167.25879ms","start":"2026-02-26T01:45:35.585404Z","end":"2026-02-26T01:45:35.752663Z","steps":["trace[1085573560] 'process raft request' (duration: 73.456761ms)","trace[1085573560] 'compare' (duration: 93.524222ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:35.752766Z","caller":"traceutil/trace.go:171","msg":"trace[1122247264] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"128.349569ms","start":"2026-02-26T01:45:35.624395Z","end":"2026-02-26T01:45:35.752745Z","steps":["trace[1122247264] 'process raft request' (duration: 128.148513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:35.753041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.951944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:35.753097Z","caller":"traceutil/trace.go:171","msg":"trace[66679531] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:601; }","duration":"169.031056ms","start":"2026-02-26T01:45:35.584051Z","end":"2026-02-26T01:45:35.753082Z","steps":["trace[66679531] 'agreement among raft nodes before linearized reading' (duration: 168.679507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:35.753109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.463221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:35.753157Z","caller":"traceutil/trace.go:171","msg":"trace[134106581] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:601; }","duration":"168.554354ms","start":"2026-02-26T01:45:35.584587Z","end":"2026-02-26T01:45:35.753142Z","steps":["trace[134106581] 'agreement among raft nodes before linearized reading' (duration: 168.072571ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:37.409363Z","caller":"traceutil/trace.go:171","msg":"trace[1904690513] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"109.878938ms","start":"2026-02-26T01:45:37.299465Z","end":"2026-02-26T01:45:37.409344Z","steps":["trace[1904690513] 'process raft request' (duration: 109.327864ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:38.501798Z","caller":"traceutil/trace.go:171","msg":"trace[283175439] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"147.792384ms","start":"2026-02-26T01:45:38.353957Z","end":"2026-02-26T01:45:38.50175Z","steps":["trace[283175439] 'process raft request' (duration: 147.528517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:45.621686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.83695ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T01:45:45.621812Z","caller":"traceutil/trace.go:171","msg":"trace[689230185] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"414.285788ms","start":"2026-02-26T01:45:45.207506Z","end":"2026-02-26T01:45:45.621792Z","steps":["trace[689230185] 'read index received' (duration: 43.741µs)","trace[689230185] 'applied index is now lower than readState.Index' (duration: 414.240497ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:45.622048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.527094ms","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-26T01:45:45.622089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.897914ms","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-26T01:45:45.622105Z","caller":"traceutil/trace.go:171","msg":"trace[871194613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"414.621896ms","start":"2026-02-26T01:45:45.207466Z","end":"2026-02-26T01:45:45.622088Z","steps":["trace[871194613] 'agreement among raft nodes before linearized reading' (duration: 414.430491ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:45.62213Z","caller":"traceutil/trace.go:171","msg":"trace[2031686019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"404.993716ms","start":"2026-02-26T01:45:45.217126Z","end":"2026-02-26T01:45:45.622119Z","steps":["trace[2031686019] 'agreement among raft nodes before linearized reading' (duration: 404.889093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:45.622122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.759444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T01:45:45.62215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:45.207449Z","time spent":"414.692308ms","remote":"127.0.0.1:32882","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-26T01:45:45.622155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:45.217091Z","time spent":"405.058678ms","remote":"127.0.0.1:32882","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-26T01:45:45.622187Z","caller":"traceutil/trace.go:171","msg":"trace[1221070243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"147.869997ms","start":"2026-02-26T01:45:45.474301Z","end":"2026-02-26T01:45:45.622171Z","steps":["trace[1221070243] 'agreement among raft nodes before linearized reading' (duration: 147.700073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:45.622629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.633512ms","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-26T01:45:45.622676Z","caller":"traceutil/trace.go:171","msg":"trace[216782930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"405.706284ms","start":"2026-02-26T01:45:45.216958Z","end":"2026-02-26T01:45:45.622664Z","steps":["trace[216782930] 'agreement among raft nodes before linearized reading' (duration: 405.003046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:45.622708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:45.216934Z","time spent":"405.766566ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:45:46.285156Z","caller":"traceutil/trace.go:171","msg":"trace[2080104034] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"657.639828ms","start":"2026-02-26T01:45:45.627484Z","end":"2026-02-26T01:45:46.285124Z","steps":["trace[2080104034] 'process raft request' (duration: 657.401062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:46.28539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:45.627459Z","time spent":"657.804793ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:47.669505Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.38460547s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:45:47.670023Z","caller":"traceutil/trace.go:171","msg":"trace[1766917193] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"2.042304289s","start":"2026-02-26T01:45:45.62769Z","end":"2026-02-26T01:45:47.669994Z","steps":["trace[1766917193] 'process raft request' (duration: 2.042040093s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:47.670169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:45.627681Z","time spent":"2.042390561s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:45:47.670633Z","caller":"traceutil/trace.go:171","msg":"trace[1987923096] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:702; }","duration":"1.512294681s","start":"2026-02-26T01:45:46.158306Z","end":"2026-02-26T01:45:47.6706Z","steps":["trace[1987923096] 'read index received' (duration: 126.509951ms)","trace[1987923096] 'applied index is now lower than readState.Index' (duration: 1.38578316s)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:47.670727Z","caller":"traceutil/trace.go:171","msg":"trace[613522916] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"2.042931286s","start":"2026-02-26T01:45:45.627779Z","end":"2026-02-26T01:45:47.67071Z","steps":["trace[613522916] 'process raft request' (duration: 2.042169926s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:47.670718Z","caller":"traceutil/trace.go:171","msg":"trace[1798890710] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"2.042154215s","start":"2026-02-26T01:45:45.628514Z","end":"2026-02-26T01:45:47.670669Z","steps":["trace[1798890710] 'process raft request' (duration: 2.041855047s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:47.6708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:45.627728Z","time spent":"2.043033248s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:45:47.670818Z","caller":"traceutil/trace.go:171","msg":"trace[2065972419] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.813313142s","start":"2026-02-26T01:45:45.857466Z","end":"2026-02-26T01:45:47.670779Z","steps":["trace[2065972419] 'process raft request' (duration: 1.813061445s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:47.670965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5126337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-l4bd8\" ","response":"range_response_count:1 size:5083"} {"level":"warn","ts":"2026-02-26T01:45:47.670963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:45.628501Z","time spent":"2.04231174s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:47.671003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:45.857434Z","time spent":"1.813477286s","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:45:47.671026Z","caller":"traceutil/trace.go:171","msg":"trace[1758186517] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-l4bd8; range_end:; response_count:1; response_revision:683; }","duration":"1.512758663s","start":"2026-02-26T01:45:46.15825Z","end":"2026-02-26T01:45:47.671008Z","steps":["trace[1758186517] 'agreement among raft nodes before linearized reading' (duration: 1.512525767s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:47.671076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398960022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:45:47.671096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:46.158225Z","time spent":"1.512853926s","remote":"127.0.0.1:32804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5106,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-l4bd8\" "} {"level":"info","ts":"2026-02-26T01:45:47.671114Z","caller":"traceutil/trace.go:171","msg":"trace[358597547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"1.399056875s","start":"2026-02-26T01:45:46.272046Z","end":"2026-02-26T01:45:47.671103Z","steps":["trace[358597547] 'agreement among raft nodes before linearized reading' (duration: 1.398995644s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:47.671146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:46.272008Z","time spent":"1.399131247s","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:45:47.671315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.000791ms","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-26T01:45:47.671348Z","caller":"traceutil/trace.go:171","msg":"trace[54435216] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:683; }","duration":"188.033231ms","start":"2026-02-26T01:45:47.483302Z","end":"2026-02-26T01:45:47.671336Z","steps":["trace[54435216] 'agreement among raft nodes before linearized reading' (duration: 187.96821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:47.672529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134239097s","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-26T01:45:47.672582Z","caller":"traceutil/trace.go:171","msg":"trace[234312968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.13436472s","start":"2026-02-26T01:45:46.538206Z","end":"2026-02-26T01:45:47.672571Z","steps":["trace[234312968] 'agreement among raft nodes before linearized reading' (duration: 1.133302983s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:47.672613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:46.538183Z","time spent":"1.134422162s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:45:48.1798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935613589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:45:48.389488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.242431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:45:48.389639Z","caller":"traceutil/trace.go:171","msg":"trace[1024341491] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"712.846014ms","start":"2026-02-26T01:45:47.67677Z","end":"2026-02-26T01:45:48.389616Z","steps":["trace[1024341491] 'process raft request' (duration: 520.343097ms)","trace[1024341491] 'compare' (duration: 191.632935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:48.389709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:47.676745Z","time spent":"712.929667ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:45:48.839463Z","caller":"traceutil/trace.go:171","msg":"trace[1124870201] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:707; }","duration":"1.160116561s","start":"2026-02-26T01:45:47.679319Z","end":"2026-02-26T01:45:48.839435Z","steps":["trace[1124870201] 'read index received' (duration: 517.811801ms)","trace[1124870201] 'applied index is now lower than readState.Index' (duration: 642.30336ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:48.839514Z","caller":"traceutil/trace.go:171","msg":"trace[2037773520] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.158631482s","start":"2026-02-26T01:45:47.680844Z","end":"2026-02-26T01:45:48.839476Z","steps":["trace[2037773520] 'process raft request' (duration: 1.158428146s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:48.839709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:47.680827Z","time spent":"1.158771516s","remote":"127.0.0.1:32804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4890,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:48.839699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160339316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:45:48.839754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.948719ms","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-26T01:45:48.83978Z","caller":"traceutil/trace.go:171","msg":"trace[1481615867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"1.1604736s","start":"2026-02-26T01:45:47.679287Z","end":"2026-02-26T01:45:48.839761Z","steps":["trace[1481615867] 'agreement among raft nodes before linearized reading' (duration: 1.160281575s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:48.8398Z","caller":"traceutil/trace.go:171","msg":"trace[1443082155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"547.045122ms","start":"2026-02-26T01:45:48.292745Z","end":"2026-02-26T01:45:48.83979Z","steps":["trace[1443082155] 'agreement among raft nodes before linearized reading' (duration: 546.863497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:48.839827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:47.679273Z","time spent":"1.160545902s","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:45:48.839837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:48.292725Z","time spent":"547.103103ms","remote":"127.0.0.1:32882","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-26T01:45:48.957311Z","caller":"traceutil/trace.go:171","msg":"trace[1697117169] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"112.127507ms","start":"2026-02-26T01:45:48.845145Z","end":"2026-02-26T01:45:48.957272Z","steps":["trace[1697117169] 'process raft request' (duration: 39.645772ms)","trace[1697117169] 'compare' (duration: 72.247439ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:48.957825Z","caller":"traceutil/trace.go:171","msg":"trace[135151669] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"112.133097ms","start":"2026-02-26T01:45:48.845677Z","end":"2026-02-26T01:45:48.95781Z","steps":["trace[135151669] 'process raft request' (duration: 111.524561ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:48.957869Z","caller":"traceutil/trace.go:171","msg":"trace[1484039594] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:709; }","duration":"106.688966ms","start":"2026-02-26T01:45:48.851155Z","end":"2026-02-26T01:45:48.957844Z","steps":["trace[1484039594] 'read index received' (duration: 33.668806ms)","trace[1484039594] 'applied index is now lower than readState.Index' (duration: 73.01894ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:48.957917Z","caller":"traceutil/trace.go:171","msg":"trace[1696521580] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"110.936986ms","start":"2026-02-26T01:45:48.846961Z","end":"2026-02-26T01:45:48.957898Z","steps":["trace[1696521580] 'process raft request' (duration: 110.712901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:48.958005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.847019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:48.95804Z","caller":"traceutil/trace.go:171","msg":"trace[534704713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"106.941922ms","start":"2026-02-26T01:45:48.85109Z","end":"2026-02-26T01:45:48.958031Z","steps":["trace[534704713] 'agreement among raft nodes before linearized reading' (duration: 106.85843ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:48.958098Z","caller":"traceutil/trace.go:171","msg":"trace[1397987454] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"110.7055ms","start":"2026-02-26T01:45:48.847381Z","end":"2026-02-26T01:45:48.958086Z","steps":["trace[1397987454] 'process raft request' (duration: 110.388422ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:49.144122Z","caller":"traceutil/trace.go:171","msg":"trace[1094693405] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"174.895019ms","start":"2026-02-26T01:45:48.969198Z","end":"2026-02-26T01:45:49.144093Z","steps":["trace[1094693405] 'process raft request' (duration: 131.314855ms)","trace[1094693405] 'compare' (duration: 43.084181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:50.612791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935613604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:45:50.697996Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017977191s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:45:50.698479Z","caller":"traceutil/trace.go:171","msg":"trace[75013723] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.018592728s","start":"2026-02-26T01:45:49.679863Z","end":"2026-02-26T01:45:50.698455Z","steps":["trace[75013723] 'process raft request' (duration: 1.018408853s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:50.698648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:49.679838Z","time spent":"1.01870257s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:51.299889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.702542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:45:51.300103Z","caller":"traceutil/trace.go:171","msg":"trace[389784098] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:714; }","duration":"1.188101528s","start":"2026-02-26T01:45:50.111979Z","end":"2026-02-26T01:45:51.30008Z","steps":["trace[389784098] 'read index received' (duration: 586.357804ms)","trace[389784098] 'applied index is now lower than readState.Index' (duration: 601.742354ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:51.300097Z","caller":"traceutil/trace.go:171","msg":"trace[1610212789] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.619460209s","start":"2026-02-26T01:45:49.680603Z","end":"2026-02-26T01:45:51.300063Z","steps":["trace[1610212789] 'process raft request' (duration: 1.491490219s)","trace[1610212789] 'compare' (duration: 127.58481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:51.300255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:49.680587Z","time spent":"1.619581002s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:51.300465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188493689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T01:45:51.300453Z","caller":"traceutil/trace.go:171","msg":"trace[685546011] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.619671735s","start":"2026-02-26T01:45:49.680654Z","end":"2026-02-26T01:45:51.300326Z","steps":["trace[685546011] 'process raft request' (duration: 1.619345026s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:51.300504Z","caller":"traceutil/trace.go:171","msg":"trace[478628311] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:693; }","duration":"1.188582401s","start":"2026-02-26T01:45:50.111909Z","end":"2026-02-26T01:45:51.300491Z","steps":["trace[478628311] 'agreement among raft nodes before linearized reading' (duration: 1.188424397s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:51.300546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:50.111879Z","time spent":"1.188660393s","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-02-26T01:45:51.300672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:49.680639Z","time spent":"1.61989909s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:51.304281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032170162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:51.304341Z","caller":"traceutil/trace.go:171","msg":"trace[1722704077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"1.032274935s","start":"2026-02-26T01:45:50.272052Z","end":"2026-02-26T01:45:51.304327Z","steps":["trace[1722704077] 'agreement among raft nodes before linearized reading' (duration: 1.032170362s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:51.304372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:50.272023Z","time spent":"1.032341897s","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:45:51.30445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.864943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:45:51.304514Z","caller":"traceutil/trace.go:171","msg":"trace[1473554975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"342.000757ms","start":"2026-02-26T01:45:50.962498Z","end":"2026-02-26T01:45:51.304499Z","steps":["trace[1473554975] 'agreement among raft nodes before linearized reading' (duration: 341.786861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:51.304565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:50.962463Z","time spent":"342.09412ms","remote":"127.0.0.1:32882","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-26T01:45:51.304605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.361187ms","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-26T01:45:51.304638Z","caller":"traceutil/trace.go:171","msg":"trace[1759480115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"909.393147ms","start":"2026-02-26T01:45:50.395235Z","end":"2026-02-26T01:45:51.304628Z","steps":["trace[1759480115] 'agreement among raft nodes before linearized reading' (duration: 909.295825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:51.304675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:50.395166Z","time spent":"909.50268ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:45:53.188099Z","caller":"traceutil/trace.go:171","msg":"trace[900801697] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"163.122643ms","start":"2026-02-26T01:45:53.024944Z","end":"2026-02-26T01:45:53.188067Z","steps":["trace[900801697] 'process raft request' (duration: 162.913318ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:15.020634Z","caller":"traceutil/trace.go:171","msg":"trace[1486643626] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"143.657416ms","start":"2026-02-26T01:46:14.876951Z","end":"2026-02-26T01:46:15.020609Z","steps":["trace[1486643626] 'process raft request' (duration: 143.499442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:16.34Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.577584ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T01:46:16.340131Z","caller":"traceutil/trace.go:171","msg":"trace[1366023272] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"806.129342ms","start":"2026-02-26T01:46:15.533975Z","end":"2026-02-26T01:46:16.340104Z","steps":["trace[1366023272] 'read index received' (duration: 72.361973ms)","trace[1366023272] 'applied index is now lower than readState.Index' (duration: 733.765809ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:46:16.340315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.024682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:46:16.340355Z","caller":"traceutil/trace.go:171","msg":"trace[1382206481] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:766; }","duration":"203.114524ms","start":"2026-02-26T01:46:16.137228Z","end":"2026-02-26T01:46:16.340342Z","steps":["trace[1382206481] 'agreement among raft nodes before linearized reading' (duration: 203.016561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:16.340387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.934507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:46:16.340448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.278481ms","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-26T01:46:16.340468Z","caller":"traceutil/trace.go:171","msg":"trace[1450368328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"753.02437ms","start":"2026-02-26T01:46:15.587424Z","end":"2026-02-26T01:46:16.340448Z","steps":["trace[1450368328] 'agreement among raft nodes before linearized reading' (duration: 752.816794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:16.340512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:15.587375Z","time spent":"753.129293ms","remote":"127.0.0.1:32882","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-26T01:46:16.340515Z","caller":"traceutil/trace.go:171","msg":"trace[788456995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"649.380324ms","start":"2026-02-26T01:46:15.691115Z","end":"2026-02-26T01:46:16.340495Z","steps":["trace[788456995] 'agreement among raft nodes before linearized reading' (duration: 649.171369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:16.34057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:15.6911Z","time spent":"649.456566ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:46:16.340699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.204863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:46:16.340819Z","caller":"traceutil/trace.go:171","msg":"trace[81758128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"673.372998ms","start":"2026-02-26T01:46:15.66742Z","end":"2026-02-26T01:46:16.340793Z","steps":["trace[81758128] 'agreement among raft nodes before linearized reading' (duration: 673.047499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:16.340876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:15.667402Z","time spent":"673.463601ms","remote":"127.0.0.1:32882","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-26T01:46:16.340748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.754228ms","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-26T01:46:16.341309Z","caller":"traceutil/trace.go:171","msg":"trace[251454620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"807.324252ms","start":"2026-02-26T01:46:15.533959Z","end":"2026-02-26T01:46:16.341283Z","steps":["trace[251454620] 'agreement among raft nodes before linearized reading' (duration: 806.280825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:16.341391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:15.533888Z","time spent":"807.486717ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:46:16.501321Z","caller":"traceutil/trace.go:171","msg":"trace[1392801866] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"155.773562ms","start":"2026-02-26T01:46:16.345524Z","end":"2026-02-26T01:46:16.501298Z","steps":["trace[1392801866] 'process raft request' (duration: 155.69112ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:16.501392Z","caller":"traceutil/trace.go:171","msg":"trace[1813929133] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"156.018238ms","start":"2026-02-26T01:46:16.345332Z","end":"2026-02-26T01:46:16.50135Z","steps":["trace[1813929133] 'process raft request' (duration: 155.744861ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:16.501602Z","caller":"traceutil/trace.go:171","msg":"trace[9756505] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"155.65304ms","start":"2026-02-26T01:46:16.345897Z","end":"2026-02-26T01:46:16.50155Z","steps":["trace[9756505] 'process raft request' (duration: 155.358772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:19.005255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935613839,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:46:19.506268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935613839,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:46:20.007218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935613839,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:46:20.444231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.879247668s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:46:20.445138Z","caller":"traceutil/trace.go:171","msg":"trace[2066226166] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"2.080546214s","start":"2026-02-26T01:46:18.364557Z","end":"2026-02-26T01:46:20.445103Z","steps":["trace[2066226166] 'process raft request' (duration: 2.080170445s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:20.445076Z","caller":"traceutil/trace.go:171","msg":"trace[1160101955] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"1.939813103s","start":"2026-02-26T01:46:18.505197Z","end":"2026-02-26T01:46:20.44501Z","steps":["trace[1160101955] 'read index received' (duration: 1.939220108s)","trace[1160101955] 'applied index is now lower than readState.Index' (duration: 591.035µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:46:20.44533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:18.364538Z","time spent":"2.080675718s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:46:20.445602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.507367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:46:20.445561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.793066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:46:20.445666Z","caller":"traceutil/trace.go:171","msg":"trace[379718247] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:772; }","duration":"152.667802ms","start":"2026-02-26T01:46:20.292983Z","end":"2026-02-26T01:46:20.445651Z","steps":["trace[379718247] 'agreement among raft nodes before linearized reading' (duration: 152.519258ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:20.445715Z","caller":"traceutil/trace.go:171","msg":"trace[404363009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"172.97579ms","start":"2026-02-26T01:46:20.272682Z","end":"2026-02-26T01:46:20.445658Z","steps":["trace[404363009] 'agreement among raft nodes before linearized reading' (duration: 172.782805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:20.445816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407212239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-26T01:46:20.445916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.432628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T01:46:20.445963Z","caller":"traceutil/trace.go:171","msg":"trace[1098394547] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:772; }","duration":"332.5241ms","start":"2026-02-26T01:46:20.113427Z","end":"2026-02-26T01:46:20.445951Z","steps":["trace[1098394547] 'agreement among raft nodes before linearized reading' (duration: 332.345796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:20.445943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93966879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:46:20.446036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939739501s","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-26T01:46:20.4461Z","caller":"traceutil/trace.go:171","msg":"trace[23447704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"1.939915746s","start":"2026-02-26T01:46:18.506158Z","end":"2026-02-26T01:46:20.446073Z","steps":["trace[23447704] 'agreement among raft nodes before linearized reading' (duration: 1.93930268s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:20.446164Z","caller":"traceutil/trace.go:171","msg":"trace[1225367037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"1.939937706s","start":"2026-02-26T01:46:18.506199Z","end":"2026-02-26T01:46:20.446137Z","steps":["trace[1225367037] 'agreement among raft nodes before linearized reading' (duration: 1.939561107s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:20.44618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:18.506124Z","time spent":"1.940038429s","remote":"127.0.0.1:32882","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-26T01:46:20.446224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:18.506174Z","time spent":"1.940041259s","remote":"127.0.0.1:32882","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-26T01:46:20.446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:20.113399Z","time spent":"332.593353ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"info","ts":"2026-02-26T01:46:20.445945Z","caller":"traceutil/trace.go:171","msg":"trace[475396472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"1.407426804s","start":"2026-02-26T01:46:19.038474Z","end":"2026-02-26T01:46:20.4459Z","steps":["trace[475396472] 'agreement among raft nodes before linearized reading' (duration: 1.407048374s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:20.446586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:19.038447Z","time spent":"1.408117192s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:46:20.446217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.941021344s","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-26T01:46:20.44737Z","caller":"traceutil/trace.go:171","msg":"trace[1748780354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"1.942193365s","start":"2026-02-26T01:46:18.505144Z","end":"2026-02-26T01:46:20.447338Z","steps":["trace[1748780354] 'agreement among raft nodes before linearized reading' (duration: 1.940036018s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:20.447516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:18.50512Z","time spent":"1.942343559s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:46:21.220272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.359174ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-26T01:46:21.220391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:20.448996Z","time spent":"771.390567ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T01:46:22.116028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.869302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:46:22.116308Z","caller":"traceutil/trace.go:171","msg":"trace[481188557] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"1.664107821s","start":"2026-02-26T01:46:20.452182Z","end":"2026-02-26T01:46:22.11629Z","steps":["trace[481188557] 'process raft request' (duration: 1.663954267s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:22.116391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:20.452175Z","time spent":"1.664180203s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:46:22.116408Z","caller":"traceutil/trace.go:171","msg":"trace[2141078150] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.665139347s","start":"2026-02-26T01:46:20.451235Z","end":"2026-02-26T01:46:22.116375Z","steps":["trace[2141078150] 'process raft request' (duration: 998.800643ms)","trace[2141078150] 'compare' (duration: 665.665637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:46:22.116568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:20.451218Z","time spent":"1.665265921s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:46:22.116584Z","caller":"traceutil/trace.go:171","msg":"trace[223164133] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"1.664216914s","start":"2026-02-26T01:46:20.452338Z","end":"2026-02-26T01:46:22.116555Z","steps":["trace[223164133] 'process raft request' (duration: 1.66408487s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:22.116603Z","caller":"traceutil/trace.go:171","msg":"trace[802158973] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.664340556s","start":"2026-02-26T01:46:20.45223Z","end":"2026-02-26T01:46:22.11657Z","steps":["trace[802158973] 'process raft request' (duration: 1.664018898s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:22.116715Z","caller":"traceutil/trace.go:171","msg":"trace[1820171718] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"895.170957ms","start":"2026-02-26T01:46:21.221531Z","end":"2026-02-26T01:46:22.116702Z","steps":["trace[1820171718] 'process raft request' (duration: 894.981912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:22.116751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:20.452333Z","time spent":"1.664332927s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:46:22.116771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:21.221499Z","time spent":"895.240609ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:46:22.116734Z","caller":"traceutil/trace.go:171","msg":"trace[1408076980] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:804; }","duration":"892.731943ms","start":"2026-02-26T01:46:21.223966Z","end":"2026-02-26T01:46:22.116698Z","steps":["trace[1408076980] 'read index received' (duration: 226.087812ms)","trace[1408076980] 'applied index is now lower than readState.Index' (duration: 666.643011ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:46:22.116907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.963059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T01:46:22.116868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:20.452225Z","time spent":"1.66450911s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:46:22.116961Z","caller":"traceutil/trace.go:171","msg":"trace[1025131163] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:777; }","duration":"893.051242ms","start":"2026-02-26T01:46:21.223893Z","end":"2026-02-26T01:46:22.116945Z","steps":["trace[1025131163] 'agreement among raft nodes before linearized reading' (duration: 892.894498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:22.117076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:21.22388Z","time spent":"893.171735ms","remote":"127.0.0.1:32986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:46:22.417248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.288709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:46:22.417342Z","caller":"traceutil/trace.go:171","msg":"trace[1367789867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"145.447703ms","start":"2026-02-26T01:46:22.271873Z","end":"2026-02-26T01:46:22.417321Z","steps":["trace[1367789867] 'range keys from in-memory index tree' (duration: 145.206478ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:22.669719Z","caller":"traceutil/trace.go:171","msg":"trace[91346861] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"213.295949ms","start":"2026-02-26T01:46:22.456389Z","end":"2026-02-26T01:46:22.669685Z","steps":["trace[91346861] 'process raft request' (duration: 213.013312ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:28.367898Z","caller":"traceutil/trace.go:171","msg":"trace[1495377354] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"170.821044ms","start":"2026-02-26T01:46:28.197052Z","end":"2026-02-26T01:46:28.367873Z","steps":["trace[1495377354] 'process raft request' (duration: 78.154853ms)","trace[1495377354] 'compare' (duration: 92.489977ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:46:28.368074Z","caller":"traceutil/trace.go:171","msg":"trace[1968261974] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"170.862185ms","start":"2026-02-26T01:46:28.197202Z","end":"2026-02-26T01:46:28.368064Z","steps":["trace[1968261974] 'process raft request' (duration: 170.627619ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:36.61383Z","caller":"traceutil/trace.go:171","msg":"trace[1072487327] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"121.704316ms","start":"2026-02-26T01:46:36.492082Z","end":"2026-02-26T01:46:36.613786Z","steps":["trace[1072487327] 'process raft request' (duration: 121.45031ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:36.770681Z","caller":"traceutil/trace.go:171","msg":"trace[385003341] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"152.105327ms","start":"2026-02-26T01:46:36.618546Z","end":"2026-02-26T01:46:36.770652Z","steps":["trace[385003341] 'process raft request' (duration: 151.981624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:39.289331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.421641ms","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-26T01:46:39.289405Z","caller":"traceutil/trace.go:171","msg":"trace[1562943705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:871; }","duration":"670.544634ms","start":"2026-02-26T01:46:38.618845Z","end":"2026-02-26T01:46:39.289389Z","steps":["trace[1562943705] 'range keys from in-memory index tree' (duration: 670.257647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:39.289444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:38.618827Z","time spent":"670.606217ms","remote":"127.0.0.1:32882","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-26T01:46:39.289555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.995632ms","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-26T01:46:39.289567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.107424ms","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-26T01:46:39.289661Z","caller":"traceutil/trace.go:171","msg":"trace[1391244276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:871; }","duration":"514.186297ms","start":"2026-02-26T01:46:38.775454Z","end":"2026-02-26T01:46:39.289641Z","steps":["trace[1391244276] 'range keys from in-memory index tree' (duration: 513.807027ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:39.289685Z","caller":"traceutil/trace.go:171","msg":"trace[335478027] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:871; }","duration":"351.275839ms","start":"2026-02-26T01:46:38.938387Z","end":"2026-02-26T01:46:39.289662Z","steps":["trace[335478027] 'count revisions from in-memory index tree' (duration: 350.995891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:39.289724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:38.775433Z","time spent":"514.27629ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:46:39.289734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:38.938364Z","time spent":"351.358511ms","remote":"127.0.0.1:32972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:46:39.289566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.958576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:46:39.289836Z","caller":"traceutil/trace.go:171","msg":"trace[1637031577] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:871; }","duration":"307.276244ms","start":"2026-02-26T01:46:38.982513Z","end":"2026-02-26T01:46:39.289789Z","steps":["trace[1637031577] 'count revisions from in-memory index tree' (duration: 306.797252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:39.289966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:38.982492Z","time spent":"307.438269ms","remote":"127.0.0.1:39748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:46:39.290304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.562954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:46:39.290376Z","caller":"traceutil/trace.go:171","msg":"trace[253437089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:871; }","duration":"385.254243ms","start":"2026-02-26T01:46:38.905106Z","end":"2026-02-26T01:46:39.29036Z","steps":["trace[253437089] 'range keys from in-memory index tree' (duration: 383.858756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:39.29042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:38.90508Z","time spent":"385.325785ms","remote":"127.0.0.1:32882","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-26T01:46:39.423351Z","caller":"traceutil/trace.go:171","msg":"trace[1640121808] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"129.122169ms","start":"2026-02-26T01:46:39.294209Z","end":"2026-02-26T01:46:39.423331Z","steps":["trace[1640121808] 'process raft request' (duration: 128.971885ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:39.434352Z","caller":"traceutil/trace.go:171","msg":"trace[661698395] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"139.387226ms","start":"2026-02-26T01:46:39.294946Z","end":"2026-02-26T01:46:39.434333Z","steps":["trace[661698395] 'process raft request' (duration: 139.195481ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:39.434666Z","caller":"traceutil/trace.go:171","msg":"trace[865240882] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"139.620202ms","start":"2026-02-26T01:46:39.29503Z","end":"2026-02-26T01:46:39.43465Z","steps":["trace[865240882] 'process raft request' (duration: 139.198021ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:40.526062Z","caller":"traceutil/trace.go:171","msg":"trace[1296641270] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"312.061988ms","start":"2026-02-26T01:46:40.213969Z","end":"2026-02-26T01:46:40.526031Z","steps":["trace[1296641270] 'read index received' (duration: 311.866303ms)","trace[1296641270] 'applied index is now lower than readState.Index' (duration: 194.415µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:46:40.526112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:40.116135Z","time spent":"409.965485ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T01:46:40.526354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.439632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-26T01:46:40.526334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.307184ms","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":"warn","ts":"2026-02-26T01:46:40.526361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.304329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:46:40.526389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.976975ms","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-26T01:46:40.5264Z","caller":"traceutil/trace.go:171","msg":"trace[1876378039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:874; }","duration":"281.573405ms","start":"2026-02-26T01:46:40.244815Z","end":"2026-02-26T01:46:40.526388Z","steps":["trace[1876378039] 'agreement among raft nodes before linearized reading' (duration: 281.370159ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:40.526471Z","caller":"traceutil/trace.go:171","msg":"trace[1585144307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"253.502784ms","start":"2026-02-26T01:46:40.27294Z","end":"2026-02-26T01:46:40.526443Z","steps":["trace[1585144307] 'agreement among raft nodes before linearized reading' (duration: 253.33145ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:40.526466Z","caller":"traceutil/trace.go:171","msg":"trace[479704885] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:874; }","duration":"312.475859ms","start":"2026-02-26T01:46:40.213952Z","end":"2026-02-26T01:46:40.526428Z","steps":["trace[479704885] 'agreement among raft nodes before linearized reading' (duration: 312.215712ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:40.526503Z","caller":"traceutil/trace.go:171","msg":"trace[2023295063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:874; }","duration":"207.133718ms","start":"2026-02-26T01:46:40.319316Z","end":"2026-02-26T01:46:40.52645Z","steps":["trace[2023295063] 'agreement among raft nodes before linearized reading' (duration: 206.892292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:40.526543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:40.213904Z","time spent":"312.623472ms","remote":"127.0.0.1:60948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:46:41.939259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935614096,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:46:42.439956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935614096,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:46:42.941128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740370935614096,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:46:43.427132Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.992238979s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:46:43.427677Z","caller":"traceutil/trace.go:171","msg":"trace[322776106] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"1.992850895s","start":"2026-02-26T01:46:41.434726Z","end":"2026-02-26T01:46:43.427577Z","steps":["trace[322776106] 'process raft request' (duration: 1.99267013s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:43.427889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:41.4347Z","time spent":"1.99309123s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:46:44.272806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000584524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-26T01:46:44.272868Z","caller":"traceutil/trace.go:171","msg":"trace[2105736186] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000701027s","start":"2026-02-26T01:46:42.272152Z","end":"2026-02-26T01:46:44.272853Z","steps":["trace[2105736186] 'agreement among raft nodes before linearized reading' (duration: 2.000618595s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:44.272902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:42.272126Z","time spent":"2.000769429s","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/26 01:46:44 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-26T01:46:44.446678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.672524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:46:44.446808Z","caller":"traceutil/trace.go:171","msg":"trace[1464507215] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"3.008375102s","start":"2026-02-26T01:46:41.438397Z","end":"2026-02-26T01:46:44.446772Z","steps":["trace[1464507215] 'read index received' (duration: 1.989294332s)","trace[1464507215] 'applied index is now lower than readState.Index' (duration: 1.01907978s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:46:44.447234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.864571376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:46:44.44723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.008085715s","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-26T01:46:44.44728Z","caller":"traceutil/trace.go:171","msg":"trace[55910447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"1.864682869s","start":"2026-02-26T01:46:42.582585Z","end":"2026-02-26T01:46:44.447268Z","steps":["trace[55910447] 'agreement among raft nodes before linearized reading' (duration: 1.864522865s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:44.4473Z","caller":"traceutil/trace.go:171","msg":"trace[1772332752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:879; }","duration":"3.008181188s","start":"2026-02-26T01:46:41.439101Z","end":"2026-02-26T01:46:44.447282Z","steps":["trace[1772332752] 'agreement among raft nodes before linearized reading' (duration: 3.007976122s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:44.447334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:41.439094Z","time spent":"3.008235039s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:46:44.44722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.63293895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:46:44.447545Z","caller":"traceutil/trace.go:171","msg":"trace[379273589] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:879; }","duration":"1.633355391s","start":"2026-02-26T01:46:42.814178Z","end":"2026-02-26T01:46:44.447533Z","steps":["trace[379273589] 'agreement among raft nodes before linearized reading' (duration: 1.632905089s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:44.447575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:42.814159Z","time spent":"1.633404172s","remote":"127.0.0.1:33008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:46:44.447326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:42.582556Z","time spent":"1.86476162s","remote":"127.0.0.1:32882","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-26T01:46:44.447892Z","caller":"traceutil/trace.go:171","msg":"trace[1109160902] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"1.56641191s","start":"2026-02-26T01:46:42.881453Z","end":"2026-02-26T01:46:44.447865Z","steps":["trace[1109160902] 'process raft request' (duration: 1.460445953s)","trace[1109160902] 'compare' (duration: 104.5368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:46:44.447958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.430258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:46:44.447965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.009559833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:46:44.448032Z","caller":"traceutil/trace.go:171","msg":"trace[167265846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:879; }","duration":"3.009646055s","start":"2026-02-26T01:46:41.438364Z","end":"2026-02-26T01:46:44.44801Z","steps":["trace[167265846] 'agreement among raft nodes before linearized reading' (duration: 3.009480541s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:44.448074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:41.438352Z","time spent":"3.009714027s","remote":"127.0.0.1:32882","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-26T01:46:44.447984Z","caller":"traceutil/trace.go:171","msg":"trace[799018154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"174.50427ms","start":"2026-02-26T01:46:44.273473Z","end":"2026-02-26T01:46:44.447978Z","steps":["trace[799018154] 'agreement among raft nodes before linearized reading' (duration: 174.467529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:44.448147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:42.88143Z","time spent":"1.566529792s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:46:44.447923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.530442468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:46:44.448238Z","caller":"traceutil/trace.go:171","msg":"trace[914300720] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:879; }","duration":"2.530776437s","start":"2026-02-26T01:46:41.917446Z","end":"2026-02-26T01:46:44.448223Z","steps":["trace[914300720] 'agreement among raft nodes before linearized reading' (duration: 2.530409527s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:44.448269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:41.917407Z","time spent":"2.530854149s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":86,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:46:44.448837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86586207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T01:46:44.448889Z","caller":"traceutil/trace.go:171","msg":"trace[650624237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"1.865963602s","start":"2026-02-26T01:46:42.58291Z","end":"2026-02-26T01:46:44.448873Z","steps":["trace[650624237] 'agreement among raft nodes before linearized reading' (duration: 1.865821888s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:44.448931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:42.582883Z","time spent":"1.866036404s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:46:46.787453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.65709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:46:46.787611Z","caller":"traceutil/trace.go:171","msg":"trace[683441301] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"290.478107ms","start":"2026-02-26T01:46:46.497111Z","end":"2026-02-26T01:46:46.78759Z","steps":["trace[683441301] 'process raft request' (duration: 74.56593ms)","trace[683441301] 'compare' (duration: 215.505466ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:46:54.94941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.498529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:46:54.949506Z","caller":"traceutil/trace.go:171","msg":"trace[1795589612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"125.650653ms","start":"2026-02-26T01:46:54.823832Z","end":"2026-02-26T01:46:54.949482Z","steps":["trace[1795589612] 'range keys from in-memory index tree' (duration: 125.305734ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:58.797033Z","caller":"traceutil/trace.go:171","msg":"trace[537341773] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"138.665174ms","start":"2026-02-26T01:46:58.658352Z","end":"2026-02-26T01:46:58.797017Z","steps":["trace[537341773] 'process raft request' (duration: 90.56894ms)","trace[537341773] 'compare' (duration: 47.406556ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:46:58.796552Z","caller":"traceutil/trace.go:171","msg":"trace[1153652693] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"135.952094ms","start":"2026-02-26T01:46:58.66057Z","end":"2026-02-26T01:46:58.796523Z","steps":["trace[1153652693] 'process raft request' (duration: 135.899752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:00.207995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221118379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:47:00.208073Z","caller":"traceutil/trace.go:171","msg":"trace[206051793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"1.221258723s","start":"2026-02-26T01:46:58.986795Z","end":"2026-02-26T01:47:00.208053Z","steps":["trace[206051793] 'range keys from in-memory index tree' (duration: 1.220974336s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:00.208114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:58.986774Z","time spent":"1.221331335s","remote":"127.0.0.1:32882","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-26T01:47:00.208001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.916716ms","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-26T01:47:00.208188Z","caller":"traceutil/trace.go:171","msg":"trace[163269419] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:922; }","duration":"733.125461ms","start":"2026-02-26T01:46:59.475037Z","end":"2026-02-26T01:47:00.208162Z","steps":["trace[163269419] 'range keys from in-memory index tree' (duration: 732.891425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:00.208465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.226701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:47:00.208471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.972182ms","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-26T01:47:00.208499Z","caller":"traceutil/trace.go:171","msg":"trace[1530978030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"936.317163ms","start":"2026-02-26T01:46:59.272171Z","end":"2026-02-26T01:47:00.208488Z","steps":["trace[1530978030] 'range keys from in-memory index tree' (duration: 936.128677ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:00.20851Z","caller":"traceutil/trace.go:171","msg":"trace[1958923551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"651.050774ms","start":"2026-02-26T01:46:59.557445Z","end":"2026-02-26T01:47:00.208496Z","steps":["trace[1958923551] 'range keys from in-memory index tree' (duration: 650.856649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:00.20854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:59.557419Z","time spent":"651.115106ms","remote":"127.0.0.1:32882","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-26T01:47:00.20854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:46:59.27214Z","time spent":"936.390425ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:47:00.328777Z","caller":"traceutil/trace.go:171","msg":"trace[486057925] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"114.159051ms","start":"2026-02-26T01:47:00.214592Z","end":"2026-02-26T01:47:00.328751Z","steps":["trace[486057925] 'process raft request' (duration: 114.099549ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:00.329275Z","caller":"traceutil/trace.go:171","msg":"trace[1070382897] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"115.195068ms","start":"2026-02-26T01:47:00.214066Z","end":"2026-02-26T01:47:00.329261Z","steps":["trace[1070382897] 'process raft request' (duration: 114.393317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:03.065842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.860221ms","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-26T01:47:03.065942Z","caller":"traceutil/trace.go:171","msg":"trace[1662406183] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:941; }","duration":"136.055316ms","start":"2026-02-26T01:47:02.929864Z","end":"2026-02-26T01:47:03.06592Z","steps":["trace[1662406183] 'count revisions from in-memory index tree' (duration: 135.640015ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:04.42802Z","caller":"traceutil/trace.go:171","msg":"trace[1454757675] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"115.916147ms","start":"2026-02-26T01:47:04.312068Z","end":"2026-02-26T01:47:04.427984Z","steps":["trace[1454757675] 'process raft request' (duration: 115.464075ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:04.587988Z","caller":"traceutil/trace.go:171","msg":"trace[1773559688] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"154.309206ms","start":"2026-02-26T01:47:04.433643Z","end":"2026-02-26T01:47:04.587952Z","steps":["trace[1773559688] 'process raft request' (duration: 64.751002ms)","trace[1773559688] 'compare' (duration: 89.41834ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:04.631012Z","caller":"traceutil/trace.go:171","msg":"trace[1298913689] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"196.281759ms","start":"2026-02-26T01:47:04.4347Z","end":"2026-02-26T01:47:04.630981Z","steps":["trace[1298913689] 'process raft request' (duration: 196.050003ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:04.631552Z","caller":"traceutil/trace.go:171","msg":"trace[2028271258] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"193.24674ms","start":"2026-02-26T01:47:04.438289Z","end":"2026-02-26T01:47:04.631536Z","steps":["trace[2028271258] 'process raft request' (duration: 192.612343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:05.451716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.204631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:47:05.451911Z","caller":"traceutil/trace.go:171","msg":"trace[1826905398] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"617.625304ms","start":"2026-02-26T01:47:04.834265Z","end":"2026-02-26T01:47:05.45189Z","steps":["trace[1826905398] 'process raft request' (duration: 617.563213ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:05.451925Z","caller":"traceutil/trace.go:171","msg":"trace[409917596] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"620.550921ms","start":"2026-02-26T01:47:04.831332Z","end":"2026-02-26T01:47:05.451883Z","steps":["trace[409917596] 'process raft request' (duration: 24.087803ms)","trace[409917596] 'compare' (duration: 596.059797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:05.451985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:04.834233Z","time spent":"617.715677ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:05.452089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:04.831318Z","time spent":"620.676055ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:06.628793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.325661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:47:06.6296Z","caller":"traceutil/trace.go:171","msg":"trace[846623938] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.667687157s","start":"2026-02-26T01:47:04.96179Z","end":"2026-02-26T01:47:06.629477Z","steps":["trace[846623938] 'process raft request' (duration: 759.548355ms)","trace[846623938] 'compare' (duration: 907.150816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:06.629823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:04.96176Z","time spent":"1.667937374s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:07.356589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.203976ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T01:47:07.357154Z","caller":"traceutil/trace.go:171","msg":"trace[458446401] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1000; }","duration":"1.085750531s","start":"2026-02-26T01:47:06.271366Z","end":"2026-02-26T01:47:07.357116Z","steps":["trace[458446401] 'read index received' (duration: 56.611µs)","trace[458446401] 'applied index is now lower than readState.Index' (duration: 1.08568756s)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:07.357205Z","caller":"traceutil/trace.go:171","msg":"trace[1799480395] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.778876602s","start":"2026-02-26T01:47:05.578307Z","end":"2026-02-26T01:47:07.357183Z","steps":["trace[1799480395] 'process raft request' (duration: 1.77843603s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:07.357434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:05.578279Z","time spent":"1.778979674s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:07.86444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22874694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:47:07.864535Z","caller":"traceutil/trace.go:171","msg":"trace[1444829615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"1.228886734s","start":"2026-02-26T01:47:06.635626Z","end":"2026-02-26T01:47:07.864513Z","steps":["trace[1444829615] 'agreement among raft nodes before linearized reading' (duration: 721.725692ms)","trace[1444829615] 'range keys from in-memory index tree' (duration: 506.915256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:07.864512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593130279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:47:07.86458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:06.63561Z","time spent":"1.228960765s","remote":"127.0.0.1:32882","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-26T01:47:07.864612Z","caller":"traceutil/trace.go:171","msg":"trace[405040782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"1.593276682s","start":"2026-02-26T01:47:06.271294Z","end":"2026-02-26T01:47:07.864571Z","steps":["trace[405040782] 'agreement among raft nodes before linearized reading' (duration: 1.086032059s)","trace[405040782] 'range keys from in-memory index tree' (duration: 507.117591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:07.864612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271513745s","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-26T01:47:07.86468Z","caller":"traceutil/trace.go:171","msg":"trace[1402134663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"1.271593587s","start":"2026-02-26T01:47:06.593066Z","end":"2026-02-26T01:47:07.86466Z","steps":["trace[1402134663] 'agreement among raft nodes before linearized reading' (duration: 764.272981ms)","trace[1402134663] 'range keys from in-memory index tree' (duration: 506.935726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:07.864675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:06.271266Z","time spent":"1.593392876s","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:47:07.864716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:06.592974Z","time spent":"1.27173666s","remote":"127.0.0.1:32882","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-26T01:47:07.864921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.897072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:47:07.864982Z","caller":"traceutil/trace.go:171","msg":"trace[22617662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"408.020926ms","start":"2026-02-26T01:47:07.456946Z","end":"2026-02-26T01:47:07.864967Z","steps":["trace[22617662] 'range keys from in-memory index tree' (duration: 407.41012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:07.865025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:07.456923Z","time spent":"408.087957ms","remote":"127.0.0.1:32882","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-26T01:47:07.865109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.05372ms","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-26T01:47:07.865114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12296595s","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-26T01:47:07.865133Z","caller":"traceutil/trace.go:171","msg":"trace[1988025936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"407.101341ms","start":"2026-02-26T01:47:07.458025Z","end":"2026-02-26T01:47:07.865126Z","steps":["trace[1988025936] 'range keys from in-memory index tree' (duration: 406.648869ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:07.865141Z","caller":"traceutil/trace.go:171","msg":"trace[1634057909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"1.123044162s","start":"2026-02-26T01:47:06.742088Z","end":"2026-02-26T01:47:07.865132Z","steps":["trace[1634057909] 'agreement among raft nodes before linearized reading' (duration: 615.245504ms)","trace[1634057909] 'range keys from in-memory index tree' (duration: 507.694716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:07.86516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:06.742062Z","time spent":"1.123094123s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:47:07.865213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:07.458014Z","time spent":"407.138623ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:47:08.060074Z","caller":"traceutil/trace.go:171","msg":"trace[755389655] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"189.490931ms","start":"2026-02-26T01:47:07.870555Z","end":"2026-02-26T01:47:08.060046Z","steps":["trace[755389655] 'process raft request' (duration: 178.42458ms)","trace[755389655] 'compare' (duration: 10.830515ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:08.060766Z","caller":"traceutil/trace.go:171","msg":"trace[1173599028] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1003; }","duration":"182.469416ms","start":"2026-02-26T01:47:07.878281Z","end":"2026-02-26T01:47:08.06075Z","steps":["trace[1173599028] 'read index received' (duration: 14.930022ms)","trace[1173599028] 'applied index is now lower than readState.Index' (duration: 167.538324ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:08.060757Z","caller":"traceutil/trace.go:171","msg":"trace[1658753272] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"190.099298ms","start":"2026-02-26T01:47:07.870621Z","end":"2026-02-26T01:47:08.060721Z","steps":["trace[1658753272] 'process raft request' (duration: 189.372549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:08.060925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.624351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:47:08.060963Z","caller":"traceutil/trace.go:171","msg":"trace[1745044575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"182.697763ms","start":"2026-02-26T01:47:07.878252Z","end":"2026-02-26T01:47:08.06095Z","steps":["trace[1745044575] 'agreement among raft nodes before linearized reading' (duration: 182.557419ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:08.061038Z","caller":"traceutil/trace.go:171","msg":"trace[1900128504] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"190.332124ms","start":"2026-02-26T01:47:07.870664Z","end":"2026-02-26T01:47:08.060996Z","steps":["trace[1900128504] 'process raft request' (duration: 189.926453ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:08.061136Z","caller":"traceutil/trace.go:171","msg":"trace[1833552810] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"190.381014ms","start":"2026-02-26T01:47:07.870744Z","end":"2026-02-26T01:47:08.061125Z","steps":["trace[1833552810] 'process raft request' (duration: 189.956843ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:10.42292Z","caller":"traceutil/trace.go:171","msg":"trace[1695735943] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"351.371807ms","start":"2026-02-26T01:47:10.07152Z","end":"2026-02-26T01:47:10.422892Z","steps":["trace[1695735943] 'read index received' (duration: 351.149761ms)","trace[1695735943] 'applied index is now lower than readState.Index' (duration: 220.896µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:10.423006Z","caller":"traceutil/trace.go:171","msg":"trace[973673749] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"500.089197ms","start":"2026-02-26T01:47:09.922859Z","end":"2026-02-26T01:47:10.422949Z","steps":["trace[973673749] 'process raft request' (duration: 499.804279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:10.423212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:09.922812Z","time spent":"500.282721ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:10.423317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.088483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-26T01:47:10.423349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.788588ms","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-26T01:47:10.423446Z","caller":"traceutil/trace.go:171","msg":"trace[1960523564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"351.915801ms","start":"2026-02-26T01:47:10.071509Z","end":"2026-02-26T01:47:10.423425Z","steps":["trace[1960523564] 'agreement among raft nodes before linearized reading' (duration: 351.547141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:10.423477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.727836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:47:10.423503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.070768Z","time spent":"352.725633ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:47:10.423509Z","caller":"traceutil/trace.go:171","msg":"trace[59553722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"351.790828ms","start":"2026-02-26T01:47:10.071709Z","end":"2026-02-26T01:47:10.4235Z","steps":["trace[59553722] 'agreement among raft nodes before linearized reading' (duration: 351.705556ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:10.423379Z","caller":"traceutil/trace.go:171","msg":"trace[1856502690] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:967; }","duration":"307.194175ms","start":"2026-02-26T01:47:10.116167Z","end":"2026-02-26T01:47:10.423361Z","steps":["trace[1856502690] 'agreement among raft nodes before linearized reading' (duration: 307.0003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:10.423553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.071686Z","time spent":"351.860029ms","remote":"127.0.0.1:32882","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-26T01:47:10.423615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.791248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T01:47:10.423664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.116155Z","time spent":"307.438012ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-02-26T01:47:10.423732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.119427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:47:10.423752Z","caller":"traceutil/trace.go:171","msg":"trace[1542202040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"351.979893ms","start":"2026-02-26T01:47:10.071738Z","end":"2026-02-26T01:47:10.423718Z","steps":["trace[1542202040] 'agreement among raft nodes before linearized reading' (duration: 351.533541ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:10.423768Z","caller":"traceutil/trace.go:171","msg":"trace[305777534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"352.175398ms","start":"2026-02-26T01:47:10.07158Z","end":"2026-02-26T01:47:10.423755Z","steps":["trace[305777534] 'agreement among raft nodes before linearized reading' (duration: 351.546011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:10.423804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.071561Z","time spent":"352.23712ms","remote":"127.0.0.1:32882","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-26T01:47:10.423816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.071723Z","time spent":"352.082305ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:47:11.20604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.166472ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-26T01:47:11.206171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.425092Z","time spent":"781.073692ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T01:47:11.453743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.765144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:47:11.454065Z","caller":"traceutil/trace.go:171","msg":"trace[347082623] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.024872651s","start":"2026-02-26T01:47:10.429162Z","end":"2026-02-26T01:47:11.454034Z","steps":["trace[347082623] 'process raft request' (duration: 900.649515ms)","trace[347082623] 'compare' (duration: 123.579659ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:11.454123Z","caller":"traceutil/trace.go:171","msg":"trace[1931057164] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"1.024795579s","start":"2026-02-26T01:47:10.429305Z","end":"2026-02-26T01:47:11.454101Z","steps":["trace[1931057164] 'process raft request' (duration: 1.024683986s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:11.454187Z","caller":"traceutil/trace.go:171","msg":"trace[1030865858] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1011; }","duration":"181.945513ms","start":"2026-02-26T01:47:11.272228Z","end":"2026-02-26T01:47:11.454174Z","steps":["trace[1030865858] 'read index received' (duration: 57.598854ms)","trace[1030865858] 'applied index is now lower than readState.Index' (duration: 124.345589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:11.454211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.42928Z","time spent":"1.024892462s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:11.45422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.429135Z","time spent":"1.024996955s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:11.454271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.050296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:47:11.454298Z","caller":"traceutil/trace.go:171","msg":"trace[198971827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"182.121458ms","start":"2026-02-26T01:47:11.272168Z","end":"2026-02-26T01:47:11.454289Z","steps":["trace[198971827] 'agreement among raft nodes before linearized reading' (duration: 182.056186ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:11.454379Z","caller":"traceutil/trace.go:171","msg":"trace[75486639] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.025099477s","start":"2026-02-26T01:47:10.429268Z","end":"2026-02-26T01:47:11.454367Z","steps":["trace[75486639] 'process raft request' (duration: 1.024678296s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:11.45443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.429227Z","time spent":"1.02517555s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:47:11.454532Z","caller":"traceutil/trace.go:171","msg":"trace[1558188711] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"246.996133ms","start":"2026-02-26T01:47:11.207522Z","end":"2026-02-26T01:47:11.454519Z","steps":["trace[1558188711] 'process raft request' (duration: 246.52705ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:11.454329Z","caller":"traceutil/trace.go:171","msg":"trace[2037228798] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.02482013s","start":"2026-02-26T01:47:10.429211Z","end":"2026-02-26T01:47:11.454031Z","steps":["trace[2037228798] 'process raft request' (duration: 1.024658416s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:11.455071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:10.429191Z","time spent":"1.025830696s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:47:12.108924Z","caller":"traceutil/trace.go:171","msg":"trace[371324402] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"204.978029ms","start":"2026-02-26T01:47:11.903897Z","end":"2026-02-26T01:47:12.108875Z","steps":["trace[371324402] 'read index received' (duration: 204.744892ms)","trace[371324402] 'applied index is now lower than readState.Index' (duration: 232.317µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:12.108946Z","caller":"traceutil/trace.go:171","msg":"trace[992760759] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"315.506243ms","start":"2026-02-26T01:47:11.793397Z","end":"2026-02-26T01:47:12.108903Z","steps":["trace[992760759] 'process raft request' (duration: 315.325459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:12.109128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:11.79337Z","time spent":"315.667438ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:47:12.109279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.171053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:47:12.109331Z","caller":"traceutil/trace.go:171","msg":"trace[799510392] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:973; }","duration":"205.456171ms","start":"2026-02-26T01:47:11.90386Z","end":"2026-02-26T01:47:12.109316Z","steps":["trace[799510392] 'agreement among raft nodes before linearized reading' (duration: 205.146523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:13.617909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156380527s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:47:13.618112Z","caller":"traceutil/trace.go:171","msg":"trace[41950296] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"1.189898669s","start":"2026-02-26T01:47:12.428197Z","end":"2026-02-26T01:47:13.618096Z","steps":["trace[41950296] 'read index received' (duration: 33.266774ms)","trace[41950296] 'applied index is now lower than readState.Index' (duration: 1.156630805s)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:13.618211Z","caller":"traceutil/trace.go:171","msg":"trace[809426868] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.367810025s","start":"2026-02-26T01:47:12.250391Z","end":"2026-02-26T01:47:13.618201Z","steps":["trace[809426868] 'process raft request' (duration: 1.367629241s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:13.618291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:12.250364Z","time spent":"1.367877637s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:47:13.61853Z","caller":"traceutil/trace.go:171","msg":"trace[79912275] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.370012794s","start":"2026-02-26T01:47:12.248479Z","end":"2026-02-26T01:47:13.618491Z","steps":["trace[79912275] 'process raft request' (duration: 212.971469ms)","trace[79912275] 'compare' (duration: 1.155958057s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:13.618616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190413293s","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-26T01:47:13.618658Z","caller":"traceutil/trace.go:171","msg":"trace[1773387443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:977; }","duration":"1.190514585s","start":"2026-02-26T01:47:12.428131Z","end":"2026-02-26T01:47:13.618645Z","steps":["trace[1773387443] 'agreement among raft nodes before linearized reading' (duration: 1.190380722s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:13.618696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:12.428104Z","time spent":"1.190584627s","remote":"127.0.0.1:32882","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-26T01:47:13.618695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:12.248451Z","time spent":"1.370153157s","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:47:13.618834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.099488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:47:13.618946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.141773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T01:47:13.619055Z","caller":"traceutil/trace.go:171","msg":"trace[1269836088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"159.297987ms","start":"2026-02-26T01:47:13.459733Z","end":"2026-02-26T01:47:13.619031Z","steps":["trace[1269836088] 'agreement among raft nodes before linearized reading' (duration: 158.890427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:13.619404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.533598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T01:47:13.619443Z","caller":"traceutil/trace.go:171","msg":"trace[1333908636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"158.585419ms","start":"2026-02-26T01:47:13.460848Z","end":"2026-02-26T01:47:13.619433Z","steps":["trace[1333908636] 'agreement among raft nodes before linearized reading' (duration: 158.487536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:13.620103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.54813ms","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-26T01:47:13.620108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.68477ms","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-26T01:47:13.620151Z","caller":"traceutil/trace.go:171","msg":"trace[1706827254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"160.666233ms","start":"2026-02-26T01:47:13.459474Z","end":"2026-02-26T01:47:13.62014Z","steps":["trace[1706827254] 'agreement among raft nodes before linearized reading' (duration: 160.53062ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:13.620158Z","caller":"traceutil/trace.go:171","msg":"trace[908737481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:977; }","duration":"161.770143ms","start":"2026-02-26T01:47:13.458376Z","end":"2026-02-26T01:47:13.620147Z","steps":["trace[908737481] 'agreement among raft nodes before linearized reading' (duration: 161.650109ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:13.618953Z","caller":"traceutil/trace.go:171","msg":"trace[387326008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"346.465188ms","start":"2026-02-26T01:47:13.272464Z","end":"2026-02-26T01:47:13.618929Z","steps":["trace[387326008] 'agreement among raft nodes before linearized reading' (duration: 346.066967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:13.621072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:13.272443Z","time spent":"348.520051ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:47:14.211043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.791267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:47:14.211175Z","caller":"traceutil/trace.go:171","msg":"trace[1050685841] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"587.264997ms","start":"2026-02-26T01:47:13.623887Z","end":"2026-02-26T01:47:14.211151Z","steps":["trace[1050685841] 'process raft request' (duration: 469.238685ms)","trace[1050685841] 'compare' (duration: 117.619861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:14.211246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:13.623861Z","time spent":"587.3523ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:47:14.822404Z","caller":"traceutil/trace.go:171","msg":"trace[376730829] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.198033852s","start":"2026-02-26T01:47:13.624314Z","end":"2026-02-26T01:47:14.822348Z","steps":["trace[376730829] 'process raft request' (duration: 1.197765535s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:14.822511Z","caller":"traceutil/trace.go:171","msg":"trace[1669512908] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1022; }","duration":"285.640589ms","start":"2026-02-26T01:47:14.536846Z","end":"2026-02-26T01:47:14.822486Z","steps":["trace[1669512908] 'read index received' (duration: 285.211648ms)","trace[1669512908] 'applied index is now lower than readState.Index' (duration: 428.171µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:14.82258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:13.624298Z","time spent":"1.198200076s","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:47:14.822691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.823764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:47:14.822728Z","caller":"traceutil/trace.go:171","msg":"trace[2141121996] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:983; }","duration":"285.918326ms","start":"2026-02-26T01:47:14.536796Z","end":"2026-02-26T01:47:14.822714Z","steps":["trace[2141121996] 'agreement among raft nodes before linearized reading' (duration: 285.782152ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:14.822785Z","caller":"traceutil/trace.go:171","msg":"trace[338521610] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.197862677s","start":"2026-02-26T01:47:13.624906Z","end":"2026-02-26T01:47:14.822769Z","steps":["trace[338521610] 'process raft request' (duration: 1.197466057s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:14.822925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:13.624897Z","time spent":"1.19793706s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:47:14.822964Z","caller":"traceutil/trace.go:171","msg":"trace[1649411358] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.195762012s","start":"2026-02-26T01:47:13.627191Z","end":"2026-02-26T01:47:14.822953Z","steps":["trace[1649411358] 'process raft request' (duration: 1.195258479s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:14.822975Z","caller":"traceutil/trace.go:171","msg":"trace[587596930] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.197100578s","start":"2026-02-26T01:47:13.625827Z","end":"2026-02-26T01:47:14.822928Z","steps":["trace[587596930] 'process raft request' (duration: 1.196585744s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:14.823025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:13.627184Z","time spent":"1.195803223s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:14.823205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:13.625813Z","time spent":"1.197271973s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:47:14.823804Z","caller":"traceutil/trace.go:171","msg":"trace[1081425348] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.198252638s","start":"2026-02-26T01:47:13.62473Z","end":"2026-02-26T01:47:14.822982Z","steps":["trace[1081425348] 'process raft request' (duration: 1.197530169s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:14.824104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:13.624702Z","time spent":"1.199268605s","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:47:16.544095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27174991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:47:16.544201Z","caller":"traceutil/trace.go:171","msg":"trace[894675023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"1.271907345s","start":"2026-02-26T01:47:15.272268Z","end":"2026-02-26T01:47:16.544175Z","steps":["trace[894675023] 'range keys from in-memory index tree' (duration: 1.271663017s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:16.544256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:15.272245Z","time spent":"1.271997666s","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:47:16.552041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.616211ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T01:47:16.552151Z","caller":"traceutil/trace.go:171","msg":"trace[2113214180] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"335.711795ms","start":"2026-02-26T01:47:16.21642Z","end":"2026-02-26T01:47:16.552132Z","steps":["trace[2113214180] 'read index received' (duration: 31.930499ms)","trace[2113214180] 'applied index is now lower than readState.Index' (duration: 303.779846ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:47:16.552297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.64633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:47:16.552335Z","caller":"traceutil/trace.go:171","msg":"trace[710159956] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:983; }","duration":"115.741053ms","start":"2026-02-26T01:47:16.436585Z","end":"2026-02-26T01:47:16.552326Z","steps":["trace[710159956] 'agreement among raft nodes before linearized reading' (duration: 115.660231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:16.552424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.995933ms","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-26T01:47:16.552586Z","caller":"traceutil/trace.go:171","msg":"trace[1252991928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"336.202958ms","start":"2026-02-26T01:47:16.216328Z","end":"2026-02-26T01:47:16.552531Z","steps":["trace[1252991928] 'agreement among raft nodes before linearized reading' (duration: 335.88193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:16.552666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:47:16.216286Z","time spent":"336.367112ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:47:21.848496Z","caller":"traceutil/trace.go:171","msg":"trace[567813232] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"174.22005ms","start":"2026-02-26T01:47:21.674213Z","end":"2026-02-26T01:47:21.848433Z","steps":["trace[567813232] 'process raft request' (duration: 173.961513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:47:25.063121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.74542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:47:25.063461Z","caller":"traceutil/trace.go:171","msg":"trace[1751661991] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"165.319166ms","start":"2026-02-26T01:47:24.898091Z","end":"2026-02-26T01:47:25.06341Z","steps":["trace[1751661991] 'process raft request' (duration: 23.357184ms)","trace[1751661991] 'compare' (duration: 139.99838ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:47:25.063678Z","caller":"traceutil/trace.go:171","msg":"trace[102912386] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"164.695379ms","start":"2026-02-26T01:47:24.898936Z","end":"2026-02-26T01:47:25.063631Z","steps":["trace[102912386] 'process raft request' (duration: 164.601616ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:25.064077Z","caller":"traceutil/trace.go:171","msg":"trace[1203661425] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"165.524071ms","start":"2026-02-26T01:47:24.898518Z","end":"2026-02-26T01:47:25.064042Z","steps":["trace[1203661425] 'process raft request' (duration: 164.763821ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:25.064419Z","caller":"traceutil/trace.go:171","msg":"trace[1484630893] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"165.525841ms","start":"2026-02-26T01:47:24.898853Z","end":"2026-02-26T01:47:25.064379Z","steps":["trace[1484630893] 'process raft request' (duration: 164.605667ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:47:57.936565Z","caller":"traceutil/trace.go:171","msg":"trace[2071169158] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"122.785266ms","start":"2026-02-26T01:47:57.813749Z","end":"2026-02-26T01:47:57.936534Z","steps":["trace[2071169158] 'process raft request' (duration: 122.617712ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:11.605674Z","caller":"traceutil/trace.go:171","msg":"trace[521857961] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"111.258612ms","start":"2026-02-26T01:48:11.494405Z","end":"2026-02-26T01:48:11.605663Z","steps":["trace[521857961] 'process raft request' (duration: 111.18735ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:11.605675Z","caller":"traceutil/trace.go:171","msg":"trace[972465061] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"111.827877ms","start":"2026-02-26T01:48:11.493819Z","end":"2026-02-26T01:48:11.605646Z","steps":["trace[972465061] 'process raft request' (duration: 17.820468ms)","trace[972465061] 'compare' (duration: 93.859025ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:48:11.605854Z","caller":"traceutil/trace.go:171","msg":"trace[193701424] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"108.836658ms","start":"2026-02-26T01:48:11.497007Z","end":"2026-02-26T01:48:11.605844Z","steps":["trace[193701424] 'process raft request' (duration: 108.626133ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:13.593043Z","caller":"traceutil/trace.go:171","msg":"trace[1078204626] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"113.376178ms","start":"2026-02-26T01:48:13.479562Z","end":"2026-02-26T01:48:13.592938Z","steps":["trace[1078204626] 'process raft request' (duration: 112.859335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:48:23.90549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.002783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:48:23.905561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.400316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T01:48:23.905657Z","caller":"traceutil/trace.go:171","msg":"trace[301374014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"109.172818ms","start":"2026-02-26T01:48:23.796427Z","end":"2026-02-26T01:48:23.9056Z","steps":["trace[301374014] 'range keys from in-memory index tree' (duration: 108.783107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:48:23.905485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.876565ms","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-26T01:48:23.905853Z","caller":"traceutil/trace.go:171","msg":"trace[1843533010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"105.279386ms","start":"2026-02-26T01:48:23.800549Z","end":"2026-02-26T01:48:23.905828Z","steps":["trace[1843533010] 'range keys from in-memory index tree' (duration: 104.71626ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:23.905707Z","caller":"traceutil/trace.go:171","msg":"trace[74254557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"103.594171ms","start":"2026-02-26T01:48:23.802088Z","end":"2026-02-26T01:48:23.905682Z","steps":["trace[74254557] 'range keys from in-memory index tree' (duration: 103.211421ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:24.231203Z","caller":"traceutil/trace.go:171","msg":"trace[1628926994] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1343; }","duration":"310.129626ms","start":"2026-02-26T01:48:23.921036Z","end":"2026-02-26T01:48:24.231165Z","steps":["trace[1628926994] 'read index received' (duration: 65.321846ms)","trace[1628926994] 'applied index is now lower than readState.Index' (duration: 244.80658ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:48:24.231269Z","caller":"traceutil/trace.go:171","msg":"trace[1611427826] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"319.901773ms","start":"2026-02-26T01:48:23.911299Z","end":"2026-02-26T01:48:24.2312Z","steps":["trace[1611427826] 'process raft request' (duration: 269.708144ms)","trace[1611427826] 'compare' (duration: 49.928862ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:48:24.23127Z","caller":"traceutil/trace.go:171","msg":"trace[1205565457] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"318.511076ms","start":"2026-02-26T01:48:23.912724Z","end":"2026-02-26T01:48:24.231235Z","steps":["trace[1205565457] 'process raft request' (duration: 318.376492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:48:24.231816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:48:23.912717Z","time spent":"319.008459ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:48:24.231521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:48:23.91129Z","time spent":"320.081767ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:48:24.23154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.472935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-26T01:48:24.232269Z","caller":"traceutil/trace.go:171","msg":"trace[907066865] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1288; }","duration":"311.263555ms","start":"2026-02-26T01:48:23.920985Z","end":"2026-02-26T01:48:24.232248Z","steps":["trace[907066865] 'agreement among raft nodes before linearized reading' (duration: 310.376112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:48:24.232347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:48:23.920962Z","time spent":"311.369998ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-26T01:48:34.4635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.661523ms","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-26T01:48:34.463572Z","caller":"traceutil/trace.go:171","msg":"trace[612366150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1329; }","duration":"103.745395ms","start":"2026-02-26T01:48:34.359809Z","end":"2026-02-26T01:48:34.463555Z","steps":["trace[612366150] 'range keys from in-memory index tree' (duration: 103.512159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:48:48.731403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.166447ms","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-26T01:48:48.731531Z","caller":"traceutil/trace.go:171","msg":"trace[496680708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1384; }","duration":"152.364952ms","start":"2026-02-26T01:48:48.579142Z","end":"2026-02-26T01:48:48.731507Z","steps":["trace[496680708] 'range keys from in-memory index tree' (duration: 151.848008ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:48.850599Z","caller":"traceutil/trace.go:171","msg":"trace[1629733157] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"114.804214ms","start":"2026-02-26T01:48:48.735764Z","end":"2026-02-26T01:48:48.850569Z","steps":["trace[1629733157] 'process raft request' (duration: 114.622469ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:48.878972Z","caller":"traceutil/trace.go:171","msg":"trace[1314727281] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"142.476471ms","start":"2026-02-26T01:48:48.736474Z","end":"2026-02-26T01:48:48.878951Z","steps":["trace[1314727281] 'process raft request' (duration: 142.312766ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:49.027382Z","caller":"traceutil/trace.go:171","msg":"trace[2041520093] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"108.941821ms","start":"2026-02-26T01:48:48.918395Z","end":"2026-02-26T01:48:49.027337Z","steps":["trace[2041520093] 'process raft request' (duration: 33.323365ms)","trace[2041520093] 'compare' (duration: 75.466772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:48:49.679624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.074046ms","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-26T01:48:49.679812Z","caller":"traceutil/trace.go:171","msg":"trace[734101572] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1389; }","duration":"205.296532ms","start":"2026-02-26T01:48:49.474493Z","end":"2026-02-26T01:48:49.679789Z","steps":["trace[734101572] 'range keys from in-memory index tree' (duration: 205.049906ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:49.680287Z","caller":"traceutil/trace.go:171","msg":"trace[1656184672] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"339.24936ms","start":"2026-02-26T01:48:49.34101Z","end":"2026-02-26T01:48:49.680259Z","steps":["trace[1656184672] 'process raft request' (duration: 256.778484ms)","trace[1656184672] 'compare' (duration: 81.988424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:48:49.680465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:48:49.340979Z","time spent":"339.391704ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:49:09.856973Z","caller":"traceutil/trace.go:171","msg":"trace[1939924665] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"110.637529ms","start":"2026-02-26T01:49:09.746301Z","end":"2026-02-26T01:49:09.856938Z","steps":["trace[1939924665] 'process raft request' (duration: 110.434694ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:14.04248Z","caller":"traceutil/trace.go:171","msg":"trace[500687261] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"115.810957ms","start":"2026-02-26T01:49:13.92663Z","end":"2026-02-26T01:49:14.042441Z","steps":["trace[500687261] 'process raft request' (duration: 115.503849ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:28.410281Z","caller":"traceutil/trace.go:171","msg":"trace[1068602294] linearizableReadLoop","detail":"{readStateIndex:1616; appliedIndex:1615; }","duration":"132.718304ms","start":"2026-02-26T01:49:28.277534Z","end":"2026-02-26T01:49:28.410252Z","steps":["trace[1068602294] 'read index received' (duration: 132.529829ms)","trace[1068602294] 'applied index is now lower than readState.Index' (duration: 184.495µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:49:28.410386Z","caller":"traceutil/trace.go:171","msg":"trace[327799089] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"133.808043ms","start":"2026-02-26T01:49:28.276537Z","end":"2026-02-26T01:49:28.410345Z","steps":["trace[327799089] 'process raft request' (duration: 133.538305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:49:28.41049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.93999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:49:28.41058Z","caller":"traceutil/trace.go:171","msg":"trace[608437753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1545; }","duration":"133.080113ms","start":"2026-02-26T01:49:28.277479Z","end":"2026-02-26T01:49:28.410559Z","steps":["trace[608437753] 'agreement among raft nodes before linearized reading' (duration: 132.93616ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:28.533441Z","caller":"traceutil/trace.go:171","msg":"trace[1952285862] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"118.60239ms","start":"2026-02-26T01:49:28.414805Z","end":"2026-02-26T01:49:28.533407Z","steps":["trace[1952285862] 'process raft request' (duration: 85.063832ms)","trace[1952285862] 'compare' (duration: 33.405585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:49:33.5655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.021553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-02-26T01:49:33.565625Z","caller":"traceutil/trace.go:171","msg":"trace[878525964] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1567; }","duration":"147.207748ms","start":"2026-02-26T01:49:33.418396Z","end":"2026-02-26T01:49:33.565604Z","steps":["trace[878525964] 'range keys from in-memory index tree' (duration: 146.837157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:35.760986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.296616ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T01:50:35.761137Z","caller":"traceutil/trace.go:171","msg":"trace[1180245323] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"141.424276ms","start":"2026-02-26T01:50:35.619688Z","end":"2026-02-26T01:50:35.761112Z","steps":["trace[1180245323] 'read index received' (duration: 3.888453ms)","trace[1180245323] 'applied index is now lower than readState.Index' (duration: 137.533293ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:35.761416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.698752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-26T01:50:35.761528Z","caller":"traceutil/trace.go:171","msg":"trace[138762861] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1850; }","duration":"141.880887ms","start":"2026-02-26T01:50:35.619623Z","end":"2026-02-26T01:50:35.761503Z","steps":["trace[138762861] 'agreement among raft nodes before linearized reading' (duration: 141.545898ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:37.060433Z","caller":"traceutil/trace.go:171","msg":"trace[524973835] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"129.109659ms","start":"2026-02-26T01:50:36.931296Z","end":"2026-02-26T01:50:37.060405Z","steps":["trace[524973835] 'process raft request' (duration: 128.823952ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:37.424817Z","caller":"traceutil/trace.go:171","msg":"trace[1720861088] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"107.896428ms","start":"2026-02-26T01:50:37.316897Z","end":"2026-02-26T01:50:37.424794Z","steps":["trace[1720861088] 'read index received' (duration: 107.631911ms)","trace[1720861088] 'applied index is now lower than readState.Index' (duration: 263.167µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:37.424865Z","caller":"traceutil/trace.go:171","msg":"trace[894911257] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"184.056855ms","start":"2026-02-26T01:50:37.240782Z","end":"2026-02-26T01:50:37.424839Z","steps":["trace[894911257] 'process raft request' (duration: 183.85539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:37.425013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.097473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T01:50:37.425055Z","caller":"traceutil/trace.go:171","msg":"trace[1616023598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1857; }","duration":"108.219286ms","start":"2026-02-26T01:50:37.316824Z","end":"2026-02-26T01:50:37.425043Z","steps":["trace[1616023598] 'agreement among raft nodes before linearized reading' (duration: 108.070192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:39.143857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.613136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-26T01:50:39.143981Z","caller":"traceutil/trace.go:171","msg":"trace[975177047] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1861; }","duration":"424.806882ms","start":"2026-02-26T01:50:38.719145Z","end":"2026-02-26T01:50:39.143952Z","steps":["trace[975177047] 'range keys from in-memory index tree' (duration: 424.435822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:39.144024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:38.719114Z","time spent":"424.898783ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-26T01:50:39.144146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.136588ms","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:532"} {"level":"info","ts":"2026-02-26T01:50:39.144294Z","caller":"traceutil/trace.go:171","msg":"trace[1182724520] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1861; }","duration":"548.299003ms","start":"2026-02-26T01:50:38.595919Z","end":"2026-02-26T01:50:39.144218Z","steps":["trace[1182724520] 'range keys from in-memory index tree' (duration: 547.923152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:39.14436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:38.595897Z","time spent":"548.446056ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-26T01:50:47.597727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.053746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:50:47.597877Z","caller":"traceutil/trace.go:171","msg":"trace[417362728] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"242.942834ms","start":"2026-02-26T01:50:47.354912Z","end":"2026-02-26T01:50:47.597855Z","steps":["trace[417362728] 'read index received' (duration: 104.681982ms)","trace[417362728] 'applied index is now lower than readState.Index' (duration: 138.259312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:47.598055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.744456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:expand-controller\" ","response":"range_response_count:1 size:880"} {"level":"info","ts":"2026-02-26T01:50:47.598096Z","caller":"traceutil/trace.go:171","msg":"trace[2056867149] range","detail":"{range_begin:/registry/clusterroles/system:controller:expand-controller; range_end:; response_count:1; response_revision:1903; }","duration":"200.830398ms","start":"2026-02-26T01:50:47.397252Z","end":"2026-02-26T01:50:47.598083Z","steps":["trace[2056867149] 'agreement among raft nodes before linearized reading' (duration: 200.708515ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:47.598328Z","caller":"traceutil/trace.go:171","msg":"trace[1987812006] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"281.510666ms","start":"2026-02-26T01:50:47.316592Z","end":"2026-02-26T01:50:47.598103Z","steps":["trace[1987812006] 'process raft request' (duration: 142.949846ms)","trace[1987812006] 'compare' (duration: 137.865431ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:47.598695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.768016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-26T01:50:47.598766Z","caller":"traceutil/trace.go:171","msg":"trace[1661950294] range","detail":"{range_begin:/registry/clusterroles/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1903; }","duration":"243.875689ms","start":"2026-02-26T01:50:47.354872Z","end":"2026-02-26T01:50:47.598748Z","steps":["trace[1661950294] 'agreement among raft nodes before linearized reading' (duration: 243.079248ms)"],"step_count":1}