{"level":"warn","ts":"2026-02-18T14:46:52.975448Z","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-18T14:46:52.975556Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-18T14:46:52.975629Z","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-18T14:46:52.975638Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-18T14:46:52.97567Z","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-18T14:46:52.978356Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-02-18T14:46:52.980804Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-18T14:46:52.990166Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.037925ms"} {"level":"info","ts":"2026-02-18T14:46:53.001087Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-02-18T14:46:53.001228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T14:46:53.001298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-02-18T14:46:53.001325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T14:46:53.001364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-02-18T14:46:53.001461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-02-18T14:46:53.010863Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T14:46:53.03289Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T14:46:53.03446Z","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-18T14:46:53.039136Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T14:46:53.057125Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T14:46:53.057534Z","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-18T14:46:53.057585Z","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-18T14:46:53.057684Z","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-18T14:46:53.058109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-02-18T14:46:53.058266Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-18T14:46:53.0646Z","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-18T14:46:53.064933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T14:46:53.065017Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T14:46:53.064937Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-18T14:46:53.066879Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-18T14:46:53.702785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T14:46:53.702845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T14:46:53.702863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-02-18T14:46:53.702874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-02-18T14:46:53.702884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-18T14:46:53.702901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-02-18T14:46:53.702912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-18T14:46:53.705035Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T14:46:53.705189Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:46:53.70597Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:46:53.706039Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:46:53.707692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T14:46:53.72941Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:46:53.729533Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:46:53.731369Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:46:53.733007Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T14:46:53.733034Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T14:46:53.734366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-02-18T14:47:13.782316Z","caller":"traceutil/trace.go:171","msg":"trace[1598713705] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"119.408124ms","start":"2026-02-18T14:47:13.662892Z","end":"2026-02-18T14:47:13.7823Z","steps":["trace[1598713705] 'read index received' (duration: 119.270541ms)","trace[1598713705] 'applied index is now lower than readState.Index' (duration: 137.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:13.782502Z","caller":"traceutil/trace.go:171","msg":"trace[1769549527] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"184.605819ms","start":"2026-02-18T14:47:13.597891Z","end":"2026-02-18T14:47:13.782497Z","steps":["trace[1769549527] 'process raft request' (duration: 184.319742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:13.782628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.730733ms","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-18T14:47:13.782656Z","caller":"traceutil/trace.go:171","msg":"trace[2087432587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"119.830135ms","start":"2026-02-18T14:47:13.662821Z","end":"2026-02-18T14:47:13.782651Z","steps":["trace[2087432587] 'agreement among raft nodes before linearized reading' (duration: 119.776793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:13.782741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.81802ms","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-18T14:47:13.782753Z","caller":"traceutil/trace.go:171","msg":"trace[92594638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"118.868391ms","start":"2026-02-18T14:47:13.663881Z","end":"2026-02-18T14:47:13.782749Z","steps":["trace[92594638] 'agreement among raft nodes before linearized reading' (duration: 118.842511ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:14.041198Z","caller":"traceutil/trace.go:171","msg":"trace[552368987] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"251.31467ms","start":"2026-02-18T14:47:13.78987Z","end":"2026-02-18T14:47:14.041185Z","steps":["trace[552368987] 'process raft request' (duration: 246.617696ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:14.041296Z","caller":"traceutil/trace.go:171","msg":"trace[1230411167] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"239.937324ms","start":"2026-02-18T14:47:13.801354Z","end":"2026-02-18T14:47:14.041291Z","steps":["trace[1230411167] 'process raft request' (duration: 239.917763ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:14.04156Z","caller":"traceutil/trace.go:171","msg":"trace[1163087863] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"242.465405ms","start":"2026-02-18T14:47:13.799077Z","end":"2026-02-18T14:47:14.041543Z","steps":["trace[1163087863] 'process raft request' (duration: 242.082646ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:32.251578Z","caller":"traceutil/trace.go:171","msg":"trace[208907198] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"355.260358ms","start":"2026-02-18T14:47:31.896294Z","end":"2026-02-18T14:47:32.251554Z","steps":["trace[208907198] 'process raft request' (duration: 355.098735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:32.252166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:47:31.896282Z","time spent":"355.363071ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:47:32.252644Z","caller":"traceutil/trace.go:171","msg":"trace[1041813516] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:544; }","duration":"283.189026ms","start":"2026-02-18T14:47:31.969443Z","end":"2026-02-18T14:47:32.252633Z","steps":["trace[1041813516] 'read index received' (duration: 283.184466ms)","trace[1041813516] 'applied index is now lower than readState.Index' (duration: 3.84µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:32.252771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.322969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:47:32.252799Z","caller":"traceutil/trace.go:171","msg":"trace[804947689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"283.389581ms","start":"2026-02-18T14:47:31.969401Z","end":"2026-02-18T14:47:32.25279Z","steps":["trace[804947689] 'agreement among raft nodes before linearized reading' (duration: 283.285448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:32.302689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.079595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:47:32.302824Z","caller":"traceutil/trace.go:171","msg":"trace[470724626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"265.250119ms","start":"2026-02-18T14:47:32.037556Z","end":"2026-02-18T14:47:32.302806Z","steps":["trace[470724626] 'agreement among raft nodes before linearized reading' (duration: 265.084565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:32.30311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.932315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T14:47:32.303145Z","caller":"traceutil/trace.go:171","msg":"trace[1328777377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"135.978366ms","start":"2026-02-18T14:47:32.167157Z","end":"2026-02-18T14:47:32.303135Z","steps":["trace[1328777377] 'agreement among raft nodes before linearized reading' (duration: 135.916265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:32.303493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.370415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T14:47:32.303535Z","caller":"traceutil/trace.go:171","msg":"trace[14777493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"136.480719ms","start":"2026-02-18T14:47:32.167044Z","end":"2026-02-18T14:47:32.303525Z","steps":["trace[14777493] 'agreement among raft nodes before linearized reading' (duration: 136.355756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:32.303739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.403172ms","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-18T14:47:32.303773Z","caller":"traceutil/trace.go:171","msg":"trace[1994108024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"151.464353ms","start":"2026-02-18T14:47:32.1523Z","end":"2026-02-18T14:47:32.303765Z","steps":["trace[1994108024] 'agreement among raft nodes before linearized reading' (duration: 151.35496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:46.538063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566515884,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:47:46.581076Z","caller":"traceutil/trace.go:171","msg":"trace[1131841813] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"544.075759ms","start":"2026-02-18T14:47:46.03699Z","end":"2026-02-18T14:47:46.581066Z","steps":["trace[1131841813] 'read index received' (duration: 543.964867ms)","trace[1131841813] 'applied index is now lower than readState.Index' (duration: 110.382µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:46.581228Z","caller":"traceutil/trace.go:171","msg":"trace[220120912] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"595.569131ms","start":"2026-02-18T14:47:45.985653Z","end":"2026-02-18T14:47:46.581222Z","steps":["trace[220120912] 'process raft request' (duration: 595.335935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:46.58129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:47:45.985644Z","time spent":"595.602442ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4829,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:47:46.581393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.404637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:47:46.581413Z","caller":"traceutil/trace.go:171","msg":"trace[855684366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"544.460499ms","start":"2026-02-18T14:47:46.036948Z","end":"2026-02-18T14:47:46.581408Z","steps":["trace[855684366] 'agreement among raft nodes before linearized reading' (duration: 544.428518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:46.581428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:47:46.036927Z","time spent":"544.49639ms","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:47:47.191481Z","caller":"traceutil/trace.go:171","msg":"trace[918739239] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"490.411034ms","start":"2026-02-18T14:47:46.701054Z","end":"2026-02-18T14:47:47.191465Z","steps":["trace[918739239] 'process raft request' (duration: 490.324672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:47.191603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:47:46.701036Z","time spent":"490.493397ms","remote":"127.0.0.1:59996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:47:47.191812Z","caller":"traceutil/trace.go:171","msg":"trace[1547003760] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:724; }","duration":"436.828322ms","start":"2026-02-18T14:47:46.754975Z","end":"2026-02-18T14:47:47.191803Z","steps":["trace[1547003760] 'read index received' (duration: 436.824442ms)","trace[1547003760] 'applied index is now lower than readState.Index' (duration: 3.05µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:47.191907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.929774ms","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-18T14:47:47.19193Z","caller":"traceutil/trace.go:171","msg":"trace[238359719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"436.978736ms","start":"2026-02-18T14:47:46.754946Z","end":"2026-02-18T14:47:47.191925Z","steps":["trace[238359719] 'agreement among raft nodes before linearized reading' (duration: 436.900094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:47.191949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:47:46.754932Z","time spent":"437.012736ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T14:47:47.195951Z","caller":"traceutil/trace.go:171","msg":"trace[644990253] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"212.24044ms","start":"2026-02-18T14:47:46.983702Z","end":"2026-02-18T14:47:47.195942Z","steps":["trace[644990253] 'process raft request' (duration: 212.195259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:47.196224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.951033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T14:47:47.196245Z","caller":"traceutil/trace.go:171","msg":"trace[602185381] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:704; }","duration":"249.032045ms","start":"2026-02-18T14:47:46.947207Z","end":"2026-02-18T14:47:47.196239Z","steps":["trace[602185381] 'agreement among raft nodes before linearized reading' (duration: 248.948863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:47.196341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.409413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:47:47.196357Z","caller":"traceutil/trace.go:171","msg":"trace[1009658555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:704; }","duration":"156.459004ms","start":"2026-02-18T14:47:47.039893Z","end":"2026-02-18T14:47:47.196352Z","steps":["trace[1009658555] 'agreement among raft nodes before linearized reading' (duration: 156.433863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:47.19647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.708472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-nx2tt\" ","response":"range_response_count:1 size:4823"} {"level":"info","ts":"2026-02-18T14:47:47.196486Z","caller":"traceutil/trace.go:171","msg":"trace[740013244] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-nx2tt; range_end:; response_count:1; response_revision:704; }","duration":"212.752403ms","start":"2026-02-18T14:47:46.983729Z","end":"2026-02-18T14:47:47.196481Z","steps":["trace[740013244] 'agreement among raft nodes before linearized reading' (duration: 212.698202ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:52.85315Z","caller":"traceutil/trace.go:171","msg":"trace[1868936655] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"136.824428ms","start":"2026-02-18T14:47:52.716299Z","end":"2026-02-18T14:47:52.853124Z","steps":["trace[1868936655] 'process raft request' (duration: 80.795395ms)","trace[1868936655] 'compare' (duration: 55.90766ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:52.856394Z","caller":"traceutil/trace.go:171","msg":"trace[1545604255] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:742; }","duration":"137.4098ms","start":"2026-02-18T14:47:52.71897Z","end":"2026-02-18T14:47:52.85638Z","steps":["trace[1545604255] 'read index received' (duration: 78.136649ms)","trace[1545604255] 'applied index is now lower than readState.Index' (duration: 59.272401ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:52.856489Z","caller":"traceutil/trace.go:171","msg":"trace[2047851169] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"138.946908ms","start":"2026-02-18T14:47:52.717532Z","end":"2026-02-18T14:47:52.856479Z","steps":["trace[2047851169] 'process raft request' (duration: 138.782954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:52.856518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.529373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:47:52.856545Z","caller":"traceutil/trace.go:171","msg":"trace[309035407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"137.587475ms","start":"2026-02-18T14:47:52.718947Z","end":"2026-02-18T14:47:52.856534Z","steps":["trace[309035407] 'agreement among raft nodes before linearized reading' (duration: 137.489442ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:54.839498Z","caller":"traceutil/trace.go:171","msg":"trace[580409115] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"174.188635ms","start":"2026-02-18T14:47:54.665293Z","end":"2026-02-18T14:47:54.839482Z","steps":["trace[580409115] 'read index received' (duration: 174.077463ms)","trace[580409115] 'applied index is now lower than readState.Index' (duration: 110.592µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:54.83963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.325329ms","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-18T14:47:54.839648Z","caller":"traceutil/trace.go:171","msg":"trace[90379248] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:729; }","duration":"174.3714ms","start":"2026-02-18T14:47:54.665272Z","end":"2026-02-18T14:47:54.839643Z","steps":["trace[90379248] 'agreement among raft nodes before linearized reading' (duration: 174.264848ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:54.839806Z","caller":"traceutil/trace.go:171","msg":"trace[640156472] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"175.656781ms","start":"2026-02-18T14:47:54.664144Z","end":"2026-02-18T14:47:54.839801Z","steps":["trace[640156472] 'process raft request' (duration: 175.263571ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:11.448135Z","caller":"traceutil/trace.go:171","msg":"trace[1928052033] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"168.919468ms","start":"2026-02-18T14:48:11.279199Z","end":"2026-02-18T14:48:11.448118Z","steps":["trace[1928052033] 'process raft request' (duration: 168.774926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:12.428036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.106826ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T14:48:13.074464Z","caller":"traceutil/trace.go:171","msg":"trace[1025707919] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"140.063749ms","start":"2026-02-18T14:48:12.934377Z","end":"2026-02-18T14:48:13.074441Z","steps":["trace[1025707919] 'read index received' (duration: 139.724591ms)","trace[1025707919] 'applied index is now lower than readState.Index' (duration: 338.058µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:13.074572Z","caller":"traceutil/trace.go:171","msg":"trace[1779960994] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"150.066602ms","start":"2026-02-18T14:48:12.924495Z","end":"2026-02-18T14:48:13.074562Z","steps":["trace[1779960994] 'process raft request' (duration: 149.686514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:13.074924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.531239ms","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-18T14:48:13.07499Z","caller":"traceutil/trace.go:171","msg":"trace[832210427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"140.598461ms","start":"2026-02-18T14:48:12.934372Z","end":"2026-02-18T14:48:13.07497Z","steps":["trace[832210427] 'agreement among raft nodes before linearized reading' (duration: 140.439217ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:13.33613Z","caller":"traceutil/trace.go:171","msg":"trace[41961193] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"254.722121ms","start":"2026-02-18T14:48:13.08138Z","end":"2026-02-18T14:48:13.336102Z","steps":["trace[41961193] 'process raft request' (duration: 207.916996ms)","trace[41961193] 'compare' (duration: 46.675492ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:13.654627Z","caller":"traceutil/trace.go:171","msg":"trace[1439938024] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:809; }","duration":"201.742295ms","start":"2026-02-18T14:48:13.452867Z","end":"2026-02-18T14:48:13.65461Z","steps":["trace[1439938024] 'read index received' (duration: 184.695242ms)","trace[1439938024] 'applied index is now lower than readState.Index' (duration: 17.046163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:13.654798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.908819ms","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-18T14:48:13.654833Z","caller":"traceutil/trace.go:171","msg":"trace[1037096117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"201.990112ms","start":"2026-02-18T14:48:13.452828Z","end":"2026-02-18T14:48:13.654818Z","steps":["trace[1037096117] 'agreement among raft nodes before linearized reading' (duration: 201.849728ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:13.654821Z","caller":"traceutil/trace.go:171","msg":"trace[1652060105] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"570.974116ms","start":"2026-02-18T14:48:13.083817Z","end":"2026-02-18T14:48:13.654791Z","steps":["trace[1652060105] 'process raft request' (duration: 553.8355ms)","trace[1652060105] 'compare' (duration: 16.551181ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:13.655019Z","caller":"traceutil/trace.go:171","msg":"trace[672280509] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"570.543515ms","start":"2026-02-18T14:48:13.084468Z","end":"2026-02-18T14:48:13.655011Z","steps":["trace[672280509] 'process raft request' (duration: 570.069484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:13.655012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:13.083811Z","time spent":"571.104729ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:48:13.655115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:13.084462Z","time spent":"570.586236ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:48:29.557186Z","caller":"traceutil/trace.go:171","msg":"trace[153469064] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"135.378106ms","start":"2026-02-18T14:48:29.421794Z","end":"2026-02-18T14:48:29.557173Z","steps":["trace[153469064] 'read index received' (duration: 135.281293ms)","trace[153469064] 'applied index is now lower than readState.Index' (duration: 95.993µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:29.557265Z","caller":"traceutil/trace.go:171","msg":"trace[504086659] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"413.369841ms","start":"2026-02-18T14:48:29.14389Z","end":"2026-02-18T14:48:29.55726Z","steps":["trace[504086659] 'process raft request' (duration: 413.191247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:29.557326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:29.143869Z","time spent":"413.409152ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:48:29.557537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.738875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:48:29.557583Z","caller":"traceutil/trace.go:171","msg":"trace[2085966401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"135.758065ms","start":"2026-02-18T14:48:29.421791Z","end":"2026-02-18T14:48:29.557549Z","steps":["trace[2085966401] 'agreement among raft nodes before linearized reading' (duration: 135.690803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:30.084959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.094202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:30.085031Z","caller":"traceutil/trace.go:171","msg":"trace[1119864471] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"357.230829ms","start":"2026-02-18T14:48:29.727791Z","end":"2026-02-18T14:48:30.085022Z","steps":["trace[1119864471] 'read index received' (duration: 56.977843ms)","trace[1119864471] 'applied index is now lower than readState.Index' (duration: 300.252326ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:30.085146Z","caller":"traceutil/trace.go:171","msg":"trace[782228021] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"524.781365ms","start":"2026-02-18T14:48:29.560359Z","end":"2026-02-18T14:48:30.08514Z","steps":["trace[782228021] 'process raft request' (duration: 224.442917ms)","trace[782228021] 'compare' (duration: 299.989639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:30.085201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:29.560348Z","time spent":"524.836546ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:48:30.085332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.539656ms","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-18T14:48:30.085346Z","caller":"traceutil/trace.go:171","msg":"trace[241990694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"357.574167ms","start":"2026-02-18T14:48:29.727766Z","end":"2026-02-18T14:48:30.085341Z","steps":["trace[241990694] 'agreement among raft nodes before linearized reading' (duration: 357.516075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:30.08536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:29.727754Z","time spent":"357.602818ms","remote":"127.0.0.1:59828","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-18T14:48:30.085442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.364463ms","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-18T14:48:30.085455Z","caller":"traceutil/trace.go:171","msg":"trace[1135827520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"350.385433ms","start":"2026-02-18T14:48:29.735065Z","end":"2026-02-18T14:48:30.085451Z","steps":["trace[1135827520] 'agreement among raft nodes before linearized reading' (duration: 350.348052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:30.085467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:29.73506Z","time spent":"350.405304ms","remote":"127.0.0.1:59828","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-18T14:48:30.085607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.6625ms","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-18T14:48:30.085659Z","caller":"traceutil/trace.go:171","msg":"trace[1658144437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"329.724951ms","start":"2026-02-18T14:48:29.755915Z","end":"2026-02-18T14:48:30.08564Z","steps":["trace[1658144437] 'agreement among raft nodes before linearized reading' (duration: 329.594448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:30.085682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:29.75591Z","time spent":"329.766852ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:48:32.445771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.545251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:32.445994Z","caller":"traceutil/trace.go:171","msg":"trace[131562042] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"319.628137ms","start":"2026-02-18T14:48:32.126353Z","end":"2026-02-18T14:48:32.445982Z","steps":["trace[131562042] 'process raft request' (duration: 131.794068ms)","trace[131562042] 'compare' (duration: 187.47477ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:32.446044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:32.126346Z","time spent":"319.672368ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:48:32.446258Z","caller":"traceutil/trace.go:171","msg":"trace[705702257] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"317.911155ms","start":"2026-02-18T14:48:32.128342Z","end":"2026-02-18T14:48:32.446253Z","steps":["trace[705702257] 'process raft request' (duration: 317.518396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:32.446283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:32.128325Z","time spent":"317.944477ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:48:42.702889Z","caller":"traceutil/trace.go:171","msg":"trace[330365385] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"143.160074ms","start":"2026-02-18T14:48:42.559714Z","end":"2026-02-18T14:48:42.702875Z","steps":["trace[330365385] 'read index received' (duration: 143.030781ms)","trace[330365385] 'applied index is now lower than readState.Index' (duration: 128.763µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:42.70301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.282837ms","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-18T14:48:42.703046Z","caller":"traceutil/trace.go:171","msg":"trace[831085108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"143.360569ms","start":"2026-02-18T14:48:42.559669Z","end":"2026-02-18T14:48:42.70303Z","steps":["trace[831085108] 'agreement among raft nodes before linearized reading' (duration: 143.259077ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:42.703251Z","caller":"traceutil/trace.go:171","msg":"trace[1602122533] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"189.233722ms","start":"2026-02-18T14:48:42.514012Z","end":"2026-02-18T14:48:42.703246Z","steps":["trace[1602122533] 'process raft request' (duration: 188.77766ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:43.850606Z","caller":"traceutil/trace.go:171","msg":"trace[155164114] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"184.019796ms","start":"2026-02-18T14:48:43.66657Z","end":"2026-02-18T14:48:43.85059Z","steps":["trace[155164114] 'read index received' (duration: 183.850191ms)","trace[155164114] 'applied index is now lower than readState.Index' (duration: 168.985µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:43.850707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.117468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:48:43.850729Z","caller":"traceutil/trace.go:171","msg":"trace[1218985900] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:926; }","duration":"184.1941ms","start":"2026-02-18T14:48:43.666529Z","end":"2026-02-18T14:48:43.850723Z","steps":["trace[1218985900] 'agreement among raft nodes before linearized reading' (duration: 184.133288ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:43.850907Z","caller":"traceutil/trace.go:171","msg":"trace[1809452969] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"209.563796ms","start":"2026-02-18T14:48:43.641315Z","end":"2026-02-18T14:48:43.850879Z","steps":["trace[1809452969] 'process raft request' (duration: 209.164516ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:44.4109Z","caller":"traceutil/trace.go:171","msg":"trace[1550759493] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"185.665196ms","start":"2026-02-18T14:48:44.225219Z","end":"2026-02-18T14:48:44.410884Z","steps":["trace[1550759493] 'process raft request' (duration: 185.557073ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:44.603977Z","caller":"traceutil/trace.go:171","msg":"trace[835400978] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"378.630348ms","start":"2026-02-18T14:48:44.225331Z","end":"2026-02-18T14:48:44.603962Z","steps":["trace[835400978] 'process raft request' (duration: 378.550966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:44.604078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:44.225308Z","time spent":"378.714071ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:48:45.028656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.02664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:45.028722Z","caller":"traceutil/trace.go:171","msg":"trace[1951939457] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"242.352451ms","start":"2026-02-18T14:48:44.786359Z","end":"2026-02-18T14:48:45.028712Z","steps":["trace[1951939457] 'process raft request' (duration: 132.222109ms)","trace[1951939457] 'compare' (duration: 109.913158ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:46.354802Z","caller":"traceutil/trace.go:171","msg":"trace[111032196] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"496.908489ms","start":"2026-02-18T14:48:45.857869Z","end":"2026-02-18T14:48:46.354778Z","steps":["trace[111032196] 'process raft request' (duration: 496.587911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:46.354959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:45.857861Z","time spent":"497.029452ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:48:46.366366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.917594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:48:46.366418Z","caller":"traceutil/trace.go:171","msg":"trace[791936955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"320.036357ms","start":"2026-02-18T14:48:46.04637Z","end":"2026-02-18T14:48:46.366406Z","steps":["trace[791936955] 'agreement among raft nodes before linearized reading' (duration: 319.913834ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:46.366224Z","caller":"traceutil/trace.go:171","msg":"trace[325987892] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"319.781571ms","start":"2026-02-18T14:48:46.046422Z","end":"2026-02-18T14:48:46.366203Z","steps":["trace[325987892] 'read index received' (duration: 308.015275ms)","trace[325987892] 'applied index is now lower than readState.Index' (duration: 11.763275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:46.366444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:46.046347Z","time spent":"320.091788ms","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:48:46.6536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.658616ms","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-18T14:48:46.653657Z","caller":"traceutil/trace.go:171","msg":"trace[1164062354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"239.749578ms","start":"2026-02-18T14:48:46.413896Z","end":"2026-02-18T14:48:46.653646Z","steps":["trace[1164062354] 'range keys from in-memory index tree' (duration: 239.572193ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:50.272739Z","caller":"traceutil/trace.go:171","msg":"trace[787012283] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"231.22577ms","start":"2026-02-18T14:48:50.041494Z","end":"2026-02-18T14:48:50.27272Z","steps":["trace[787012283] 'process raft request' (duration: 231.139318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:52.449399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.234453ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T14:51:21.948814Z","caller":"traceutil/trace.go:171","msg":"trace[1006047153] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"194.28766ms","start":"2026-02-18T14:51:21.753662Z","end":"2026-02-18T14:51:21.947949Z","steps":["trace[1006047153] 'process raft request' (duration: 188.774809ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:07.517233Z","caller":"traceutil/trace.go:171","msg":"trace[1534358732] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"152.239208ms","start":"2026-02-18T14:52:07.364972Z","end":"2026-02-18T14:52:07.517211Z","steps":["trace[1534358732] 'read index received' (duration: 152.071024ms)","trace[1534358732] 'applied index is now lower than readState.Index' (duration: 167.204µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:07.517535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.541614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-18T14:52:07.51757Z","caller":"traceutil/trace.go:171","msg":"trace[794744874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"152.590965ms","start":"2026-02-18T14:52:07.364968Z","end":"2026-02-18T14:52:07.517559Z","steps":["trace[794744874] 'agreement among raft nodes before linearized reading' (duration: 152.395721ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:07.517815Z","caller":"traceutil/trace.go:171","msg":"trace[1460865512] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"210.338102ms","start":"2026-02-18T14:52:07.307467Z","end":"2026-02-18T14:52:07.517805Z","steps":["trace[1460865512] 'process raft request' (duration: 209.639356ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:11.120562Z","caller":"traceutil/trace.go:171","msg":"trace[1399347531] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2004; }","duration":"177.020048ms","start":"2026-02-18T14:52:10.94352Z","end":"2026-02-18T14:52:11.12054Z","steps":["trace[1399347531] 'read index received' (duration: 176.61731ms)","trace[1399347531] 'applied index is now lower than readState.Index' (duration: 401.758µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:11.121076Z","caller":"traceutil/trace.go:171","msg":"trace[1083085213] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"192.923228ms","start":"2026-02-18T14:52:10.928138Z","end":"2026-02-18T14:52:11.121061Z","steps":["trace[1083085213] 'process raft request' (duration: 192.050788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:11.121346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.816647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-18T14:52:11.12138Z","caller":"traceutil/trace.go:171","msg":"trace[1819745416] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1928; }","duration":"177.876419ms","start":"2026-02-18T14:52:10.943493Z","end":"2026-02-18T14:52:11.121369Z","steps":["trace[1819745416] 'agreement among raft nodes before linearized reading' (duration: 177.755146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:16.720821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:17.565362Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.372571129s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:52:17.568755Z","caller":"traceutil/trace.go:171","msg":"trace[502522313] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"1.348605099s","start":"2026-02-18T14:52:16.220129Z","end":"2026-02-18T14:52:17.568734Z","steps":["trace[502522313] 'read index received' (duration: 1.348013595s)","trace[502522313] 'applied index is now lower than readState.Index' (duration: 590.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:17.569048Z","caller":"traceutil/trace.go:171","msg":"trace[599631285] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"1.37637906s","start":"2026-02-18T14:52:16.192655Z","end":"2026-02-18T14:52:17.569034Z","steps":["trace[599631285] 'process raft request' (duration: 1.375894119s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:17.569659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.897145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T14:52:17.569708Z","caller":"traceutil/trace.go:171","msg":"trace[1322564739] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1961; }","duration":"604.965746ms","start":"2026-02-18T14:52:16.964724Z","end":"2026-02-18T14:52:17.56969Z","steps":["trace[1322564739] 'agreement among raft nodes before linearized reading' (duration: 604.873664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:17.569752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:16.964709Z","time spent":"605.035279ms","remote":"127.0.0.1:59602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-02-18T14:52:17.57005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:16.192643Z","time spent":"1.377035147s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:52:17.569483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11530034s","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:528"} {"level":"info","ts":"2026-02-18T14:52:17.570706Z","caller":"traceutil/trace.go:171","msg":"trace[247118206] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1961; }","duration":"1.116561439s","start":"2026-02-18T14:52:16.454119Z","end":"2026-02-18T14:52:17.57068Z","steps":["trace[247118206] 'agreement among raft nodes before linearized reading' (duration: 1.114975502s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:17.570917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:16.454105Z","time spent":"1.116794885s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T14:52:17.570978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.958533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:17.571172Z","caller":"traceutil/trace.go:171","msg":"trace[2025138794] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1961; }","duration":"993.096976ms","start":"2026-02-18T14:52:16.577979Z","end":"2026-02-18T14:52:17.571076Z","steps":["trace[2025138794] 'agreement among raft nodes before linearized reading' (duration: 992.965333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:17.571252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:16.577966Z","time spent":"993.269971ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:17.56958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349456699s","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-18T14:52:17.571857Z","caller":"traceutil/trace.go:171","msg":"trace[734896630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1961; }","duration":"1.351785934s","start":"2026-02-18T14:52:16.220057Z","end":"2026-02-18T14:52:17.571843Z","steps":["trace[734896630] 'agreement among raft nodes before linearized reading' (duration: 1.349460129s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:17.571984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:16.220037Z","time spent":"1.351930418s","remote":"127.0.0.1:59828","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-18T14:52:17.570916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.344956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:52:17.574431Z","caller":"traceutil/trace.go:171","msg":"trace[1909056567] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1961; }","duration":"788.92075ms","start":"2026-02-18T14:52:16.785495Z","end":"2026-02-18T14:52:17.574415Z","steps":["trace[1909056567] 'agreement among raft nodes before linearized reading' (duration: 784.987727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:17.574647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:16.785479Z","time spent":"789.152026ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T14:52:17.571866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006703481s","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-18T14:52:17.575175Z","caller":"traceutil/trace.go:171","msg":"trace[2093329754] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1961; }","duration":"1.01004781s","start":"2026-02-18T14:52:16.565112Z","end":"2026-02-18T14:52:17.57516Z","steps":["trace[2093329754] 'agreement among raft nodes before linearized reading' (duration: 1.00667464s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:17.575443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:16.565093Z","time spent":"1.010333557s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T14:52:20.036923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000662511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:20.038478Z","caller":"traceutil/trace.go:171","msg":"trace[641091182] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.002273139s","start":"2026-02-18T14:52:18.036183Z","end":"2026-02-18T14:52:20.038456Z","steps":["trace[641091182] 'agreement among raft nodes before linearized reading' (duration: 2.000684062s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:20.039072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:18.036169Z","time spent":"2.002885774s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:20 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:22.039261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000970937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:22.039342Z","caller":"traceutil/trace.go:171","msg":"trace[1239724980] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00111427s","start":"2026-02-18T14:52:20.038206Z","end":"2026-02-18T14:52:22.03932Z","steps":["trace[1239724980] 'agreement among raft nodes before linearized reading' (duration: 2.001006847s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:22.039404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:20.038186Z","time spent":"2.001207302s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:22 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:22.591125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000470851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:22.591247Z","caller":"traceutil/trace.go:171","msg":"trace[235965049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000556903s","start":"2026-02-18T14:52:17.590626Z","end":"2026-02-18T14:52:22.591182Z","steps":["trace[235965049] 'agreement among raft nodes before linearized reading' (duration: 5.000469141s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:22.591302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.590599Z","time spent":"5.000687216s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/18 14:52:22 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:22.639746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999678832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:22.639976Z","caller":"traceutil/trace.go:171","msg":"trace[342007993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999848046s","start":"2026-02-18T14:52:17.640011Z","end":"2026-02-18T14:52:22.639859Z","steps":["trace[342007993] 'agreement among raft nodes before linearized reading' (duration: 4.999674392s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:22.640236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.640003Z","time spent":"5.000185334s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:52:22.798865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999091558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:22.799006Z","caller":"traceutil/trace.go:171","msg":"trace[779945365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999332563s","start":"2026-02-18T14:52:17.799648Z","end":"2026-02-18T14:52:22.79898Z","steps":["trace[779945365] 'agreement among raft nodes before linearized reading' (duration: 4.999120708s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:22.799079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.79963Z","time spent":"4.999434026s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/18 14:52:22 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:24.048836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000482795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:24.048914Z","caller":"traceutil/trace.go:171","msg":"trace[1860290] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000597108s","start":"2026-02-18T14:52:22.048298Z","end":"2026-02-18T14:52:24.048895Z","steps":["trace[1860290] 'agreement among raft nodes before linearized reading' (duration: 2.000497166s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:24.04896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:22.04829Z","time spent":"2.000660409s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:24.580392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.580201Z","time spent":"7.000183378s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:24.580433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.580138Z","time spent":"7.00028715s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:24.580714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.580655Z","time spent":"7.000056574s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:24.582801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.581887Z","time spent":"7.000909275s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:24.58828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.587527Z","time spent":"7.000748652s","remote":"127.0.0.1:59602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:24.990435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"7.123876985s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:52:26.049691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000630129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:26.049779Z","caller":"traceutil/trace.go:171","msg":"trace[365904224] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000768233s","start":"2026-02-18T14:52:24.048994Z","end":"2026-02-18T14:52:26.049762Z","steps":["trace[365904224] 'agreement among raft nodes before linearized reading' (duration: 2.00065067s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:26.049834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:24.048981Z","time spent":"2.000843774s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:26 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:27.593538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.976902835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:27.593647Z","caller":"traceutil/trace.go:171","msg":"trace[548987244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.97708657s","start":"2026-02-18T14:52:24.616539Z","end":"2026-02-18T14:52:27.593626Z","steps":["trace[548987244] 'agreement among raft nodes before linearized reading' (duration: 2.976932516s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:27.593814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:24.616524Z","time spent":"2.977276454s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/18 14:52:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:27.641849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.972792697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:27.642091Z","caller":"traceutil/trace.go:171","msg":"trace[1353510415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.973092344s","start":"2026-02-18T14:52:24.668965Z","end":"2026-02-18T14:52:27.642057Z","steps":["trace[1353510415] 'agreement among raft nodes before linearized reading' (duration: 2.972807837s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:27.642208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:24.668933Z","time spent":"2.973256998s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/18 14:52:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:27.657341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999508577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:27.657426Z","caller":"traceutil/trace.go:171","msg":"trace[711811390] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; }","duration":"9.99963388s","start":"2026-02-18T14:52:17.657773Z","end":"2026-02-18T14:52:27.657406Z","steps":["trace[711811390] 'agreement among raft nodes before linearized reading' (duration: 9.999524287s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:27.657522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.657763Z","time spent":"9.999745912s","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":0,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} 2026/02/18 14:52:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:27.798804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998426108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:27.79896Z","caller":"traceutil/trace.go:171","msg":"trace[1369054361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.998639963s","start":"2026-02-18T14:52:24.800292Z","end":"2026-02-18T14:52:27.798932Z","steps":["trace[1369054361] 'agreement among raft nodes before linearized reading' (duration: 2.998455679s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:27.799025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:24.800272Z","time spent":"2.998738336s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/18 14:52:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:28.058101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000444135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:28.058275Z","caller":"traceutil/trace.go:171","msg":"trace[1511014877] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00066436s","start":"2026-02-18T14:52:26.057593Z","end":"2026-02-18T14:52:28.058257Z","steps":["trace[1511014877] 'agreement among raft nodes before linearized reading' (duration: 2.000480075s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:28.058393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:26.05757Z","time spent":"2.000810003s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:28.739138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.845217222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:28.739218Z","caller":"traceutil/trace.go:171","msg":"trace[1294901080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.845315065s","start":"2026-02-18T14:52:17.893883Z","end":"2026-02-18T14:52:28.739198Z","steps":["trace[1294901080] 'agreement among raft nodes before linearized reading' (duration: 10.845213102s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:28.739266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:17.893866Z","time spent":"10.845388957s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/18 14:52:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:28.829673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:27.644516Z","time spent":"1.185145495s","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/18 14:52:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/02/18 14:52:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:29.043617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:22.042654Z","time spent":"7.000957036s","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:29.14211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:22.141886Z","time spent":"7.000216109s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:29.436334Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38c19c7137df5ac6","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-18T14:52:30.058708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000629171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:30.058857Z","caller":"traceutil/trace.go:171","msg":"trace[2076639939] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000834226s","start":"2026-02-18T14:52:28.057972Z","end":"2026-02-18T14:52:30.058807Z","steps":["trace[2076639939] 'agreement among raft nodes before linearized reading' (duration: 2.000668712s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:30.05891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:28.057948Z","time spent":"2.000950729s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:30 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:31.957578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:24.957212Z","time spent":"7.000350453s","remote":"127.0.0.1:59746","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:32.068599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000873785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:32.06883Z","caller":"traceutil/trace.go:171","msg":"trace[1842605325] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001152382s","start":"2026-02-18T14:52:30.067663Z","end":"2026-02-18T14:52:32.068815Z","steps":["trace[1842605325] 'agreement among raft nodes before linearized reading' (duration: 2.000891546s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:32.068963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:30.067651Z","time spent":"2.001298615s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:32.102443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:25.101731Z","time spent":"7.000707232s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:32.580598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000841211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:32.580691Z","caller":"traceutil/trace.go:171","msg":"trace[424659806] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; }","duration":"10.000984015s","start":"2026-02-18T14:52:22.579688Z","end":"2026-02-18T14:52:32.580672Z","steps":["trace[424659806] 'agreement among raft nodes before linearized reading' (duration: 10.000867982s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:32.580743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:22.579668Z","time spent":"10.001064446s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} 2026/02/18 14:52:32 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:32.593744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:27.596549Z","time spent":"4.997189324s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/18 14:52:32 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:32.800535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:27.802273Z","time spent":"4.998254279s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/18 14:52:32 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:33.678479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"15.811798127s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:52:33.683666Z","caller":"traceutil/trace.go:171","msg":"trace[1413372144] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2040; }","duration":"16.093021009s","start":"2026-02-18T14:52:17.590629Z","end":"2026-02-18T14:52:33.68365Z","steps":["trace[1413372144] 'read index received' (duration: 275.929536ms)","trace[1413372144] 'applied index is now lower than readState.Index' (duration: 15.817090433s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:33.675486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.952485735s","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-18T14:52:33.684335Z","caller":"traceutil/trace.go:171","msg":"trace[1558730665] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1961; }","duration":"12.961361177s","start":"2026-02-18T14:52:20.722945Z","end":"2026-02-18T14:52:33.684306Z","steps":["trace[1558730665] 'range keys from in-memory index tree' (duration: 12.952463175s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:33.698944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:32.594827Z","time spent":"1.104106942s","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/18 14:52:33 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:34.069885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000535997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:34.070004Z","caller":"traceutil/trace.go:171","msg":"trace[509432017] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000716161s","start":"2026-02-18T14:52:32.069243Z","end":"2026-02-18T14:52:34.069959Z","steps":["trace[509432017] 'agreement among raft nodes before linearized reading' (duration: 2.000575748s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:34.070059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:32.069217Z","time spent":"2.000831594s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:34.184613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:34.684992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:34.81349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999648315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:34.813574Z","caller":"traceutil/trace.go:171","msg":"trace[873773337] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999765378s","start":"2026-02-18T14:52:32.813777Z","end":"2026-02-18T14:52:34.813543Z","steps":["trace[873773337] 'agreement among raft nodes before linearized reading' (duration: 1.999670536s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:34.813621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:32.813762Z","time spent":"1.999850049s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:34 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:35.185396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:35.686085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:35.754766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:28.754583Z","time spent":"7.000172069s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:35.764291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:28.96748Z","time spent":"6.796800053s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/18 14:52:35 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:36.046525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:29.046254Z","time spent":"7.000260572s","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:36.077159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000526426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:36.077228Z","caller":"traceutil/trace.go:171","msg":"trace[2095717674] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000641469s","start":"2026-02-18T14:52:34.076571Z","end":"2026-02-18T14:52:36.077213Z","steps":["trace[2095717674] 'agreement among raft nodes before linearized reading' (duration: 2.000545537s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:36.077267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:34.076537Z","time spent":"2.000722841s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:36 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:36.18707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:36.444869Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38c19c7137df5ac6","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-18T14:52:36.68735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:36.940405Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"11.949765778s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:52:37.297684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.736322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-02-18T14:52:37.453217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.2577ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-18T14:52:37.461226Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"6.673479ms","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"info","ts":"2026-02-18T14:52:37.462666Z","caller":"traceutil/trace.go:171","msg":"trace[1772296509] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1976; }","duration":"5.355192695s","start":"2026-02-18T14:52:32.107418Z","end":"2026-02-18T14:52:37.46261Z","steps":["trace[1772296509] 'process raft request' (duration: 5.353880825s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.462916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:32.107384Z","time spent":"5.35535858s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:52:37.723142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.698677ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-18T14:52:37.723363Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"11.98µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"info","ts":"2026-02-18T14:52:37.723448Z","caller":"traceutil/trace.go:171","msg":"trace[793214980] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2045; }","duration":"4.039657874s","start":"2026-02-18T14:52:33.683772Z","end":"2026-02-18T14:52:37.72343Z","steps":["trace[793214980] 'read index received' (duration: 3.257163844s)","trace[793214980] 'applied index is now lower than readState.Index' (duration: 782.49304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:37.72358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.758359985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:37.723607Z","caller":"traceutil/trace.go:171","msg":"trace[2129517040] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:0; response_revision:1978; }","duration":"10.758433947s","start":"2026-02-18T14:52:26.965164Z","end":"2026-02-18T14:52:37.723598Z","steps":["trace[2129517040] 'agreement among raft nodes before linearized reading' (duration: 10.758382915s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.72363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:26.965141Z","time spent":"10.758483878s","remote":"127.0.0.1:59602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-02-18T14:52:37.723648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.331567607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:52:37.723711Z","caller":"traceutil/trace.go:171","msg":"trace[1313953778] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1978; }","duration":"11.331630138s","start":"2026-02-18T14:52:26.392066Z","end":"2026-02-18T14:52:37.723696Z","steps":["trace[1313953778] 'agreement among raft nodes before linearized reading' (duration: 11.331544766s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.723743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:26.392004Z","time spent":"11.33172902s","remote":"127.0.0.1:59980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-18T14:52:37.723802Z","caller":"traceutil/trace.go:171","msg":"trace[1173113881] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1978; }","duration":"4.033025426s","start":"2026-02-18T14:52:33.690769Z","end":"2026-02-18T14:52:37.723794Z","steps":["trace[1173113881] 'process raft request' (duration: 4.032463963s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.723857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:33.690751Z","time spent":"4.033067508s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:52:37.723905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.712324052s","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-18T14:52:37.723932Z","caller":"traceutil/trace.go:171","msg":"trace[1987100095] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1978; }","duration":"12.712353383s","start":"2026-02-18T14:52:25.011571Z","end":"2026-02-18T14:52:37.723924Z","steps":["trace[1987100095] 'agreement among raft nodes before linearized reading' (duration: 12.712309421s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.723947Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38c19c7137df5ac6","error":"lease not found"} {"level":"warn","ts":"2026-02-18T14:52:37.72396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:25.011518Z","time spent":"12.712434384s","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.723981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.25722281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:52:37.723999Z","caller":"traceutil/trace.go:171","msg":"trace[1235367755] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1978; }","duration":"4.257302571s","start":"2026-02-18T14:52:33.466689Z","end":"2026-02-18T14:52:37.723991Z","steps":["trace[1235367755] 'agreement among raft nodes before linearized reading' (duration: 4.25727043s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:33.466657Z","time spent":"4.257354273s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":12,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.724088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.546602405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:37.724103Z","caller":"traceutil/trace.go:171","msg":"trace[734067981] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1978; }","duration":"4.546653467s","start":"2026-02-18T14:52:33.177445Z","end":"2026-02-18T14:52:37.724098Z","steps":["trace[734067981] 'agreement among raft nodes before linearized reading' (duration: 4.546629466s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.731296397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T14:52:37.724121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:33.177426Z","time spent":"4.546691057s","remote":"127.0.0.1:59816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-02-18T14:52:37.724131Z","caller":"traceutil/trace.go:171","msg":"trace[1703391768] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1978; }","duration":"13.731323797s","start":"2026-02-18T14:52:23.992798Z","end":"2026-02-18T14:52:37.724122Z","steps":["trace[1703391768] 'agreement among raft nodes before linearized reading' (duration: 13.731277436s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:23.992766Z","time spent":"13.731385699s","remote":"127.0.0.1:59818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.724209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.758428724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:37.724225Z","caller":"traceutil/trace.go:171","msg":"trace[1802282652] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1978; }","duration":"4.758476826s","start":"2026-02-18T14:52:32.965743Z","end":"2026-02-18T14:52:37.72422Z","steps":["trace[1802282652] 'agreement among raft nodes before linearized reading' (duration: 4.758450445s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:32.965725Z","time spent":"4.758510876s","remote":"127.0.0.1:59784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.724299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.744402298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:37.724326Z","caller":"traceutil/trace.go:171","msg":"trace[1188181790] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1978; }","duration":"13.74446443s","start":"2026-02-18T14:52:23.979853Z","end":"2026-02-18T14:52:37.724318Z","steps":["trace[1188181790] 'agreement among raft nodes before linearized reading' (duration: 13.744420679s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.763374135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6160"} {"level":"warn","ts":"2026-02-18T14:52:37.724356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:23.979834Z","time spent":"13.744514132s","remote":"127.0.0.1:59646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-18T14:52:37.724368Z","caller":"traceutil/trace.go:171","msg":"trace[1870106594] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1978; }","duration":"5.763414525s","start":"2026-02-18T14:52:31.960948Z","end":"2026-02-18T14:52:37.724363Z","steps":["trace[1870106594] 'agreement among raft nodes before linearized reading' (duration: 5.763382774s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:31.960935Z","time spent":"5.763445447s","remote":"127.0.0.1:59746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":6184,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T14:52:37.724475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.662145446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"warn","ts":"2026-02-18T14:52:37.724481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"14.896149166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:52:37.724491Z","caller":"traceutil/trace.go:171","msg":"trace[272446677] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1978; }","duration":"7.662160486s","start":"2026-02-18T14:52:30.062326Z","end":"2026-02-18T14:52:37.724486Z","steps":["trace[272446677] 'agreement among raft nodes before linearized reading' (duration: 7.662128336s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:30.062283Z","time spent":"7.662219148s","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7060,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-18T14:52:37.724507Z","caller":"traceutil/trace.go:171","msg":"trace[1803496058] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1978; }","duration":"14.896211087s","start":"2026-02-18T14:52:22.828288Z","end":"2026-02-18T14:52:37.724499Z","steps":["trace[1803496058] 'agreement among raft nodes before linearized reading' (duration: 14.896169977s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:22.828269Z","time spent":"14.896257019s","remote":"127.0.0.1:59996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.724599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.345111324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:37.724617Z","caller":"traceutil/trace.go:171","msg":"trace[58338783] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1978; }","duration":"9.345171245s","start":"2026-02-18T14:52:28.37944Z","end":"2026-02-18T14:52:37.724611Z","steps":["trace[58338783] 'agreement among raft nodes before linearized reading' (duration: 9.345141304s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.724633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:28.379416Z","time spent":"9.345212336s","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.725228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"16.674735734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:52:37.725256Z","caller":"traceutil/trace.go:171","msg":"trace[310164205] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1978; }","duration":"16.674791265s","start":"2026-02-18T14:52:21.050459Z","end":"2026-02-18T14:52:37.72525Z","steps":["trace[310164205] 'agreement among raft nodes before linearized reading' (duration: 16.674751094s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.725275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:21.050445Z","time spent":"16.674825126s","remote":"127.0.0.1:59746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.725355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.616020535s","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:7"} {"level":"info","ts":"2026-02-18T14:52:37.725372Z","caller":"traceutil/trace.go:171","msg":"trace[1765396139] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1978; }","duration":"2.616039425s","start":"2026-02-18T14:52:35.109327Z","end":"2026-02-18T14:52:37.725366Z","steps":["trace[1765396139] 'agreement among raft nodes before linearized reading' (duration: 2.616012745s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.725411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:35.109224Z","time spent":"2.616159729s","remote":"127.0.0.1:55138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.725574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.657386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-18T14:52:37.725592Z","caller":"traceutil/trace.go:171","msg":"trace[961144384] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:1978; }","duration":"256.709108ms","start":"2026-02-18T14:52:37.468877Z","end":"2026-02-18T14:52:37.725586Z","steps":["trace[961144384] 'agreement among raft nodes before linearized reading' (duration: 256.642666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.725667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.73741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:52:37.725684Z","caller":"traceutil/trace.go:171","msg":"trace[616326850] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1978; }","duration":"658.783581ms","start":"2026-02-18T14:52:37.066895Z","end":"2026-02-18T14:52:37.725678Z","steps":["trace[616326850] 'agreement among raft nodes before linearized reading' (duration: 658.75638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.725704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:37.066871Z","time spent":"658.827691ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.725787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648447807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:37.725803Z","caller":"traceutil/trace.go:171","msg":"trace[1880895113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1978; }","duration":"1.648482167s","start":"2026-02-18T14:52:36.077316Z","end":"2026-02-18T14:52:37.725798Z","steps":["trace[1880895113] 'agreement among raft nodes before linearized reading' (duration: 1.648457706s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.725817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:36.077305Z","time spent":"1.648508878s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:37.725919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676102235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf67177159\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T14:52:37.725934Z","caller":"traceutil/trace.go:171","msg":"trace[591362437] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18955edf67177159; range_end:; response_count:1; response_revision:1978; }","duration":"1.676148806s","start":"2026-02-18T14:52:36.049781Z","end":"2026-02-18T14:52:37.725929Z","steps":["trace[591362437] 'agreement among raft nodes before linearized reading' (duration: 1.676101835s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.725949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:36.049763Z","time spent":"1.676182107s","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf67177159\" "} {"level":"warn","ts":"2026-02-18T14:52:37.726028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.941689614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:52:37.726043Z","caller":"traceutil/trace.go:171","msg":"trace[1096103189] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1978; }","duration":"1.941748726s","start":"2026-02-18T14:52:35.784289Z","end":"2026-02-18T14:52:37.726038Z","steps":["trace[1096103189] 'agreement among raft nodes before linearized reading' (duration: 1.941722555s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.72606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:35.784269Z","time spent":"1.941786647s","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:37.728899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"16.461611375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:4 size:1383"} {"level":"info","ts":"2026-02-18T14:52:37.728928Z","caller":"traceutil/trace.go:171","msg":"trace[2027541843] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:4; response_revision:1978; }","duration":"16.461670645s","start":"2026-02-18T14:52:21.26725Z","end":"2026-02-18T14:52:37.728921Z","steps":["trace[2027541843] 'agreement among raft nodes before linearized reading' (duration: 16.457392333s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.728949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:21.267233Z","time spent":"16.461710017s","remote":"127.0.0.1:59672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":4,"response size":1407,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"info","ts":"2026-02-18T14:52:37.86707Z","caller":"traceutil/trace.go:171","msg":"trace[788418559] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2064; }","duration":"137.661951ms","start":"2026-02-18T14:52:37.729398Z","end":"2026-02-18T14:52:37.86706Z","steps":["trace[788418559] 'read index received' (duration: 20.550429ms)","trace[788418559] 'applied index is now lower than readState.Index' (duration: 117.111002ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:37.867127Z","caller":"traceutil/trace.go:171","msg":"trace[1778389623] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"127.848947ms","start":"2026-02-18T14:52:37.739273Z","end":"2026-02-18T14:52:37.867122Z","steps":["trace[1778389623] 'process raft request' (duration: 127.739855ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:37.867243Z","caller":"traceutil/trace.go:171","msg":"trace[1903567597] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"125.670166ms","start":"2026-02-18T14:52:37.741567Z","end":"2026-02-18T14:52:37.867237Z","steps":["trace[1903567597] 'process raft request' (duration: 125.469761ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:37.867241Z","caller":"traceutil/trace.go:171","msg":"trace[1055831233] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"137.878196ms","start":"2026-02-18T14:52:37.72935Z","end":"2026-02-18T14:52:37.867228Z","steps":["trace[1055831233] 'process raft request' (duration: 115.318708ms)","trace[1055831233] 'compare' (duration: 22.23645ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:37.867349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.939937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:686"} {"level":"info","ts":"2026-02-18T14:52:37.867367Z","caller":"traceutil/trace.go:171","msg":"trace[1666439947] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:1982; }","duration":"137.964507ms","start":"2026-02-18T14:52:37.729396Z","end":"2026-02-18T14:52:37.867361Z","steps":["trace[1666439947] 'agreement among raft nodes before linearized reading' (duration: 137.903626ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:37.872156Z","caller":"traceutil/trace.go:171","msg":"trace[877793269] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"133.018281ms","start":"2026-02-18T14:52:37.739119Z","end":"2026-02-18T14:52:37.872138Z","steps":["trace[877793269] 'process raft request' (duration: 127.862088ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:37.896914Z","caller":"traceutil/trace.go:171","msg":"trace[1770976524] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"146.474202ms","start":"2026-02-18T14:52:37.750429Z","end":"2026-02-18T14:52:37.896903Z","steps":["trace[1770976524] 'process raft request' (duration: 146.40249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:37.897232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.858655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:37.897261Z","caller":"traceutil/trace.go:171","msg":"trace[1585096403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1983; }","duration":"155.913436ms","start":"2026-02-18T14:52:37.741341Z","end":"2026-02-18T14:52:37.897254Z","steps":["trace[1585096403] 'agreement among raft nodes before linearized reading' (duration: 155.702311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:38.186192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.688197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:52:38.186356Z","caller":"traceutil/trace.go:171","msg":"trace[915011716] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"228.717241ms","start":"2026-02-18T14:52:37.957629Z","end":"2026-02-18T14:52:38.186347Z","steps":["trace[915011716] 'process raft request' (duration: 228.67117ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:38.18639Z","caller":"traceutil/trace.go:171","msg":"trace[164744282] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2072; }","duration":"231.132739ms","start":"2026-02-18T14:52:37.955244Z","end":"2026-02-18T14:52:38.186377Z","steps":["trace[164744282] 'read index received' (duration: 97.215536ms)","trace[164744282] 'applied index is now lower than readState.Index' (duration: 133.916233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:38.186477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.550131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:38.186505Z","caller":"traceutil/trace.go:171","msg":"trace[1596409720] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1988; }","duration":"237.613983ms","start":"2026-02-18T14:52:37.948881Z","end":"2026-02-18T14:52:38.186495Z","steps":["trace[1596409720] 'agreement among raft nodes before linearized reading' (duration: 237.541741ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:38.186626Z","caller":"traceutil/trace.go:171","msg":"trace[1513420672] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"254.668269ms","start":"2026-02-18T14:52:37.931951Z","end":"2026-02-18T14:52:38.186619Z","steps":["trace[1513420672] 'process raft request' (duration: 120.499411ms)","trace[1513420672] 'compare' (duration: 133.570794ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:38.419358Z","caller":"traceutil/trace.go:171","msg":"trace[1872759492] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2076; }","duration":"224.582662ms","start":"2026-02-18T14:52:38.194764Z","end":"2026-02-18T14:52:38.419346Z","steps":["trace[1872759492] 'read index received' (duration: 37.099734ms)","trace[1872759492] 'applied index is now lower than readState.Index' (duration: 187.482568ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:38.419411Z","caller":"traceutil/trace.go:171","msg":"trace[12560713] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"225.976836ms","start":"2026-02-18T14:52:38.193397Z","end":"2026-02-18T14:52:38.419374Z","steps":["trace[12560713] 'process raft request' (duration: 211.381818ms)","trace[12560713] 'compare' (duration: 14.513266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:38.419495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.719615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-5d8ff44fc9-r8fqm\" ","response":"range_response_count:1 size:12538"} {"level":"info","ts":"2026-02-18T14:52:38.419514Z","caller":"traceutil/trace.go:171","msg":"trace[1325430790] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-5d8ff44fc9-r8fqm; range_end:; response_count:1; response_revision:1990; }","duration":"224.761796ms","start":"2026-02-18T14:52:38.194746Z","end":"2026-02-18T14:52:38.419508Z","steps":["trace[1325430790] 'agreement among raft nodes before linearized reading' (duration: 224.663634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:38.419627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.872406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-18T14:52:38.419639Z","caller":"traceutil/trace.go:171","msg":"trace[320019354] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1990; }","duration":"184.897076ms","start":"2026-02-18T14:52:38.234739Z","end":"2026-02-18T14:52:38.419636Z","steps":["trace[320019354] 'agreement among raft nodes before linearized reading' (duration: 184.872396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:38.419731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.733696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:38.419744Z","caller":"traceutil/trace.go:171","msg":"trace[656687797] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:1990; }","duration":"180.755537ms","start":"2026-02-18T14:52:38.238985Z","end":"2026-02-18T14:52:38.41974Z","steps":["trace[656687797] 'agreement among raft nodes before linearized reading' (duration: 180.734846ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:38.747573Z","caller":"traceutil/trace.go:171","msg":"trace[446840374] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"296.691661ms","start":"2026-02-18T14:52:38.450866Z","end":"2026-02-18T14:52:38.747557Z","steps":["trace[446840374] 'read index received' (duration: 284.48957ms)","trace[446840374] 'applied index is now lower than readState.Index' (duration: 12.201051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:38.752559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.659069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf9544f43c\" ","response":"range_response_count:1 size:773"} {"level":"info","ts":"2026-02-18T14:52:38.752659Z","caller":"traceutil/trace.go:171","msg":"trace[317975910] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18955edf9544f43c; range_end:; response_count:1; response_revision:1992; }","duration":"301.756302ms","start":"2026-02-18T14:52:38.450862Z","end":"2026-02-18T14:52:38.752618Z","steps":["trace[317975910] 'agreement among raft nodes before linearized reading' (duration: 301.519636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:38.752708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:38.450818Z","time spent":"301.879605ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf9544f43c\" "} {"level":"info","ts":"2026-02-18T14:52:38.75324Z","caller":"traceutil/trace.go:171","msg":"trace[1775187370] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"322.566897ms","start":"2026-02-18T14:52:38.430644Z","end":"2026-02-18T14:52:38.753211Z","steps":["trace[1775187370] 'process raft request' (duration: 304.783923ms)","trace[1775187370] 'compare' (duration: 12.042507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:38.753335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:38.430635Z","time spent":"322.634909ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13230,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:52:38.756768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.332194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360313"} {"level":"info","ts":"2026-02-18T14:52:38.756843Z","caller":"traceutil/trace.go:171","msg":"trace[1029731984] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1992; }","duration":"286.411516ms","start":"2026-02-18T14:52:38.470408Z","end":"2026-02-18T14:52:38.75682Z","steps":["trace[1029731984] 'agreement among raft nodes before linearized reading' (duration: 278.821845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:39.331439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.227443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:52:39.331518Z","caller":"traceutil/trace.go:171","msg":"trace[1275825598] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"542.275603ms","start":"2026-02-18T14:52:38.789227Z","end":"2026-02-18T14:52:39.331503Z","steps":["trace[1275825598] 'process raft request' (duration: 333.911758ms)","trace[1275825598] 'compare' (duration: 208.035638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:39.33156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:38.789213Z","time spent":"542.326934ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6956,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:52:39.345436Z","caller":"traceutil/trace.go:171","msg":"trace[2045210059] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2080; }","duration":"471.502886ms","start":"2026-02-18T14:52:38.87392Z","end":"2026-02-18T14:52:39.345423Z","steps":["trace[2045210059] 'read index received' (duration: 249.138208ms)","trace[2045210059] 'applied index is now lower than readState.Index' (duration: 222.363958ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:39.345457Z","caller":"traceutil/trace.go:171","msg":"trace[2084990743] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"555.042338ms","start":"2026-02-18T14:52:38.790395Z","end":"2026-02-18T14:52:39.345437Z","steps":["trace[2084990743] 'process raft request' (duration: 554.943606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:39.345566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:38.790388Z","time spent":"555.114029ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:52:39.345698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.767102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360313"} {"level":"info","ts":"2026-02-18T14:52:39.345736Z","caller":"traceutil/trace.go:171","msg":"trace[1138480342] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1995; }","duration":"471.833104ms","start":"2026-02-18T14:52:38.873882Z","end":"2026-02-18T14:52:39.345715Z","steps":["trace[1138480342] 'agreement among raft nodes before linearized reading' (duration: 471.613959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:39.345759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:38.873864Z","time spent":"471.889735ms","remote":"127.0.0.1:59658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":6,"response size":360337,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-18T14:52:39.345814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.455855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:52:39.34584Z","caller":"traceutil/trace.go:171","msg":"trace[2002476542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1995; }","duration":"309.510766ms","start":"2026-02-18T14:52:39.036322Z","end":"2026-02-18T14:52:39.345833Z","steps":["trace[2002476542] 'agreement among raft nodes before linearized reading' (duration: 309.468355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:39.345857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:39.036307Z","time spent":"309.545867ms","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:39.346038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.782522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:52:39.346059Z","caller":"traceutil/trace.go:171","msg":"trace[1081309256] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1995; }","duration":"254.830593ms","start":"2026-02-18T14:52:39.091223Z","end":"2026-02-18T14:52:39.346053Z","steps":["trace[1081309256] 'agreement among raft nodes before linearized reading' (duration: 254.793293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:39.833354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.497602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:52:39.83344Z","caller":"traceutil/trace.go:171","msg":"trace[2032712960] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"467.680176ms","start":"2026-02-18T14:52:39.365745Z","end":"2026-02-18T14:52:39.833425Z","steps":["trace[2032712960] 'read index received' (duration: 226.967199ms)","trace[2032712960] 'applied index is now lower than readState.Index' (duration: 240.711907ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:39.833654Z","caller":"traceutil/trace.go:171","msg":"trace[1410530260] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"468.087996ms","start":"2026-02-18T14:52:39.365551Z","end":"2026-02-18T14:52:39.833639Z","steps":["trace[1410530260] 'process raft request' (duration: 227.215835ms)","trace[1410530260] 'compare' (duration: 240.365448ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:52:39.833716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:39.365535Z","time spent":"468.146647ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4861,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:52:39.833989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.235399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-7fcd8d6ffd-sm4kf.18955ee1ef5e6e07\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-18T14:52:39.834021Z","caller":"traceutil/trace.go:171","msg":"trace[1752011119] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-7fcd8d6ffd-sm4kf.18955ee1ef5e6e07; range_end:; response_count:1; response_revision:1996; }","duration":"468.28164ms","start":"2026-02-18T14:52:39.365728Z","end":"2026-02-18T14:52:39.83401Z","steps":["trace[1752011119] 'agreement among raft nodes before linearized reading' (duration: 468.132656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:39.83405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:39.365722Z","time spent":"468.320842ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":885,"request content":"key:\"/registry/events/kube-system/cilium-operator-7fcd8d6ffd-sm4kf.18955ee1ef5e6e07\" "} {"level":"warn","ts":"2026-02-18T14:52:39.834462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.825791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360313"} {"level":"info","ts":"2026-02-18T14:52:39.834529Z","caller":"traceutil/trace.go:171","msg":"trace[1438314847] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1996; }","duration":"423.927123ms","start":"2026-02-18T14:52:39.410585Z","end":"2026-02-18T14:52:39.834512Z","steps":["trace[1438314847] 'agreement among raft nodes before linearized reading' (duration: 423.620055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:39.834575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:39.410573Z","time spent":"423.992665ms","remote":"127.0.0.1:59658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":6,"response size":360337,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-18T14:52:40.023695Z","caller":"traceutil/trace.go:171","msg":"trace[468772377] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"174.606061ms","start":"2026-02-18T14:52:39.849062Z","end":"2026-02-18T14:52:40.023668Z","steps":["trace[468772377] 'process raft request' (duration: 160.003332ms)","trace[468772377] 'compare' (duration: 14.414554ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:40.040191Z","caller":"traceutil/trace.go:171","msg":"trace[1409172858] linearizableReadLoop","detail":"{readStateIndex:2086; appliedIndex:2084; }","duration":"150.488696ms","start":"2026-02-18T14:52:39.889691Z","end":"2026-02-18T14:52:40.04018Z","steps":["trace[1409172858] 'read index received' (duration: 119.446476ms)","trace[1409172858] 'applied index is now lower than readState.Index' (duration: 31.04166ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:40.040244Z","caller":"traceutil/trace.go:171","msg":"trace[297709275] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"153.803895ms","start":"2026-02-18T14:52:39.886435Z","end":"2026-02-18T14:52:40.040239Z","steps":["trace[297709275] 'process raft request' (duration: 153.693833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:40.040547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.844344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360313"} {"level":"info","ts":"2026-02-18T14:52:40.040566Z","caller":"traceutil/trace.go:171","msg":"trace[126072454] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1999; }","duration":"150.894586ms","start":"2026-02-18T14:52:39.889665Z","end":"2026-02-18T14:52:40.040559Z","steps":["trace[126072454] 'agreement among raft nodes before linearized reading' (duration: 150.672261ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:52:40.253828Z","caller":"traceutil/trace.go:171","msg":"trace[504117643] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"199.036933ms","start":"2026-02-18T14:52:40.054765Z","end":"2026-02-18T14:52:40.253802Z","steps":["trace[504117643] 'process raft request' (duration: 105.961205ms)","trace[504117643] 'compare' (duration: 92.928445ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:40.270447Z","caller":"traceutil/trace.go:171","msg":"trace[587825907] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"106.875057ms","start":"2026-02-18T14:52:40.163561Z","end":"2026-02-18T14:52:40.270436Z","steps":["trace[587825907] 'read index received' (duration: 106.800825ms)","trace[587825907] 'applied index is now lower than readState.Index' (duration: 73.672µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:52:40.270498Z","caller":"traceutil/trace.go:171","msg":"trace[218220787] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"171.348974ms","start":"2026-02-18T14:52:40.099143Z","end":"2026-02-18T14:52:40.270492Z","steps":["trace[218220787] 'process raft request' (duration: 171.249632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:40.270935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.360259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360313"} {"level":"info","ts":"2026-02-18T14:52:40.270961Z","caller":"traceutil/trace.go:171","msg":"trace[305241522] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2002; }","duration":"107.42403ms","start":"2026-02-18T14:52:40.163529Z","end":"2026-02-18T14:52:40.270953Z","steps":["trace[305241522] 'agreement among raft nodes before linearized reading' (duration: 107.078762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:46.285298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:46.785716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:47.286339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:47.787374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:48.034123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000145198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:48.034345Z","caller":"traceutil/trace.go:171","msg":"trace[1997308930] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000337553s","start":"2026-02-18T14:52:46.03392Z","end":"2026-02-18T14:52:48.034258Z","steps":["trace[1997308930] 'agreement among raft nodes before linearized reading' (duration: 2.000169159s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:48.034573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:46.03391Z","time spent":"2.00061768s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:48 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:48.287792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:48.788853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:49.289958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:49.790732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:50.034252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999637925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:50.03435Z","caller":"traceutil/trace.go:171","msg":"trace[1279907361] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99981568s","start":"2026-02-18T14:52:48.034512Z","end":"2026-02-18T14:52:50.034328Z","steps":["trace[1279907361] 'agreement among raft nodes before linearized reading' (duration: 1.999685107s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:50.034436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:48.034417Z","time spent":"2.000008644s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:50.291116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:50.791946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:51.292269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:51.792723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:51.918947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999997502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:51.919029Z","caller":"traceutil/trace.go:171","msg":"trace[1855530814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000118185s","start":"2026-02-18T14:52:46.918888Z","end":"2026-02-18T14:52:51.919007Z","steps":["trace[1855530814] 'agreement among raft nodes before linearized reading' (duration: 5.000021912s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:51.919077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:46.918876Z","time spent":"5.000191746s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:52:52.044549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000280552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:52.044627Z","caller":"traceutil/trace.go:171","msg":"trace[762013369] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000389194s","start":"2026-02-18T14:52:50.044218Z","end":"2026-02-18T14:52:52.044607Z","steps":["trace[762013369] 'agreement among raft nodes before linearized reading' (duration: 2.000288051s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:52.044674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:50.044211Z","time spent":"2.000454155s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:52.293152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:52.468654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999349106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:52.468712Z","caller":"traceutil/trace.go:171","msg":"trace[385159714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999459878s","start":"2026-02-18T14:52:47.469236Z","end":"2026-02-18T14:52:52.468696Z","steps":["trace[385159714] 'agreement among raft nodes before linearized reading' (duration: 4.999387567s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:52.468746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.469226Z","time spent":"4.99951226s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:52:52.654576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999867349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:52.654647Z","caller":"traceutil/trace.go:171","msg":"trace[513376417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999980381s","start":"2026-02-18T14:52:47.654648Z","end":"2026-02-18T14:52:52.654628Z","steps":["trace[513376417] 'agreement among raft nodes before linearized reading' (duration: 4.999876439s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:52.65469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.654638Z","time spent":"5.000042902s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:52:52.78209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:45.781759Z","time spent":"7.000327374s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:52.785617Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-18T14:52:52.785955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.00095667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:52.78613Z","caller":"traceutil/trace.go:171","msg":"trace[1047415387] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; }","duration":"7.001138753s","start":"2026-02-18T14:52:45.784939Z","end":"2026-02-18T14:52:52.786078Z","steps":["trace[1047415387] 'agreement among raft nodes before linearized reading' (duration: 7.0009811s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:52.786346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:45.784925Z","time spent":"7.00140269s","remote":"127.0.0.1:59910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":0,"request content":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-18T14:52:53.286582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:53.787623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:54.044683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999876592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:54.044791Z","caller":"traceutil/trace.go:171","msg":"trace[555538508] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000051396s","start":"2026-02-18T14:52:52.044717Z","end":"2026-02-18T14:52:54.044769Z","steps":["trace[555538508] 'agreement among raft nodes before linearized reading' (duration: 1.999908532s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:54.044846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:52.0447Z","time spent":"2.000134778s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:54.28885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:54.789338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:54.813787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000188018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:54.813848Z","caller":"traceutil/trace.go:171","msg":"trace[1433693936] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00028679s","start":"2026-02-18T14:52:52.813544Z","end":"2026-02-18T14:52:54.813831Z","steps":["trace[1433693936] 'agreement among raft nodes before linearized reading' (duration: 2.000216689s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:54.813888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:52.813529Z","time spent":"2.000346002s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:52:54 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:54.958264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.95733Z","time spent":"7.000930249s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:55.082926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:48.08198Z","time spent":"7.000940118s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:52:55.289877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:55.790025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} 2026/02/18 14:52:55 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:55.901522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.001302704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:55.901626Z","caller":"traceutil/trace.go:171","msg":"trace[2016248455] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; }","duration":"10.001455678s","start":"2026-02-18T14:52:45.900147Z","end":"2026-02-18T14:52:55.901603Z","steps":["trace[2016248455] 'agreement among raft nodes before linearized reading' (duration: 10.001336835s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:55.901705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:45.900108Z","time spent":"10.001582711s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} 2026/02/18 14:52:55 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:56.052327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000127878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:56.052432Z","caller":"traceutil/trace.go:171","msg":"trace[619202292] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000284922s","start":"2026-02-18T14:52:54.052125Z","end":"2026-02-18T14:52:56.05241Z","steps":["trace[619202292] 'agreement among raft nodes before linearized reading' (duration: 2.000151849s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:56.052482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:54.052107Z","time spent":"2.000365104s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:56.290643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:56.791496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:56.919626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998366918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:56.919712Z","caller":"traceutil/trace.go:171","msg":"trace[451967984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.998492381s","start":"2026-02-18T14:52:53.9212Z","end":"2026-02-18T14:52:56.919692Z","steps":["trace[451967984] 'agreement among raft nodes before linearized reading' (duration: 2.998392519s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:56.91976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:53.921184Z","time spent":"2.998560962s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/18 14:52:56 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:57.292485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:57.367789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000248028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:57.367849Z","caller":"traceutil/trace.go:171","msg":"trace[605478010] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; }","duration":"10.000369401s","start":"2026-02-18T14:52:47.367461Z","end":"2026-02-18T14:52:57.367831Z","steps":["trace[605478010] 'agreement among raft nodes before linearized reading' (duration: 10.000297049s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:57.368034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.367446Z","time spent":"10.000573126s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} 2026/02/18 14:52:57 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:57.470155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998292745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:57.470265Z","caller":"traceutil/trace.go:171","msg":"trace[1768195663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.99847615s","start":"2026-02-18T14:52:54.471762Z","end":"2026-02-18T14:52:57.470238Z","steps":["trace[1768195663] 'agreement among raft nodes before linearized reading' (duration: 2.998343457s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:57.470313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:54.471745Z","time spent":"2.998558471s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/18 14:52:57 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:57.61312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999952631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:57.613172Z","caller":"traceutil/trace.go:171","msg":"trace[713658045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.000101274s","start":"2026-02-18T14:52:47.613059Z","end":"2026-02-18T14:52:57.61316Z","steps":["trace[713658045] 'agreement among raft nodes before linearized reading' (duration: 9.999977381s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:57.613335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.613048Z","time spent":"10.000275019s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:52:57.693576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000680049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:57.693637Z","caller":"traceutil/trace.go:171","msg":"trace[1810687410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.0007679s","start":"2026-02-18T14:52:47.692855Z","end":"2026-02-18T14:52:57.693623Z","steps":["trace[1810687410] 'agreement among raft nodes before linearized reading' (duration: 10.000696529s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:57.693893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.692845Z","time spent":"10.000962894s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/18 14:52:57 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:57.793905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:57.847749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999374046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:52:57.8478Z","caller":"traceutil/trace.go:171","msg":"trace[1003741380] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; }","duration":"9.999462789s","start":"2026-02-18T14:52:47.848323Z","end":"2026-02-18T14:52:57.847786Z","steps":["trace[1003741380] 'agreement among raft nodes before linearized reading' (duration: 9.999401407s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:57.847891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.848312Z","time spent":"9.999569191s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} 2026/02/18 14:52:57 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:52:58.05403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000420515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:52:58.054116Z","caller":"traceutil/trace.go:171","msg":"trace[2055860040] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000566378s","start":"2026-02-18T14:52:56.05353Z","end":"2026-02-18T14:52:58.054097Z","steps":["trace[2055860040] 'agreement among raft nodes before linearized reading' (duration: 2.000454395s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:58.054181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:56.053514Z","time spent":"2.00065757s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:52:58.294385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:58.794825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:59.295137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:52:59.786301Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-18T14:52:59.786445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.05143609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.786503Z","caller":"traceutil/trace.go:171","msg":"trace[106265198] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; }","duration":"7.051558153s","start":"2026-02-18T14:52:52.734928Z","end":"2026-02-18T14:52:59.786487Z","steps":["trace[106265198] 'agreement among raft nodes before linearized reading' (duration: 7.051473511s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.786546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:52.734906Z","time spent":"7.051628194s","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:59.786606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.666589871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.786628Z","caller":"traceutil/trace.go:171","msg":"trace[2064045493] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; }","duration":"7.666668894s","start":"2026-02-18T14:52:52.119953Z","end":"2026-02-18T14:52:59.786622Z","steps":["trace[2064045493] 'agreement among raft nodes before linearized reading' (duration: 7.666647153s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.786658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:52.119934Z","time spent":"7.666718214s","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":0,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-18T14:52:59.786709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.096976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.786735Z","caller":"traceutil/trace.go:171","msg":"trace[1984564374] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; }","duration":"13.097038371s","start":"2026-02-18T14:52:46.689691Z","end":"2026-02-18T14:52:59.786729Z","steps":["trace[1984564374] 'agreement among raft nodes before linearized reading' (duration: 13.097010131s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.78677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:46.68966Z","time spent":"13.097098223s","remote":"127.0.0.1:59734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":0,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:59.786806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.922814851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.786883Z","caller":"traceutil/trace.go:171","msg":"trace[1859841413] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; }","duration":"13.922899753s","start":"2026-02-18T14:52:45.863975Z","end":"2026-02-18T14:52:59.786875Z","steps":["trace[1859841413] 'agreement among raft nodes before linearized reading' (duration: 13.922825151s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.786921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:45.86397Z","time spent":"13.922941804s","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":0,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T14:52:59.786974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.965687753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.787015Z","caller":"traceutil/trace.go:171","msg":"trace[975845580] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; }","duration":"13.965741175s","start":"2026-02-18T14:52:45.821253Z","end":"2026-02-18T14:52:59.786995Z","steps":["trace[975845580] 'agreement among raft nodes before linearized reading' (duration: 13.965711874s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.787044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:45.821243Z","time spent":"13.965795416s","remote":"127.0.0.1:59910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":0,"request content":"key:\"/registry/clusterroles/system:controller:certificate-controller\" "} {"level":"warn","ts":"2026-02-18T14:52:59.786947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.344503707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.787233Z","caller":"traceutil/trace.go:171","msg":"trace[662178468] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; }","duration":"12.34505802s","start":"2026-02-18T14:52:47.442156Z","end":"2026-02-18T14:52:59.787214Z","steps":["trace[662178468] 'agreement among raft nodes before linearized reading' (duration: 12.344520787s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.787335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.442145Z","time spent":"12.345172832s","remote":"127.0.0.1:59852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":0,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:59.787448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.578025225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.787534Z","caller":"traceutil/trace.go:171","msg":"trace[202868660] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; }","duration":"9.578113107s","start":"2026-02-18T14:52:50.20941Z","end":"2026-02-18T14:52:59.787523Z","steps":["trace[202868660] 'agreement among raft nodes before linearized reading' (duration: 9.578025425s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.788152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:50.209331Z","time spent":"9.578802253s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:59.790357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.749587505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf67177159\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.793795Z","caller":"traceutil/trace.go:171","msg":"trace[722269574] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18955edf67177159; range_end:; }","duration":"9.752956824s","start":"2026-02-18T14:52:50.04071Z","end":"2026-02-18T14:52:59.793666Z","steps":["trace[722269574] 'agreement among raft nodes before linearized reading' (duration: 9.749626915s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.793971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:50.040687Z","time spent":"9.753232501s","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":0,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf67177159\" "} {"level":"warn","ts":"2026-02-18T14:52:59.790398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.862609502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.794098Z","caller":"traceutil/trace.go:171","msg":"trace[156905341] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; }","duration":"11.8663452s","start":"2026-02-18T14:52:47.927742Z","end":"2026-02-18T14:52:59.794087Z","steps":["trace[156905341] 'agreement among raft nodes before linearized reading' (duration: 11.862648593s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.794879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.927723Z","time spent":"11.86713854s","remote":"127.0.0.1:59602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-02-18T14:52:59.790422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.969060929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-18T14:52:59.795218Z","caller":"traceutil/trace.go:171","msg":"trace[945431273] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; }","duration":"11.973877203s","start":"2026-02-18T14:52:47.821328Z","end":"2026-02-18T14:52:59.795205Z","steps":["trace[945431273] 'agreement among raft nodes before linearized reading' (duration: 11.969084539s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:52:59.795314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:47.821316Z","time spent":"11.973982797s","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":0,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:52:59.976268Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38c19c7137df5b70","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-18T14:53:00.062781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000240629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:53:00.062866Z","caller":"traceutil/trace.go:171","msg":"trace[43180730] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000334142s","start":"2026-02-18T14:52:58.062491Z","end":"2026-02-18T14:53:00.062826Z","steps":["trace[43180730] 'agreement among raft nodes before linearized reading' (duration: 2.00025953s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:00.062915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:58.062478Z","time spent":"2.000424164s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:53:00.287124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:53:00.477259Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38c19c7137df5b84","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-18T14:53:00.707102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000125664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:53:00.707181Z","caller":"traceutil/trace.go:171","msg":"trace[1938180783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000221656s","start":"2026-02-18T14:52:55.70694Z","end":"2026-02-18T14:53:00.707162Z","steps":["trace[1938180783] 'agreement among raft nodes before linearized reading' (duration: 5.000122014s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:00.707252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:55.706898Z","time spent":"5.000323688s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/18 14:53:00 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:53:00.787688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:53:01.288007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:53:01.789191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:53:01.920554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:56.922049Z","time spent":"4.998493845s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/18 14:53:01 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:53:01.961203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:54.961106Z","time spent":"7.000090766s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:53:02.064483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00068428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:53:02.064559Z","caller":"traceutil/trace.go:171","msg":"trace[1782846033] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000801283s","start":"2026-02-18T14:53:00.063742Z","end":"2026-02-18T14:53:02.064544Z","steps":["trace[1782846033] 'agreement among raft nodes before linearized reading' (duration: 2.000710631s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:02.064605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:00.063704Z","time spent":"2.000893376s","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:53:02.08888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:55.087946Z","time spent":"7.000922736s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-18T14:53:02.289533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093983566519444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:53:02.475071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:57.473371Z","time spent":"5.001692712s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/18 14:53:02 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:53:02.696246Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"16.914380117s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:53:03.013357Z","caller":"traceutil/trace.go:171","msg":"trace[1319344470] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"5.396336826s","start":"2026-02-18T14:52:57.617Z","end":"2026-02-18T14:53:03.013337Z","steps":["trace[1319344470] 'process raft request' (duration: 5.39608885s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.013493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:57.616985Z","time spent":"5.396446638s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:53:03.013762Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"27.631µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"warn","ts":"2026-02-18T14:53:03.013797Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"4.59µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"info","ts":"2026-02-18T14:53:03.013891Z","caller":"traceutil/trace.go:171","msg":"trace[248850794] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2135; }","duration":"3.227503018s","start":"2026-02-18T14:52:59.786377Z","end":"2026-02-18T14:53:03.01388Z","steps":["trace[248850794] 'read index received' (duration: 2.910340789s)","trace[248850794] 'applied index is now lower than readState.Index' (duration: 317.161459ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:53:03.013901Z","caller":"traceutil/trace.go:171","msg":"trace[259987808] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"5.315845728s","start":"2026-02-18T14:52:57.698032Z","end":"2026-02-18T14:53:03.013877Z","steps":["trace[259987808] 'process raft request' (duration: 5.315247263s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.013954Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38c19c7137df5b70","error":"lease not found"} {"level":"warn","ts":"2026-02-18T14:53:03.013972Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38c19c7137df5b84","error":"lease not found"} {"level":"info","ts":"2026-02-18T14:53:03.013995Z","caller":"traceutil/trace.go:171","msg":"trace[784181946] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2055; }","duration":"1.050162327s","start":"2026-02-18T14:53:01.963824Z","end":"2026-02-18T14:53:03.013987Z","steps":["trace[784181946] 'process raft request' (duration: 1.049995103s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.014025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:57.698014Z","time spent":"5.31594344s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:53:03.014045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:01.96381Z","time spent":"1.050203789s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":722,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:53:03.014194Z","caller":"traceutil/trace.go:171","msg":"trace[1147196853] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2055; }","duration":"920.61303ms","start":"2026-02-18T14:53:02.093572Z","end":"2026-02-18T14:53:03.014185Z","steps":["trace[1147196853] 'process raft request' (duration: 920.280452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.014241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:02.093558Z","time spent":"920.657151ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:53:03.01427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.158683762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-02-18T14:53:03.014307Z","caller":"traceutil/trace.go:171","msg":"trace[1447831844] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:2055; }","duration":"10.158738503s","start":"2026-02-18T14:52:52.855556Z","end":"2026-02-18T14:53:03.014294Z","steps":["trace[1447831844] 'agreement among raft nodes before linearized reading' (duration: 10.158675301s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.014338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:52.855545Z","time spent":"10.158784425s","remote":"127.0.0.1:59910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":724,"request content":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-18T14:53:03.014472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.219990599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"warn","ts":"2026-02-18T14:53:03.014498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.245875436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:53:03.014521Z","caller":"traceutil/trace.go:171","msg":"trace[2012384916] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2055; }","duration":"3.220085132s","start":"2026-02-18T14:52:59.794425Z","end":"2026-02-18T14:53:03.014495Z","steps":["trace[2012384916] 'agreement among raft nodes before linearized reading' (duration: 3.219925128s)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:53:03.014527Z","caller":"traceutil/trace.go:171","msg":"trace[1709865708] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2055; }","duration":"3.245948008s","start":"2026-02-18T14:52:59.76857Z","end":"2026-02-18T14:53:03.014518Z","steps":["trace[1709865708] 'agreement among raft nodes before linearized reading' (duration: 3.245894147s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.014553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:59.794416Z","time spent":"3.220128043s","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7060,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-18T14:53:03.014558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:59.768552Z","time spent":"3.24599909s","remote":"127.0.0.1:59658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:53:03.015012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.653461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-18T14:53:03.015044Z","caller":"traceutil/trace.go:171","msg":"trace[1795741489] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2055; }","duration":"372.716373ms","start":"2026-02-18T14:53:02.642318Z","end":"2026-02-18T14:53:03.015034Z","steps":["trace[1795741489] 'agreement among raft nodes before linearized reading' (duration: 372.634921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.015069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:02.642305Z","time spent":"372.758263ms","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T14:53:03.015221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.499501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:53:03.015245Z","caller":"traceutil/trace.go:171","msg":"trace[596721946] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2055; }","duration":"939.533762ms","start":"2026-02-18T14:53:02.075704Z","end":"2026-02-18T14:53:03.015238Z","steps":["trace[596721946] 'agreement among raft nodes before linearized reading' (duration: 939.46684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.01527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:02.0757Z","time spent":"939.564302ms","remote":"127.0.0.1:59910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":69,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:53:03.015393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.208195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:53:03.01542Z","caller":"traceutil/trace.go:171","msg":"trace[146510915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2055; }","duration":"942.255446ms","start":"2026-02-18T14:53:02.073153Z","end":"2026-02-18T14:53:03.015409Z","steps":["trace[146510915] 'agreement among raft nodes before linearized reading' (duration: 942.222675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.015447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:02.073142Z","time spent":"942.298618ms","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:53:03.015578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.154764626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-18T14:53:03.015603Z","caller":"traceutil/trace.go:171","msg":"trace[973584081] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:1; response_revision:2055; }","duration":"3.154815097s","start":"2026-02-18T14:52:59.86078Z","end":"2026-02-18T14:53:03.015595Z","steps":["trace[973584081] 'agreement among raft nodes before linearized reading' (duration: 3.154769456s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.015629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:59.860764Z","time spent":"3.154859347s","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":787,"request content":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" "} {"level":"warn","ts":"2026-02-18T14:53:03.015851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.213354151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf67177159\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T14:53:03.015878Z","caller":"traceutil/trace.go:171","msg":"trace[319222860] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18955edf67177159; range_end:; response_count:1; response_revision:2055; }","duration":"3.213393842s","start":"2026-02-18T14:52:59.802476Z","end":"2026-02-18T14:53:03.01587Z","steps":["trace[319222860] 'agreement among raft nodes before linearized reading' (duration: 3.21330283s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.015903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:59.802471Z","time spent":"3.213425313s","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf67177159\" "} {"level":"warn","ts":"2026-02-18T14:53:03.016018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.216201878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:53:03.01604Z","caller":"traceutil/trace.go:171","msg":"trace[260799282] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:0; response_revision:2055; }","duration":"3.21625596s","start":"2026-02-18T14:52:59.799777Z","end":"2026-02-18T14:53:03.016033Z","steps":["trace[260799282] 'agreement among raft nodes before linearized reading' (duration: 3.216222599s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.016063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:59.799767Z","time spent":"3.21629117s","remote":"127.0.0.1:59602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-02-18T14:53:03.017233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.554910709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-02-18T14:53:03.017264Z","caller":"traceutil/trace.go:171","msg":"trace[1400600681] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2055; }","duration":"7.55497823s","start":"2026-02-18T14:52:55.462277Z","end":"2026-02-18T14:53:03.017255Z","steps":["trace[1400600681] 'agreement among raft nodes before linearized reading' (duration: 7.554918349s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.01729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:55.462257Z","time spent":"7.555026702s","remote":"127.0.0.1:59630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-02-18T14:53:03.016141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.541619332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:105492"} {"level":"info","ts":"2026-02-18T14:53:03.018762Z","caller":"traceutil/trace.go:171","msg":"trace[773868084] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2055; }","duration":"7.544259516s","start":"2026-02-18T14:52:55.474487Z","end":"2026-02-18T14:53:03.018746Z","steps":["trace[773868084] 'agreement among raft nodes before linearized reading' (duration: 7.540263631s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.018815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:52:55.474477Z","time spent":"7.544323277s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":105516,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-02-18T14:53:03.265903Z","caller":"traceutil/trace.go:171","msg":"trace[1098190223] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2151; }","duration":"239.946969ms","start":"2026-02-18T14:53:03.025935Z","end":"2026-02-18T14:53:03.265882Z","steps":["trace[1098190223] 'read index received' (duration: 25.277813ms)","trace[1098190223] 'applied index is now lower than readState.Index' (duration: 214.668236ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:53:03.266259Z","caller":"traceutil/trace.go:171","msg":"trace[2097871145] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"231.588129ms","start":"2026-02-18T14:53:03.034659Z","end":"2026-02-18T14:53:03.266247Z","steps":["trace[2097871145] 'process raft request' (duration: 231.113858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.266533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.560483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-18T14:53:03.266606Z","caller":"traceutil/trace.go:171","msg":"trace[2067053971] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:2056; }","duration":"240.665926ms","start":"2026-02-18T14:53:03.025922Z","end":"2026-02-18T14:53:03.266588Z","steps":["trace[2067053971] 'agreement among raft nodes before linearized reading' (duration: 240.44613ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:53:03.476175Z","caller":"traceutil/trace.go:171","msg":"trace[893369120] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"423.951893ms","start":"2026-02-18T14:53:03.052201Z","end":"2026-02-18T14:53:03.476152Z","steps":["trace[893369120] 'process raft request' (duration: 340.407483ms)","trace[893369120] 'compare' (duration: 83.463939ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:53:03.476223Z","caller":"traceutil/trace.go:171","msg":"trace[1427216003] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"203.234353ms","start":"2026-02-18T14:53:03.272973Z","end":"2026-02-18T14:53:03.476207Z","steps":["trace[1427216003] 'process raft request' (duration: 203.208172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.476272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:03.05219Z","time spent":"424.029616ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:53:03.476473Z","caller":"traceutil/trace.go:171","msg":"trace[1379263157] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"207.359922ms","start":"2026-02-18T14:53:03.269108Z","end":"2026-02-18T14:53:03.476468Z","steps":["trace[1379263157] 'process raft request' (duration: 207.051735ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:53:03.476546Z","caller":"traceutil/trace.go:171","msg":"trace[1566024551] linearizableReadLoop","detail":"{readStateIndex:2154; appliedIndex:2153; }","duration":"210.580129ms","start":"2026-02-18T14:53:03.265961Z","end":"2026-02-18T14:53:03.476541Z","steps":["trace[1566024551] 'read index received' (duration: 126.656759ms)","trace[1566024551] 'applied index is now lower than readState.Index' (duration: 83.92273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:53:03.476652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.797099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-18T14:53:03.476668Z","caller":"traceutil/trace.go:171","msg":"trace[1502236037] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:1; response_revision:2059; }","duration":"363.844281ms","start":"2026-02-18T14:53:03.112819Z","end":"2026-02-18T14:53:03.476664Z","steps":["trace[1502236037] 'agreement among raft nodes before linearized reading' (duration: 363.771769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.476681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:03.112807Z","time spent":"363.870921ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":787,"request content":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" "} {"level":"warn","ts":"2026-02-18T14:53:03.476812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.599832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-02-18T14:53:03.476825Z","caller":"traceutil/trace.go:171","msg":"trace[1222087270] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2059; }","duration":"443.621163ms","start":"2026-02-18T14:53:03.033199Z","end":"2026-02-18T14:53:03.47682Z","steps":["trace[1222087270] 'agreement among raft nodes before linearized reading' (duration: 443.561501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.476839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:03.033195Z","time spent":"443.641913ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T14:53:03.476904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.25508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:687"} {"level":"info","ts":"2026-02-18T14:53:03.476928Z","caller":"traceutil/trace.go:171","msg":"trace[1175339445] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:2059; }","duration":"447.286ms","start":"2026-02-18T14:53:03.029638Z","end":"2026-02-18T14:53:03.476924Z","steps":["trace[1175339445] 'agreement among raft nodes before linearized reading' (duration: 447.230759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.476941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:03.029635Z","time spent":"447.3012ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":711,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-18T14:53:03.477007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.448733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:4 size:4276"} {"level":"info","ts":"2026-02-18T14:53:03.477018Z","caller":"traceutil/trace.go:171","msg":"trace[1947154816] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:4; response_revision:2059; }","duration":"447.464093ms","start":"2026-02-18T14:53:03.02955Z","end":"2026-02-18T14:53:03.477014Z","steps":["trace[1947154816] 'agreement among raft nodes before linearized reading' (duration: 447.420282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.47703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:03.029548Z","time spent":"447.477645ms","remote":"127.0.0.1:59772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":4,"response size":4300,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-02-18T14:53:03.477114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.566287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-02-18T14:53:03.477126Z","caller":"traceutil/trace.go:171","msg":"trace[1603612645] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:2059; }","duration":"447.585357ms","start":"2026-02-18T14:53:03.029537Z","end":"2026-02-18T14:53:03.477122Z","steps":["trace[1603612645] 'agreement among raft nodes before linearized reading' (duration: 447.560756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.477139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:03.029535Z","time spent":"447.601967ms","remote":"127.0.0.1:59910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":724,"request content":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-18T14:53:03.477224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.695939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:4 size:4276"} {"level":"info","ts":"2026-02-18T14:53:03.477235Z","caller":"traceutil/trace.go:171","msg":"trace[903362149] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:4; response_revision:2059; }","duration":"447.71704ms","start":"2026-02-18T14:53:03.029514Z","end":"2026-02-18T14:53:03.477231Z","steps":["trace[903362149] 'agreement among raft nodes before linearized reading' (duration: 447.677359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.477246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:03.029508Z","time spent":"447.735511ms","remote":"127.0.0.1:59772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":4,"response size":4300,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-02-18T14:53:03.477307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.333486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:53:03.477329Z","caller":"traceutil/trace.go:171","msg":"trace[1468566215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2059; }","duration":"451.370968ms","start":"2026-02-18T14:53:03.025952Z","end":"2026-02-18T14:53:03.477323Z","steps":["trace[1468566215] 'agreement among raft nodes before linearized reading' (duration: 451.329967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.477347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:53:03.02595Z","time spent":"451.391228ms","remote":"127.0.0.1:59586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:53:03.477464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.619267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:7008"} {"level":"info","ts":"2026-02-18T14:53:03.477478Z","caller":"traceutil/trace.go:171","msg":"trace[1141930403] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:2059; }","duration":"204.647847ms","start":"2026-02-18T14:53:03.272824Z","end":"2026-02-18T14:53:03.477472Z","steps":["trace[1141930403] 'agreement among raft nodes before linearized reading' (duration: 204.588766ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:53:03.597236Z","caller":"traceutil/trace.go:171","msg":"trace[606627755] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"102.008652ms","start":"2026-02-18T14:53:03.495201Z","end":"2026-02-18T14:53:03.59721Z","steps":["trace[606627755] 'process raft request' (duration: 82.906617ms)","trace[606627755] 'compare' (duration: 18.943031ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:53:03.597724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.551915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-18T14:53:03.597763Z","caller":"traceutil/trace.go:171","msg":"trace[998708035] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2062; }","duration":"102.596636ms","start":"2026-02-18T14:53:03.495157Z","end":"2026-02-18T14:53:03.597754Z","steps":["trace[998708035] 'agreement among raft nodes before linearized reading' (duration: 102.533685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.597885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.649617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955edf9544f43c\" ","response":"range_response_count:1 size:773"} {"level":"info","ts":"2026-02-18T14:53:03.597907Z","caller":"traceutil/trace.go:171","msg":"trace[232337134] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18955edf9544f43c; range_end:; response_count:1; response_revision:2062; }","duration":"102.673158ms","start":"2026-02-18T14:53:03.495221Z","end":"2026-02-18T14:53:03.597894Z","steps":["trace[232337134] 'agreement among raft nodes before linearized reading' (duration: 102.609556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:53:03.597971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.776249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:105492"} {"level":"info","ts":"2026-02-18T14:53:03.597994Z","caller":"traceutil/trace.go:171","msg":"trace[1108771222] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2062; }","duration":"102.81952ms","start":"2026-02-18T14:53:03.495167Z","end":"2026-02-18T14:53:03.597986Z","steps":["trace[1108771222] 'agreement among raft nodes before linearized reading' (duration: 102.732689ms)"],"step_count":1}