{"level":"warn","ts":"2026-02-25T19:31:06.645362Z","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-25T19:31:06.645474Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201: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.19.213.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201: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-25T19:31:06.645556Z","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-25T19:31:06.645572Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-02-25T19:31:06.645599Z","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-25T19:31:06.646258Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-02-25T19:31:06.646335Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201: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-25T19:31:06.650094Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.554708ms"} {"level":"info","ts":"2026-02-25T19:31:06.657596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-02-25T19:31:06.657894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:31:06.658048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-02-25T19:31:06.658083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:31:06.658094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-02-25T19:31:06.658345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-02-25T19:31:06.665073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:31:06.666577Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:31:06.668403Z","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-25T19:31:06.670736Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:31:06.670861Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:31:06.671029Z","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-25T19:31:06.671152Z","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-25T19:31:06.671176Z","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-25T19:31:06.673959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-02-25T19:31:06.675921Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-02-25T19:31:06.676766Z","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-25T19:31:06.676832Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-02-25T19:31:06.676867Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-02-25T19:31:06.677287Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:31:06.677389Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:31:07.659651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:31:07.659718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:31:07.659761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-02-25T19:31:07.659916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:31:07.659929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-02-25T19:31:07.659949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-02-25T19:31:07.65997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-02-25T19:31:07.661081Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:31:07.66134Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:31:07.661545Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:31:07.661608Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:31:07.661406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:31:07.661466Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:31:07.664113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:31:07.664285Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:31:07.664327Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:31:07.665183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:31:07.665539Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"warn","ts":"2026-02-25T19:31:14.720754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.257474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:31:14.72086Z","caller":"traceutil/trace.go:171","msg":"trace[1716816648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:320; }","duration":"100.431588ms","start":"2026-02-25T19:31:14.620406Z","end":"2026-02-25T19:31:14.720837Z","steps":["trace[1716816648] 'range keys from in-memory index tree' (duration: 100.10424ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.338033Z","caller":"traceutil/trace.go:171","msg":"trace[1130751954] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"142.028164ms","start":"2026-02-25T19:31:19.195916Z","end":"2026-02-25T19:31:19.337944Z","steps":["trace[1130751954] 'process raft request' (duration: 141.799508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:25.865571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675147691,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:25.931903Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.054032817s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:31:25.932331Z","caller":"traceutil/trace.go:171","msg":"trace[1287679106] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"567.676216ms","start":"2026-02-25T19:31:25.364626Z","end":"2026-02-25T19:31:25.932302Z","steps":["trace[1287679106] 'read index received' (duration: 567.473791ms)","trace[1287679106] 'applied index is now lower than readState.Index' (duration: 201.435µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:25.932438Z","caller":"traceutil/trace.go:171","msg":"trace[377332798] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.054751345s","start":"2026-02-25T19:31:24.877665Z","end":"2026-02-25T19:31:25.932416Z","steps":["trace[377332798] 'process raft request' (duration: 1.054498389s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:25.932535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.863542ms","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-25T19:31:25.932591Z","caller":"traceutil/trace.go:171","msg":"trace[264786526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"567.985934ms","start":"2026-02-25T19:31:25.364588Z","end":"2026-02-25T19:31:25.932574Z","steps":["trace[264786526] 'agreement among raft nodes before linearized reading' (duration: 567.80967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:25.932589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.084889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:31:25.932645Z","caller":"traceutil/trace.go:171","msg":"trace[564798656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"532.190471ms","start":"2026-02-25T19:31:25.400439Z","end":"2026-02-25T19:31:25.932629Z","steps":["trace[564798656] 'agreement among raft nodes before linearized reading' (duration: 532.090559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:25.932635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.364564Z","time spent":"568.058696ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:31:25.932692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.400415Z","time spent":"532.266433ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:31:25.932805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.842437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:31:25.932898Z","caller":"traceutil/trace.go:171","msg":"trace[1641418379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"121.98562ms","start":"2026-02-25T19:31:25.810884Z","end":"2026-02-25T19:31:25.93287Z","steps":["trace[1641418379] 'agreement among raft nodes before linearized reading' (duration: 121.832807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:25.93331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:24.877634Z","time spent":"1.054862307s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:25.933631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.17896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-02-25T19:31:25.934429Z","caller":"traceutil/trace.go:171","msg":"trace[42646702] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:417; }","duration":"355.587564ms","start":"2026-02-25T19:31:25.578817Z","end":"2026-02-25T19:31:25.934405Z","steps":["trace[42646702] 'agreement among raft nodes before linearized reading' (duration: 353.990435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:25.934479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.578787Z","time spent":"355.679186ms","remote":"127.0.0.1:45050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-25T19:31:27.720829Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.649386486s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:31:27.759868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688253786s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:27.760163Z","caller":"traceutil/trace.go:171","msg":"trace[1716272756] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.822832376s","start":"2026-02-25T19:31:25.937281Z","end":"2026-02-25T19:31:27.760114Z","steps":["trace[1716272756] 'process raft request' (duration: 134.098389ms)","trace[1716272756] 'compare' (duration: 1.68806677s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:27.760218Z","caller":"traceutil/trace.go:171","msg":"trace[294861932] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.821938633s","start":"2026-02-25T19:31:25.93823Z","end":"2026-02-25T19:31:27.760168Z","steps":["trace[294861932] 'process raft request' (duration: 1.821756869s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:27.760276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.937255Z","time spent":"1.822974609s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:27.760285Z","caller":"traceutil/trace.go:171","msg":"trace[1678553865] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.821860772s","start":"2026-02-25T19:31:25.938372Z","end":"2026-02-25T19:31:27.760232Z","steps":["trace[1678553865] 'process raft request' (duration: 1.821675997s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:27.760392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.938203Z","time spent":"1.822101597s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:27.760525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.938361Z","time spent":"1.822034356s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:27.76052Z","caller":"traceutil/trace.go:171","msg":"trace[1589384259] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.813362292s","start":"2026-02-25T19:31:25.947115Z","end":"2026-02-25T19:31:27.760477Z","steps":["trace[1589384259] 'process raft request' (duration: 1.812975652s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:27.760684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:25.947081Z","time spent":"1.813517926s","remote":"127.0.0.1:45050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:29.856808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00016649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:31:29.85695Z","caller":"traceutil/trace.go:171","msg":"trace[739454737] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000379416s","start":"2026-02-25T19:31:27.856546Z","end":"2026-02-25T19:31:29.856925Z","steps":["trace[739454737] 'agreement among raft nodes before linearized reading' (duration: 2.000212572s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:29.85702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:27.856523Z","time spent":"2.000485558s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:31:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:31:31.126858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.366698329s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:31:31.127073Z","caller":"traceutil/trace.go:171","msg":"trace[1027854186] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"3.270442033s","start":"2026-02-25T19:31:27.856613Z","end":"2026-02-25T19:31:31.127055Z","steps":["trace[1027854186] 'read index received' (duration: 59.211µs)","trace[1027854186] 'applied index is now lower than readState.Index' (duration: 3.270382192s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:31.127678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.585311658s","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-25T19:31:31.127741Z","caller":"traceutil/trace.go:171","msg":"trace[914476554] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:421; }","duration":"2.585384791s","start":"2026-02-25T19:31:28.54235Z","end":"2026-02-25T19:31:31.127735Z","steps":["trace[914476554] 'range keys from in-memory index tree' (duration: 2.585302028s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:31.128094Z","caller":"traceutil/trace.go:171","msg":"trace[1653343738] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"1.650313679s","start":"2026-02-25T19:31:29.477747Z","end":"2026-02-25T19:31:31.128061Z","steps":["trace[1653343738] 'process raft request' (duration: 1.649217852s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:31.128323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:29.477716Z","time spent":"1.650491954s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:32.364834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675147703,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:32.84906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.06839417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:31:32.849182Z","caller":"traceutil/trace.go:171","msg":"trace[1311126867] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:422; }","duration":"2.068640956s","start":"2026-02-25T19:31:30.780513Z","end":"2026-02-25T19:31:32.849154Z","steps":["trace[1311126867] 'agreement among raft nodes before linearized reading' (duration: 347.596067ms)","trace[1311126867] 'range keys from in-memory index tree' (duration: 1.720837474s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:32.849175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.991922847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:31:32.849236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:30.780479Z","time spent":"2.068746338s","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"info","ts":"2026-02-25T19:31:32.849293Z","caller":"traceutil/trace.go:171","msg":"trace[1595246450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"2.99207175s","start":"2026-02-25T19:31:29.857176Z","end":"2026-02-25T19:31:32.849248Z","steps":["trace[1595246450] 'agreement among raft nodes before linearized reading' (duration: 1.270945429s)","trace[1595246450] 'range keys from in-memory index tree' (duration: 1.720984617s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:32.849341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:29.857148Z","time spent":"2.992183423s","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:32.849474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.084409399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:31:32.849507Z","caller":"traceutil/trace.go:171","msg":"trace[267138556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"3.084547832s","start":"2026-02-25T19:31:29.764949Z","end":"2026-02-25T19:31:32.849497Z","steps":["trace[267138556] 'agreement among raft nodes before linearized reading' (duration: 1.363188335s)","trace[267138556] 'range keys from in-memory index tree' (duration: 1.721298425s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:32.849538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:29.764926Z","time spent":"3.084606593s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:31:32.849611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.912189416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:31:32.849656Z","caller":"traceutil/trace.go:171","msg":"trace[1153339068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:422; }","duration":"4.912282908s","start":"2026-02-25T19:31:27.93736Z","end":"2026-02-25T19:31:32.849643Z","steps":["trace[1153339068] 'agreement among raft nodes before linearized reading' (duration: 3.190668855s)","trace[1153339068] 'range keys from in-memory index tree' (duration: 1.72149756s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:32.849692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:27.937338Z","time spent":"4.91234726s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:31:32.849835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.084192444s","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-25T19:31:32.84988Z","caller":"traceutil/trace.go:171","msg":"trace[785406426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"3.084248485s","start":"2026-02-25T19:31:29.765616Z","end":"2026-02-25T19:31:32.849865Z","steps":["trace[785406426] 'agreement among raft nodes before linearized reading' (duration: 1.362503778s)","trace[785406426] 'range keys from in-memory index tree' (duration: 1.721668154s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:32.849911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:29.765607Z","time spent":"3.084297486s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:31:32.849911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.083823835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:31:32.850097Z","caller":"traceutil/trace.go:171","msg":"trace[1727707552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"3.08406036s","start":"2026-02-25T19:31:29.766022Z","end":"2026-02-25T19:31:32.850082Z","steps":["trace[1727707552] 'agreement among raft nodes before linearized reading' (duration: 1.362055947s)","trace[1727707552] 'range keys from in-memory index tree' (duration: 1.721779407s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:32.850142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:29.765987Z","time spent":"3.084141402s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:31:32.865855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675147703,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:33.305993Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.171417809s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:31:33.306463Z","caller":"traceutil/trace.go:171","msg":"trace[1246143230] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"2.171996674s","start":"2026-02-25T19:31:31.134438Z","end":"2026-02-25T19:31:33.306435Z","steps":["trace[1246143230] 'process raft request' (duration: 2.171785648s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:33.306617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:31.134411Z","time spent":"2.172132066s","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:31:33.319131Z","caller":"traceutil/trace.go:171","msg":"trace[1508867947] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"1.457763901s","start":"2026-02-25T19:31:31.86133Z","end":"2026-02-25T19:31:33.319094Z","steps":["trace[1508867947] 'process raft request' (duration: 1.457558456s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:33.319213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:32.852735Z","time spent":"466.473547ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T19:31:33.319274Z","caller":"traceutil/trace.go:171","msg":"trace[1718192556] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"464.810106ms","start":"2026-02-25T19:31:32.854436Z","end":"2026-02-25T19:31:33.319246Z","steps":["trace[1718192556] 'process raft request' (duration: 464.689123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:33.319483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:32.854414Z","time spent":"464.94243ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:33.319306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:31.861298Z","time spent":"1.457911984s","remote":"127.0.0.1:44676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:31:33.319615Z","caller":"traceutil/trace.go:171","msg":"trace[515922945] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"464.209132ms","start":"2026-02-25T19:31:32.855381Z","end":"2026-02-25T19:31:33.31959Z","steps":["trace[515922945] 'process raft request' (duration: 463.821002ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:33.319626Z","caller":"traceutil/trace.go:171","msg":"trace[1651263557] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:435; }","duration":"1.455743611s","start":"2026-02-25T19:31:31.863858Z","end":"2026-02-25T19:31:33.319602Z","steps":["trace[1651263557] 'read index received' (duration: 1.44236631s)","trace[1651263557] 'applied index is now lower than readState.Index' (duration: 13.376551ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:33.319719Z","caller":"traceutil/trace.go:171","msg":"trace[749839013] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"461.33488ms","start":"2026-02-25T19:31:32.858366Z","end":"2026-02-25T19:31:33.319701Z","steps":["trace[749839013] 'process raft request' (duration: 461.086784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:33.319734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:32.855356Z","time spent":"464.308584ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:33.31977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455899976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:33.31979Z","caller":"traceutil/trace.go:171","msg":"trace[30687907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"1.455992318s","start":"2026-02-25T19:31:31.863791Z","end":"2026-02-25T19:31:33.319784Z","steps":["trace[30687907] 'agreement among raft nodes before linearized reading' (duration: 1.455895745s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:33.319799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:32.85835Z","time spent":"461.395702ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:33.319835Z","caller":"traceutil/trace.go:171","msg":"trace[1139041309] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"461.321431ms","start":"2026-02-25T19:31:32.858509Z","end":"2026-02-25T19:31:33.31983Z","steps":["trace[1139041309] 'process raft request' (duration: 461.065714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:33.31981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:31.863758Z","time spent":"1.456047469s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:33.319905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:32.858502Z","time spent":"461.358441ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:35.695422Z","caller":"traceutil/trace.go:171","msg":"trace[838173852] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"367.273066ms","start":"2026-02-25T19:31:35.328085Z","end":"2026-02-25T19:31:35.695358Z","steps":["trace[838173852] 'process raft request' (duration: 367.0271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:35.695637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:35.328049Z","time spent":"367.477681ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:35.710034Z","caller":"traceutil/trace.go:171","msg":"trace[1100376240] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"380.791411ms","start":"2026-02-25T19:31:35.32921Z","end":"2026-02-25T19:31:35.710001Z","steps":["trace[1100376240] 'process raft request' (duration: 380.717019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:35.710226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:35.329204Z","time spent":"380.923654ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:35.710374Z","caller":"traceutil/trace.go:171","msg":"trace[85703853] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"381.378725ms","start":"2026-02-25T19:31:35.328952Z","end":"2026-02-25T19:31:35.71033Z","steps":["trace[85703853] 'process raft request' (duration: 380.808851ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:35.710478Z","caller":"traceutil/trace.go:171","msg":"trace[2051861302] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"381.118569ms","start":"2026-02-25T19:31:35.329321Z","end":"2026-02-25T19:31:35.71044Z","steps":["trace[2051861302] 'process raft request' (duration: 380.638607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:35.710629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:35.329296Z","time spent":"381.253942ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:35.711293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:35.328927Z","time spent":"381.617701ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:40.943467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.954473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:40.943619Z","caller":"traceutil/trace.go:171","msg":"trace[1357462516] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"203.784477ms","start":"2026-02-25T19:31:40.739807Z","end":"2026-02-25T19:31:40.943591Z","steps":["trace[1357462516] 'process raft request' (duration: 60.601998ms)","trace[1357462516] 'compare' (duration: 142.700407ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:40.957894Z","caller":"traceutil/trace.go:171","msg":"trace[1402269577] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:491; }","duration":"176.604245ms","start":"2026-02-25T19:31:40.781274Z","end":"2026-02-25T19:31:40.957878Z","steps":["trace[1402269577] 'read index received' (duration: 19.118113ms)","trace[1402269577] 'applied index is now lower than readState.Index' (duration: 157.485112ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:40.958018Z","caller":"traceutil/trace.go:171","msg":"trace[2136831238] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"217.983088ms","start":"2026-02-25T19:31:40.739936Z","end":"2026-02-25T19:31:40.957919Z","steps":["trace[2136831238] 'process raft request' (duration: 217.873385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:40.958123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.84429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:31:40.958235Z","caller":"traceutil/trace.go:171","msg":"trace[1288056821] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:476; }","duration":"176.998504ms","start":"2026-02-25T19:31:40.781219Z","end":"2026-02-25T19:31:40.958218Z","steps":["trace[1288056821] 'agreement among raft nodes before linearized reading' (duration: 176.712307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:41.235386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.519048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:41.235703Z","caller":"traceutil/trace.go:171","msg":"trace[667063800] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"273.08303ms","start":"2026-02-25T19:31:40.962531Z","end":"2026-02-25T19:31:41.235614Z","steps":["trace[667063800] 'process raft request' (duration: 116.257694ms)","trace[667063800] 'compare' (duration: 156.260742ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:41.235737Z","caller":"traceutil/trace.go:171","msg":"trace[1077763917] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"202.250748ms","start":"2026-02-25T19:31:41.033464Z","end":"2026-02-25T19:31:41.235714Z","steps":["trace[1077763917] 'process raft request' (duration: 202.139266ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:41.46189Z","caller":"traceutil/trace.go:171","msg":"trace[148999629] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"175.896197ms","start":"2026-02-25T19:31:41.285936Z","end":"2026-02-25T19:31:41.461832Z","steps":["trace[148999629] 'process raft request' (duration: 173.518989ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:41.468171Z","caller":"traceutil/trace.go:171","msg":"trace[1016976994] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"173.483347ms","start":"2026-02-25T19:31:41.294654Z","end":"2026-02-25T19:31:41.468138Z","steps":["trace[1016976994] 'process raft request' (duration: 173.291553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:41.935273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.799603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-02-25T19:31:41.935465Z","caller":"traceutil/trace.go:171","msg":"trace[1365760248] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:507; }","duration":"111.997618ms","start":"2026-02-25T19:31:41.823391Z","end":"2026-02-25T19:31:41.935389Z","steps":["trace[1365760248] 'range keys from in-memory index tree' (duration: 111.26131ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:43.573317Z","caller":"traceutil/trace.go:171","msg":"trace[1581832849] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"267.236675ms","start":"2026-02-25T19:31:43.306046Z","end":"2026-02-25T19:31:43.573282Z","steps":["trace[1581832849] 'process raft request' (duration: 267.05369ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:43.578284Z","caller":"traceutil/trace.go:171","msg":"trace[567224436] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"163.399739ms","start":"2026-02-25T19:31:43.414854Z","end":"2026-02-25T19:31:43.578254Z","steps":["trace[567224436] 'process raft request' (duration: 163.256455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:43.997264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.958213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T19:31:43.997337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.196995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:31:43.997442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.683375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:31:43.997491Z","caller":"traceutil/trace.go:171","msg":"trace[1588041798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"198.102906ms","start":"2026-02-25T19:31:43.799368Z","end":"2026-02-25T19:31:43.997471Z","steps":["trace[1588041798] 'range keys from in-memory index tree' (duration: 197.278095ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:43.997498Z","caller":"traceutil/trace.go:171","msg":"trace[1109518440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"196.173358ms","start":"2026-02-25T19:31:43.801251Z","end":"2026-02-25T19:31:43.997424Z","steps":["trace[1109518440] 'range keys from in-memory index tree' (duration: 195.825699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:43.997263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.080968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:31:43.997671Z","caller":"traceutil/trace.go:171","msg":"trace[838116674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"140.40015ms","start":"2026-02-25T19:31:43.857051Z","end":"2026-02-25T19:31:43.997451Z","steps":["trace[838116674] 'range keys from in-memory index tree' (duration: 140.062271ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:43.997762Z","caller":"traceutil/trace.go:171","msg":"trace[30076490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"222.620622ms","start":"2026-02-25T19:31:43.775089Z","end":"2026-02-25T19:31:43.997709Z","steps":["trace[30076490] 'range keys from in-memory index tree' (duration: 221.803542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:43.997366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.4721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:31:43.99792Z","caller":"traceutil/trace.go:171","msg":"trace[102142447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"198.053215ms","start":"2026-02-25T19:31:43.799847Z","end":"2026-02-25T19:31:43.9979Z","steps":["trace[102142447] 'range keys from in-memory index tree' (duration: 197.136552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:44.402313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.532813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:44.402582Z","caller":"traceutil/trace.go:171","msg":"trace[1513535430] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"399.922632ms","start":"2026-02-25T19:31:44.002635Z","end":"2026-02-25T19:31:44.402557Z","steps":["trace[1513535430] 'process raft request' (duration: 399.875881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:44.402684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:44.002606Z","time spent":"400.031825ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:44.402719Z","caller":"traceutil/trace.go:171","msg":"trace[2021327355] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"400.305772ms","start":"2026-02-25T19:31:44.002374Z","end":"2026-02-25T19:31:44.40268Z","steps":["trace[2021327355] 'process raft request' (duration: 274.310269ms)","trace[2021327355] 'compare' (duration: 124.799284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:44.402906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:44.002347Z","time spent":"400.462586ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:44.402958Z","caller":"traceutil/trace.go:171","msg":"trace[1177654772] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"400.566989ms","start":"2026-02-25T19:31:44.002371Z","end":"2026-02-25T19:31:44.402938Z","steps":["trace[1177654772] 'process raft request' (duration: 400.073757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:44.403046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:44.002339Z","time spent":"400.65891ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:46.363571Z","caller":"traceutil/trace.go:171","msg":"trace[1481870847] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"296.679052ms","start":"2026-02-25T19:31:46.066865Z","end":"2026-02-25T19:31:46.363544Z","steps":["trace[1481870847] 'process raft request' (duration: 296.483257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:46.638479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.122202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T19:31:46.638558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.322127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:31:46.638566Z","caller":"traceutil/trace.go:171","msg":"trace[2098606189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"231.239615ms","start":"2026-02-25T19:31:46.407307Z","end":"2026-02-25T19:31:46.638546Z","steps":["trace[2098606189] 'range keys from in-memory index tree' (duration: 230.957777ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:46.638594Z","caller":"traceutil/trace.go:171","msg":"trace[582556913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"231.414208ms","start":"2026-02-25T19:31:46.407168Z","end":"2026-02-25T19:31:46.638582Z","steps":["trace[582556913] 'range keys from in-memory index tree' (duration: 231.097951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:46.638858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.237265ms","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-25T19:31:46.6389Z","caller":"traceutil/trace.go:171","msg":"trace[1285270348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"231.293046ms","start":"2026-02-25T19:31:46.407598Z","end":"2026-02-25T19:31:46.638891Z","steps":["trace[1285270348] 'range keys from in-memory index tree' (duration: 231.151873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:51.283127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675147910,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:51.783896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675147910,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:52.284608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675147910,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:52.368209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.658795735s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:31:52.36871Z","caller":"traceutil/trace.go:171","msg":"trace[1411728114] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.684939361s","start":"2026-02-25T19:31:50.683736Z","end":"2026-02-25T19:31:52.368676Z","steps":["trace[1411728114] 'process raft request' (duration: 1.6848898s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:52.368768Z","caller":"traceutil/trace.go:171","msg":"trace[461432603] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.684991333s","start":"2026-02-25T19:31:50.683738Z","end":"2026-02-25T19:31:52.368729Z","steps":["trace[461432603] 'process raft request' (duration: 1.684708926s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:52.368891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:50.683705Z","time spent":"1.685094166s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:52.369035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:50.683725Z","time spent":"1.685197328s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:52.761353Z","caller":"traceutil/trace.go:171","msg":"trace[1059849490] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"2.077533134s","start":"2026-02-25T19:31:50.683785Z","end":"2026-02-25T19:31:52.761318Z","steps":["trace[1059849490] 'process raft request' (duration: 2.054598758s)","trace[1059849490] 'compare' (duration: 22.707601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:52.761567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:50.683752Z","time spent":"2.077704698s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:52.761642Z","caller":"traceutil/trace.go:171","msg":"trace[823608427] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:543; }","duration":"1.97946752s","start":"2026-02-25T19:31:50.782158Z","end":"2026-02-25T19:31:52.761626Z","steps":["trace[823608427] 'read index received' (duration: 1.586299763s)","trace[823608427] 'applied index is now lower than readState.Index' (duration: 393.165447ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:52.761742Z","caller":"traceutil/trace.go:171","msg":"trace[1527587222] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.003844339s","start":"2026-02-25T19:31:51.757847Z","end":"2026-02-25T19:31:52.761692Z","steps":["trace[1527587222] 'process raft request' (duration: 1.003416668s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:52.761794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.979609584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:31:52.761887Z","caller":"traceutil/trace.go:171","msg":"trace[118390778] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:528; }","duration":"1.979724857s","start":"2026-02-25T19:31:50.782141Z","end":"2026-02-25T19:31:52.761866Z","steps":["trace[118390778] 'agreement among raft nodes before linearized reading' (duration: 1.979540182s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:52.761941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:50.782077Z","time spent":"1.97985413s","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"warn","ts":"2026-02-25T19:31:52.761896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.90300014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:52.762034Z","caller":"traceutil/trace.go:171","msg":"trace[239814925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"1.903135304s","start":"2026-02-25T19:31:50.858884Z","end":"2026-02-25T19:31:52.762019Z","steps":["trace[239814925] 'agreement among raft nodes before linearized reading' (duration: 1.90297343s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:52.762087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:50.858816Z","time spent":"1.903258267s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:52.762125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.750174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:31:52.76217Z","caller":"traceutil/trace.go:171","msg":"trace[949619210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"366.942748ms","start":"2026-02-25T19:31:52.395216Z","end":"2026-02-25T19:31:52.762159Z","steps":["trace[949619210] 'agreement among raft nodes before linearized reading' (duration: 366.712643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:52.761974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:51.757818Z","time spent":"1.004034004s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:52.762198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:52.395192Z","time spent":"366.99826ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:31:57.232795Z","caller":"traceutil/trace.go:171","msg":"trace[2043443271] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"228.63914ms","start":"2026-02-25T19:31:57.004121Z","end":"2026-02-25T19:31:57.23276Z","steps":["trace[2043443271] 'process raft request' (duration: 228.429505ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:58.718322Z","caller":"traceutil/trace.go:171","msg":"trace[705363876] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"321.399502ms","start":"2026-02-25T19:31:58.396889Z","end":"2026-02-25T19:31:58.718289Z","steps":["trace[705363876] 'process raft request' (duration: 321.150016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:58.718517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.396857Z","time spent":"321.561907ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:58.903988Z","caller":"traceutil/trace.go:171","msg":"trace[1054761840] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"115.593917ms","start":"2026-02-25T19:31:58.788365Z","end":"2026-02-25T19:31:58.903959Z","steps":["trace[1054761840] 'read index received' (duration: 23.881921ms)","trace[1054761840] 'applied index is now lower than readState.Index' (duration: 91.710696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:58.90445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.065819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:31:58.904498Z","caller":"traceutil/trace.go:171","msg":"trace[1402119369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"116.169701ms","start":"2026-02-25T19:31:58.788316Z","end":"2026-02-25T19:31:58.904486Z","steps":["trace[1402119369] 'agreement among raft nodes before linearized reading' (duration: 115.950436ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:58.904616Z","caller":"traceutil/trace.go:171","msg":"trace[131041841] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"505.40332ms","start":"2026-02-25T19:31:58.399197Z","end":"2026-02-25T19:31:58.9046Z","steps":["trace[131041841] 'process raft request' (duration: 413.15204ms)","trace[131041841] 'compare' (duration: 91.496741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:58.904718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.399176Z","time spent":"505.473962ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:00.003132Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.094171301s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:32:00.995706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.18506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:00.995883Z","caller":"traceutil/trace.go:171","msg":"trace[1317827045] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"2.087007357s","start":"2026-02-25T19:31:58.908832Z","end":"2026-02-25T19:32:00.995839Z","steps":["trace[1317827045] 'process raft request' (duration: 1.09455437s)","trace[1317827045] 'compare' (duration: 991.472963ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:00.995976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.908811Z","time spent":"2.087118811s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:01.08646Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083106788s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:32:01.086878Z","caller":"traceutil/trace.go:171","msg":"trace[2120972553] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:566; }","duration":"1.228059549s","start":"2026-02-25T19:31:59.858788Z","end":"2026-02-25T19:32:01.086848Z","steps":["trace[2120972553] 'read index received' (duration: 144.604293ms)","trace[2120972553] 'applied index is now lower than readState.Index' (duration: 1.083454216s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:01.086986Z","caller":"traceutil/trace.go:171","msg":"trace[2042150518] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"2.177621577s","start":"2026-02-25T19:31:58.909321Z","end":"2026-02-25T19:32:01.086942Z","steps":["trace[2042150518] 'process raft request' (duration: 2.17736034s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:01.087177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.331726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T19:32:01.087177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.56905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:32:01.087178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.909292Z","time spent":"2.17775772s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:01.087216Z","caller":"traceutil/trace.go:171","msg":"trace[1302765095] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:547; }","duration":"305.444158ms","start":"2026-02-25T19:32:00.78176Z","end":"2026-02-25T19:32:01.087204Z","steps":["trace[1302765095] 'agreement among raft nodes before linearized reading' (duration: 305.299295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:01.087203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228416509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:01.087218Z","caller":"traceutil/trace.go:171","msg":"trace[327997660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"364.632261ms","start":"2026-02-25T19:32:00.722575Z","end":"2026-02-25T19:32:01.087207Z","steps":["trace[327997660] 'agreement among raft nodes before linearized reading' (duration: 364.441957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:01.087244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:00.781733Z","time spent":"305.50565ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"info","ts":"2026-02-25T19:32:01.087255Z","caller":"traceutil/trace.go:171","msg":"trace[803967160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"1.228522831s","start":"2026-02-25T19:31:59.858719Z","end":"2026-02-25T19:32:01.087242Z","steps":["trace[803967160] 'agreement among raft nodes before linearized reading' (duration: 1.228272275s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:01.087257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:00.722499Z","time spent":"364.751174ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:32:01.087322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:59.858657Z","time spent":"1.228658065s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:01.087643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.264026ms","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-25T19:32:01.087773Z","caller":"traceutil/trace.go:171","msg":"trace[2102844173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"178.502741ms","start":"2026-02-25T19:32:00.909236Z","end":"2026-02-25T19:32:01.087739Z","steps":["trace[2102844173] 'agreement among raft nodes before linearized reading' (duration: 177.791984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.000665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.873724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:06.000834Z","caller":"traceutil/trace.go:171","msg":"trace[99014528] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"825.129541ms","start":"2026-02-25T19:32:05.175679Z","end":"2026-02-25T19:32:06.000808Z","steps":["trace[99014528] 'read index received' (duration: 33.779625ms)","trace[99014528] 'applied index is now lower than readState.Index' (duration: 791.347976ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:06.00086Z","caller":"traceutil/trace.go:171","msg":"trace[932076703] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"826.67872ms","start":"2026-02-25T19:32:05.174157Z","end":"2026-02-25T19:32:06.000836Z","steps":["trace[932076703] 'process raft request' (duration: 35.305003ms)","trace[932076703] 'compare' (duration: 790.70713ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:06.000959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.174137Z","time spent":"826.776492ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:06.001092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.727136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:06.001146Z","caller":"traceutil/trace.go:171","msg":"trace[992217881] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:612; }","duration":"702.821789ms","start":"2026-02-25T19:32:05.298307Z","end":"2026-02-25T19:32:06.001128Z","steps":["trace[992217881] 'agreement among raft nodes before linearized reading' (duration: 702.634864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.001192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.298284Z","time spent":"702.90034ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:32:06.001197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.51443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T19:32:06.001184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.859852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:06.001225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.23865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-25T19:32:06.001248Z","caller":"traceutil/trace.go:171","msg":"trace[540826951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"825.584992ms","start":"2026-02-25T19:32:05.175647Z","end":"2026-02-25T19:32:06.001232Z","steps":["trace[540826951] 'agreement among raft nodes before linearized reading' (duration: 825.270355ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:06.001268Z","caller":"traceutil/trace.go:171","msg":"trace[1906364266] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:612; }","duration":"501.986405ms","start":"2026-02-25T19:32:05.499258Z","end":"2026-02-25T19:32:06.001245Z","steps":["trace[1906364266] 'agreement among raft nodes before linearized reading' (duration: 501.864782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.001283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.225947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-25T19:32:06.001286Z","caller":"traceutil/trace.go:171","msg":"trace[763655954] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:612; }","duration":"302.306201ms","start":"2026-02-25T19:32:05.698963Z","end":"2026-02-25T19:32:06.001269Z","steps":["trace[763655954] 'agreement among raft nodes before linearized reading' (duration: 302.001814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.001291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.175627Z","time spent":"825.652224ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:32:06.001306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.499237Z","time spent":"502.060887ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:32:06.001315Z","caller":"traceutil/trace.go:171","msg":"trace[55221453] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:612; }","duration":"102.287918ms","start":"2026-02-25T19:32:05.899014Z","end":"2026-02-25T19:32:06.001302Z","steps":["trace[55221453] 'agreement among raft nodes before linearized reading' (duration: 102.224817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.001318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.546962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:06.00133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.698906Z","time spent":"302.412904ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:32:06.001359Z","caller":"traceutil/trace.go:171","msg":"trace[717395019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"144.626394ms","start":"2026-02-25T19:32:05.856719Z","end":"2026-02-25T19:32:06.001345Z","steps":["trace[717395019] 'agreement among raft nodes before linearized reading' (duration: 144.566923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.001182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.044958ms","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-25T19:32:06.001416Z","caller":"traceutil/trace.go:171","msg":"trace[407935222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:612; }","duration":"483.326545ms","start":"2026-02-25T19:32:05.518076Z","end":"2026-02-25T19:32:06.001402Z","steps":["trace[407935222] 'agreement among raft nodes before linearized reading' (duration: 482.925735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.001451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:05.518057Z","time spent":"483.383587ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T19:32:06.364945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.594761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:06.365065Z","caller":"traceutil/trace.go:171","msg":"trace[1661597222] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"301.885061ms","start":"2026-02-25T19:32:06.06316Z","end":"2026-02-25T19:32:06.365045Z","steps":["trace[1661597222] 'read index received' (duration: 165.992312ms)","trace[1661597222] 'applied index is now lower than readState.Index' (duration: 135.891339ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:06.365122Z","caller":"traceutil/trace.go:171","msg":"trace[1346849438] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"351.61449ms","start":"2026-02-25T19:32:06.013488Z","end":"2026-02-25T19:32:06.365102Z","steps":["trace[1346849438] 'process raft request' (duration: 215.760172ms)","trace[1346849438] 'compare' (duration: 135.469499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:06.365209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.013481Z","time spent":"351.684782ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:06.36526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.091566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-75bwv\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-02-25T19:32:06.365297Z","caller":"traceutil/trace.go:171","msg":"trace[1226652187] range","detail":"{range_begin:/registry/pods/kube-system/cilium-75bwv; range_end:; response_count:1; response_revision:614; }","duration":"302.166128ms","start":"2026-02-25T19:32:06.063118Z","end":"2026-02-25T19:32:06.365284Z","steps":["trace[1226652187] 'agreement among raft nodes before linearized reading' (duration: 302.023674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.365331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.063095Z","time spent":"302.225289ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18090,"request content":"key:\"/registry/pods/kube-system/cilium-75bwv\" "} {"level":"warn","ts":"2026-02-25T19:32:06.365485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.963708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-25T19:32:06.365572Z","caller":"traceutil/trace.go:171","msg":"trace[992459167] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:614; }","duration":"267.110241ms","start":"2026-02-25T19:32:06.098439Z","end":"2026-02-25T19:32:06.36555Z","steps":["trace[992459167] 'agreement among raft nodes before linearized reading' (duration: 266.844255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:06.999727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:07.500749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:07.692Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.289821136s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:32:07.692527Z","caller":"traceutil/trace.go:171","msg":"trace[1068582] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.290456832s","start":"2026-02-25T19:32:06.402038Z","end":"2026-02-25T19:32:07.692495Z","steps":["trace[1068582] 'process raft request' (duration: 1.290243337s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.692711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.402011Z","time spent":"1.290605336s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18236,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:07.692869Z","caller":"traceutil/trace.go:171","msg":"trace[1039550624] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:637; }","duration":"1.194113151s","start":"2026-02-25T19:32:06.498715Z","end":"2026-02-25T19:32:07.692828Z","steps":["trace[1039550624] 'read index received' (duration: 1.194099011s)","trace[1039550624] 'applied index is now lower than readState.Index' (duration: 11.57µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:07.693123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194389218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-25T19:32:07.693218Z","caller":"traceutil/trace.go:171","msg":"trace[1777020583] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:615; }","duration":"1.194537081s","start":"2026-02-25T19:32:06.498654Z","end":"2026-02-25T19:32:07.693191Z","steps":["trace[1777020583] 'agreement among raft nodes before linearized reading' (duration: 1.194268834s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.693257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.498629Z","time spent":"1.194621304s","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:32:07.720015Z","caller":"traceutil/trace.go:171","msg":"trace[1027716674] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"184.377487ms","start":"2026-02-25T19:32:07.535568Z","end":"2026-02-25T19:32:07.719945Z","steps":["trace[1027716674] 'process raft request' (duration: 184.070879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.720101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.239242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-25T19:32:07.720159Z","caller":"traceutil/trace.go:171","msg":"trace[551350040] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"415.366865ms","start":"2026-02-25T19:32:07.304776Z","end":"2026-02-25T19:32:07.720143Z","steps":["trace[551350040] 'agreement among raft nodes before linearized reading' (duration: 415.1597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.720158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.857598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:32:07.72022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:07.304749Z","time spent":"415.460308ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:32:07.720239Z","caller":"traceutil/trace.go:171","msg":"trace[1907941211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"607.007231ms","start":"2026-02-25T19:32:07.11321Z","end":"2026-02-25T19:32:07.720217Z","steps":["trace[1907941211] 'agreement among raft nodes before linearized reading' (duration: 606.677093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.7203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:07.113189Z","time spent":"607.096364ms","remote":"127.0.0.1:44886","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-25T19:32:07.720325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.529667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T19:32:07.720406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.060916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-25T19:32:07.720442Z","caller":"traceutil/trace.go:171","msg":"trace[1516327131] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"822.140979ms","start":"2026-02-25T19:32:06.89829Z","end":"2026-02-25T19:32:07.720431Z","steps":["trace[1516327131] 'agreement among raft nodes before linearized reading' (duration: 822.041326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.720414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.210307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-02-25T19:32:07.720478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.898264Z","time spent":"822.201309ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:32:07.720521Z","caller":"traceutil/trace.go:171","msg":"trace[69991379] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"622.367521ms","start":"2026-02-25T19:32:07.098127Z","end":"2026-02-25T19:32:07.720495Z","steps":["trace[69991379] 'agreement among raft nodes before linearized reading' (duration: 622.053523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.720567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:07.098105Z","time spent":"622.453203ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:32:07.720442Z","caller":"traceutil/trace.go:171","msg":"trace[583878644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"650.726122ms","start":"2026-02-25T19:32:07.069688Z","end":"2026-02-25T19:32:07.720414Z","steps":["trace[583878644] 'agreement among raft nodes before linearized reading' (duration: 650.345673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.720639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:07.069656Z","time spent":"650.965678ms","remote":"127.0.0.1:44886","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-25T19:32:07.720752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02169998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-25T19:32:07.720787Z","caller":"traceutil/trace.go:171","msg":"trace[128883055] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"1.021778662s","start":"2026-02-25T19:32:06.698997Z","end":"2026-02-25T19:32:07.720776Z","steps":["trace[128883055] 'agreement among raft nodes before linearized reading' (duration: 1.020826009s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:07.720815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:06.698974Z","time spent":"1.021834524s","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:32:08.838268Z","caller":"traceutil/trace.go:171","msg":"trace[1533902205] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"448.567455ms","start":"2026-02-25T19:32:08.389654Z","end":"2026-02-25T19:32:08.838221Z","steps":["trace[1533902205] 'process raft request' (duration: 448.289308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:08.838504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.38964Z","time spent":"448.73405ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:09.436195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148098,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:32:09.719038Z","caller":"traceutil/trace.go:171","msg":"trace[146559500] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"783.37682ms","start":"2026-02-25T19:32:08.935641Z","end":"2026-02-25T19:32:09.719018Z","steps":["trace[146559500] 'read index received' (duration: 783.256567ms)","trace[146559500] 'applied index is now lower than readState.Index' (duration: 119.233µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:09.719129Z","caller":"traceutil/trace.go:171","msg":"trace[1444092788] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"831.1562ms","start":"2026-02-25T19:32:08.887923Z","end":"2026-02-25T19:32:09.71908Z","steps":["trace[1444092788] 'process raft request' (duration: 830.952895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.719193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.461652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:09.719219Z","caller":"traceutil/trace.go:171","msg":"trace[866123654] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:631; }","duration":"783.509724ms","start":"2026-02-25T19:32:08.935702Z","end":"2026-02-25T19:32:09.719212Z","steps":["trace[866123654] 'agreement among raft nodes before linearized reading' (duration: 783.429452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.719199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.522113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:09.719244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.935639Z","time spent":"783.597565ms","remote":"127.0.0.1:54674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:09.719267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.887881Z","time spent":"831.308064ms","remote":"127.0.0.1:44796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4680,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:09.719287Z","caller":"traceutil/trace.go:171","msg":"trace[76497324] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:631; }","duration":"783.669927ms","start":"2026-02-25T19:32:08.935592Z","end":"2026-02-25T19:32:09.719261Z","steps":["trace[76497324] 'agreement among raft nodes before linearized reading' (duration: 783.508283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.719336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.935564Z","time spent":"783.760869ms","remote":"127.0.0.1:54674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-25T19:32:09.719392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.476723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-75bwv\" ","response":"range_response_count:1 size:18337"} {"level":"warn","ts":"2026-02-25T19:32:09.719462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.202772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:09.719524Z","caller":"traceutil/trace.go:171","msg":"trace[587861749] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:631; }","duration":"740.232263ms","start":"2026-02-25T19:32:08.979249Z","end":"2026-02-25T19:32:09.719481Z","steps":["trace[587861749] 'agreement among raft nodes before linearized reading' (duration: 740.191112ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:09.719521Z","caller":"traceutil/trace.go:171","msg":"trace[2031874658] range","detail":"{range_begin:/registry/pods/kube-system/cilium-75bwv; range_end:; response_count:1; response_revision:631; }","duration":"624.642097ms","start":"2026-02-25T19:32:09.094836Z","end":"2026-02-25T19:32:09.719478Z","steps":["trace[2031874658] 'agreement among raft nodes before linearized reading' (duration: 624.412501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.719548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.97923Z","time spent":"740.311526ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-25T19:32:09.719553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.028964ms","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":"warn","ts":"2026-02-25T19:32:09.71966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.880347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:09.719562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:09.094803Z","time spent":"624.74977ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18360,"request content":"key:\"/registry/pods/kube-system/cilium-75bwv\" "} {"level":"info","ts":"2026-02-25T19:32:09.71971Z","caller":"traceutil/trace.go:171","msg":"trace[12533112] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:631; }","duration":"767.940048ms","start":"2026-02-25T19:32:08.951754Z","end":"2026-02-25T19:32:09.719694Z","steps":["trace[12533112] 'agreement among raft nodes before linearized reading' (duration: 767.854146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.719725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.890617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:09.719751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.951688Z","time spent":"768.053312ms","remote":"127.0.0.1:54690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"info","ts":"2026-02-25T19:32:09.719764Z","caller":"traceutil/trace.go:171","msg":"trace[389580364] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:631; }","duration":"767.955149ms","start":"2026-02-25T19:32:08.951793Z","end":"2026-02-25T19:32:09.719748Z","steps":["trace[389580364] 'agreement among raft nodes before linearized reading' (duration: 767.900157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.719786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.783753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:09.719794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.951772Z","time spent":"768.01419ms","remote":"127.0.0.1:54690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:32:09.719812Z","caller":"traceutil/trace.go:171","msg":"trace[630286249] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:631; }","duration":"739.806783ms","start":"2026-02-25T19:32:08.979996Z","end":"2026-02-25T19:32:09.719803Z","steps":["trace[630286249] 'agreement among raft nodes before linearized reading' (duration: 739.769412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.719938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.979967Z","time spent":"739.860044ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-25T19:32:09.719977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.172134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:09.719356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.858724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:09.720026Z","caller":"traceutil/trace.go:171","msg":"trace[988686797] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:631; }","duration":"768.236825ms","start":"2026-02-25T19:32:08.951773Z","end":"2026-02-25T19:32:09.72001Z","steps":["trace[988686797] 'agreement among raft nodes before linearized reading' (duration: 768.164783ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:09.720041Z","caller":"traceutil/trace.go:171","msg":"trace[1693914560] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:631; }","duration":"740.666394ms","start":"2026-02-25T19:32:08.979359Z","end":"2026-02-25T19:32:09.720026Z","steps":["trace[1693914560] 'agreement among raft nodes before linearized reading' (duration: 739.804902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.720069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.95149Z","time spent":"768.567553ms","remote":"127.0.0.1:54690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-02-25T19:32:09.720118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.979336Z","time spent":"740.763286ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:09.719674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.880272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:09.719654Z","caller":"traceutil/trace.go:171","msg":"trace[1101461833] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:631; }","duration":"637.159416ms","start":"2026-02-25T19:32:09.082452Z","end":"2026-02-25T19:32:09.719611Z","steps":["trace[1101461833] 'agreement among raft nodes before linearized reading' (duration: 637.023913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.720198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:09.082423Z","time spent":"637.766242ms","remote":"127.0.0.1:45028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:32:09.720196Z","caller":"traceutil/trace.go:171","msg":"trace[1405056176] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:631; }","duration":"784.407995ms","start":"2026-02-25T19:32:08.93575Z","end":"2026-02-25T19:32:09.720158Z","steps":["trace[1405056176] 'agreement among raft nodes before linearized reading' (duration: 783.901882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:09.720236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:08.935727Z","time spent":"784.496337ms","remote":"127.0.0.1:54674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-02-25T19:32:09.956329Z","caller":"traceutil/trace.go:171","msg":"trace[122501354] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"215.69156ms","start":"2026-02-25T19:32:09.74062Z","end":"2026-02-25T19:32:09.956311Z","steps":["trace[122501354] 'process raft request' (duration: 215.332981ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:10.04624Z","caller":"traceutil/trace.go:171","msg":"trace[1089053517] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"188.773006ms","start":"2026-02-25T19:32:09.857438Z","end":"2026-02-25T19:32:10.046211Z","steps":["trace[1089053517] 'read index received' (duration: 98.71123ms)","trace[1089053517] 'applied index is now lower than readState.Index' (duration: 90.060626ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:10.046254Z","caller":"traceutil/trace.go:171","msg":"trace[46060241] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"263.092942ms","start":"2026-02-25T19:32:09.783122Z","end":"2026-02-25T19:32:10.046215Z","steps":["trace[46060241] 'process raft request' (duration: 226.035636ms)","trace[46060241] 'compare' (duration: 36.82604ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:10.04638Z","caller":"traceutil/trace.go:171","msg":"trace[422255481] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"262.98359ms","start":"2026-02-25T19:32:09.783386Z","end":"2026-02-25T19:32:10.04637Z","steps":["trace[422255481] 'process raft request' (duration: 262.771824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.04652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.318599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:10.046673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.220786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.046669Z","caller":"traceutil/trace.go:171","msg":"trace[306634581] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:634; }","duration":"153.463463ms","start":"2026-02-25T19:32:09.893123Z","end":"2026-02-25T19:32:10.046586Z","steps":["trace[306634581] 'agreement among raft nodes before linearized reading' (duration: 153.321039ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:10.046705Z","caller":"traceutil/trace.go:171","msg":"trace[1875272264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"189.272458ms","start":"2026-02-25T19:32:09.857423Z","end":"2026-02-25T19:32:10.046696Z","steps":["trace[1875272264] 'agreement among raft nodes before linearized reading' (duration: 189.196026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.046883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.654413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.046907Z","caller":"traceutil/trace.go:171","msg":"trace[612475350] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:634; }","duration":"152.701444ms","start":"2026-02-25T19:32:09.894198Z","end":"2026-02-25T19:32:10.0469Z","steps":["trace[612475350] 'agreement among raft nodes before linearized reading' (duration: 152.655233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:10.046952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.547305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:10.046992Z","caller":"traceutil/trace.go:171","msg":"trace[988713693] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:634; }","duration":"153.615867ms","start":"2026-02-25T19:32:09.893363Z","end":"2026-02-25T19:32:10.046979Z","steps":["trace[988713693] 'agreement among raft nodes before linearized reading' (duration: 153.552705ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:11.544839Z","caller":"traceutil/trace.go:171","msg":"trace[1582709224] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"139.893297ms","start":"2026-02-25T19:32:11.404916Z","end":"2026-02-25T19:32:11.54481Z","steps":["trace[1582709224] 'process raft request' (duration: 139.725253ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:11.658306Z","caller":"traceutil/trace.go:171","msg":"trace[1071050867] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"107.129727ms","start":"2026-02-25T19:32:11.551151Z","end":"2026-02-25T19:32:11.658281Z","steps":["trace[1071050867] 'process raft request' (duration: 67.94284ms)","trace[1071050867] 'compare' (duration: 39.046474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:12.39958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.354026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:12.399691Z","caller":"traceutil/trace.go:171","msg":"trace[1492088569] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"276.823411ms","start":"2026-02-25T19:32:12.12285Z","end":"2026-02-25T19:32:12.399674Z","steps":["trace[1492088569] 'read index received' (duration: 21.169373ms)","trace[1492088569] 'applied index is now lower than readState.Index' (duration: 255.650798ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:12.399824Z","caller":"traceutil/trace.go:171","msg":"trace[1353690454] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"294.085648ms","start":"2026-02-25T19:32:12.105701Z","end":"2026-02-25T19:32:12.399786Z","steps":["trace[1353690454] 'process raft request' (duration: 71.446086ms)","trace[1353690454] 'compare' (duration: 222.201531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:12.39987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.021807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-75bwv\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-02-25T19:32:12.399912Z","caller":"traceutil/trace.go:171","msg":"trace[1995942160] range","detail":"{range_begin:/registry/pods/kube-system/cilium-75bwv; range_end:; response_count:1; response_revision:646; }","duration":"277.094618ms","start":"2026-02-25T19:32:12.122807Z","end":"2026-02-25T19:32:12.399902Z","steps":["trace[1995942160] 'agreement among raft nodes before linearized reading' (duration: 276.954974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:12.399949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.349395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T19:32:12.400014Z","caller":"traceutil/trace.go:171","msg":"trace[1777799297] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:646; }","duration":"275.470978ms","start":"2026-02-25T19:32:12.124527Z","end":"2026-02-25T19:32:12.399998Z","steps":["trace[1777799297] 'agreement among raft nodes before linearized reading' (duration: 275.308324ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:12.585064Z","caller":"traceutil/trace.go:171","msg":"trace[781960255] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"169.307544ms","start":"2026-02-25T19:32:12.415537Z","end":"2026-02-25T19:32:12.584845Z","steps":["trace[781960255] 'process raft request' (duration: 168.70317ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:14.258396Z","caller":"traceutil/trace.go:171","msg":"trace[1223290326] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"153.118804ms","start":"2026-02-25T19:32:14.105246Z","end":"2026-02-25T19:32:14.258365Z","steps":["trace[1223290326] 'process raft request' (duration: 58.805523ms)","trace[1223290326] 'compare' (duration: 94.191068ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:14.433223Z","caller":"traceutil/trace.go:171","msg":"trace[925394010] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"327.278678ms","start":"2026-02-25T19:32:14.105914Z","end":"2026-02-25T19:32:14.433192Z","steps":["trace[925394010] 'process raft request' (duration: 255.585327ms)","trace[925394010] 'compare' (duration: 71.453806ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:14.433309Z","caller":"traceutil/trace.go:171","msg":"trace[990473390] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:682; }","duration":"284.781428ms","start":"2026-02-25T19:32:14.148467Z","end":"2026-02-25T19:32:14.433249Z","steps":["trace[990473390] 'read index received' (duration: 15.593355ms)","trace[990473390] 'applied index is now lower than readState.Index' (duration: 269.184813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:14.433357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:14.105902Z","time spent":"327.385321ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:14.433514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.026404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T19:32:14.43354Z","caller":"traceutil/trace.go:171","msg":"trace[81024977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"285.101286ms","start":"2026-02-25T19:32:14.148432Z","end":"2026-02-25T19:32:14.433534Z","steps":["trace[81024977] 'agreement among raft nodes before linearized reading' (duration: 284.962632ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:18.50286Z","caller":"traceutil/trace.go:171","msg":"trace[1052365017] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"152.842338ms","start":"2026-02-25T19:32:18.349979Z","end":"2026-02-25T19:32:18.502822Z","steps":["trace[1052365017] 'process raft request' (duration: 106.720748ms)","trace[1052365017] 'compare' (duration: 45.848303ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:18.732133Z","caller":"traceutil/trace.go:171","msg":"trace[623514861] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"132.770411ms","start":"2026-02-25T19:32:18.59934Z","end":"2026-02-25T19:32:18.73211Z","steps":["trace[623514861] 'process raft request' (duration: 124.950078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:26.73534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.495478ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:32:26.735744Z","caller":"traceutil/trace.go:171","msg":"trace[1551527659] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:775; }","duration":"188.739454ms","start":"2026-02-25T19:32:26.546981Z","end":"2026-02-25T19:32:26.735721Z","steps":["trace[1551527659] 'read index received' (duration: 115.147375ms)","trace[1551527659] 'applied index is now lower than readState.Index' (duration: 73.590789ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:26.735787Z","caller":"traceutil/trace.go:171","msg":"trace[216879870] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"439.942363ms","start":"2026-02-25T19:32:26.295793Z","end":"2026-02-25T19:32:26.735736Z","steps":["trace[216879870] 'process raft request' (duration: 439.779599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:26.735968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:26.295758Z","time spent":"440.113547ms","remote":"127.0.0.1:44676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:32:26.736207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.215337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:32:26.736252Z","caller":"traceutil/trace.go:171","msg":"trace[83076875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"189.322869ms","start":"2026-02-25T19:32:26.546912Z","end":"2026-02-25T19:32:26.736235Z","steps":["trace[83076875] 'agreement among raft nodes before linearized reading' (duration: 188.929379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:26.736229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.688302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:32:26.73623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.157646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:32:26.736307Z","caller":"traceutil/trace.go:171","msg":"trace[928459183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"141.828215ms","start":"2026-02-25T19:32:26.59446Z","end":"2026-02-25T19:32:26.736288Z","steps":["trace[928459183] 'agreement among raft nodes before linearized reading' (duration: 141.598769ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:26.736318Z","caller":"traceutil/trace.go:171","msg":"trace[274181291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"115.269279ms","start":"2026-02-25T19:32:26.621027Z","end":"2026-02-25T19:32:26.736296Z","steps":["trace[274181291] 'agreement among raft nodes before linearized reading' (duration: 115.043644ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:27.187537Z","caller":"traceutil/trace.go:171","msg":"trace[1861907808] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"447.275693ms","start":"2026-02-25T19:32:26.740237Z","end":"2026-02-25T19:32:27.187513Z","steps":["trace[1861907808] 'process raft request' (duration: 447.213871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:27.187697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:26.74021Z","time spent":"447.405706ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:27.187802Z","caller":"traceutil/trace.go:171","msg":"trace[1036350205] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"447.53573ms","start":"2026-02-25T19:32:26.74022Z","end":"2026-02-25T19:32:27.187756Z","steps":["trace[1036350205] 'process raft request' (duration: 446.792621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:27.18807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:26.740195Z","time spent":"447.724834ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:27.196851Z","caller":"traceutil/trace.go:171","msg":"trace[506177784] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:777; }","duration":"404.318871ms","start":"2026-02-25T19:32:26.792515Z","end":"2026-02-25T19:32:27.196834Z","steps":["trace[506177784] 'read index received' (duration: 394.519239ms)","trace[506177784] 'applied index is now lower than readState.Index' (duration: 9.798802ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:27.19711Z","caller":"traceutil/trace.go:171","msg":"trace[101114265] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"456.255415ms","start":"2026-02-25T19:32:26.740845Z","end":"2026-02-25T19:32:27.197101Z","steps":["trace[101114265] 'process raft request' (duration: 455.853455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:27.197195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:26.740839Z","time spent":"456.299367ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:27.197506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.119378ms","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-25T19:32:27.19756Z","caller":"traceutil/trace.go:171","msg":"trace[1300754556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"190.217601ms","start":"2026-02-25T19:32:27.007334Z","end":"2026-02-25T19:32:27.197552Z","steps":["trace[1300754556] 'agreement among raft nodes before linearized reading' (duration: 190.074497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:27.197686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.127332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T19:32:27.197797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.940685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:27.197828Z","caller":"traceutil/trace.go:171","msg":"trace[477705014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"341.021507ms","start":"2026-02-25T19:32:26.856798Z","end":"2026-02-25T19:32:27.197819Z","steps":["trace[477705014] 'agreement among raft nodes before linearized reading' (duration: 340.31759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:27.197856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:26.856771Z","time spent":"341.077398ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:32:27.197844Z","caller":"traceutil/trace.go:171","msg":"trace[597918475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"405.360317ms","start":"2026-02-25T19:32:26.792445Z","end":"2026-02-25T19:32:27.197806Z","steps":["trace[597918475] 'agreement among raft nodes before linearized reading' (duration: 404.939667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:27.197926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:26.792422Z","time spent":"405.488211ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:32:27.435676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.135751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:32:27.435828Z","caller":"traceutil/trace.go:171","msg":"trace[874450225] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"232.621599ms","start":"2026-02-25T19:32:27.203193Z","end":"2026-02-25T19:32:27.435815Z","steps":["trace[874450225] 'process raft request' (duration: 232.562198ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:27.435923Z","caller":"traceutil/trace.go:171","msg":"trace[558492656] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"232.775233ms","start":"2026-02-25T19:32:27.203126Z","end":"2026-02-25T19:32:27.435901Z","steps":["trace[558492656] 'process raft request' (duration: 103.323754ms)","trace[558492656] 'compare' (duration: 128.998428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:29.397558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32963227s","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":"warn","ts":"2026-02-25T19:32:29.397583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539349273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:29.397635Z","caller":"traceutil/trace.go:171","msg":"trace[983738972] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:753; }","duration":"1.329773514s","start":"2026-02-25T19:32:28.067844Z","end":"2026-02-25T19:32:29.397617Z","steps":["trace[983738972] 'count revisions from in-memory index tree' (duration: 1.329516208s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:29.397666Z","caller":"traceutil/trace.go:171","msg":"trace[1058507195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"1.539469666s","start":"2026-02-25T19:32:27.858174Z","end":"2026-02-25T19:32:29.397644Z","steps":["trace[1058507195] 'range keys from in-memory index tree' (duration: 1.539203769s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:29.39768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:28.067822Z","time spent":"1.329847476s","remote":"127.0.0.1:44978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:29.39771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:27.858157Z","time spent":"1.539543998s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:29.397985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.355219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:32:29.398071Z","caller":"traceutil/trace.go:171","msg":"trace[1618594750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"206.486502ms","start":"2026-02-25T19:32:29.191563Z","end":"2026-02-25T19:32:29.398049Z","steps":["trace[1618594750] 'range keys from in-memory index tree' (duration: 206.193176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:29.397688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.279507ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:29.398653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.805999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:32:29.398726Z","caller":"traceutil/trace.go:171","msg":"trace[597094612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"197.914201ms","start":"2026-02-25T19:32:29.200792Z","end":"2026-02-25T19:32:29.398707Z","steps":["trace[597094612] 'range keys from in-memory index tree' (duration: 196.392803ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:29.398727Z","caller":"traceutil/trace.go:171","msg":"trace[1475909031] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:753; }","duration":"856.323222ms","start":"2026-02-25T19:32:28.542379Z","end":"2026-02-25T19:32:29.398702Z","steps":["trace[1475909031] 'range keys from in-memory index tree' (duration: 855.263846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:29.399127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.446057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:32:29.399172Z","caller":"traceutil/trace.go:171","msg":"trace[2129733945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"207.491388ms","start":"2026-02-25T19:32:29.19167Z","end":"2026-02-25T19:32:29.399161Z","steps":["trace[2129733945] 'range keys from in-memory index tree' (duration: 205.508069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:29.90743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:32:29.907822Z","caller":"traceutil/trace.go:171","msg":"trace[1446571542] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"505.027911ms","start":"2026-02-25T19:32:29.402762Z","end":"2026-02-25T19:32:29.90779Z","steps":["trace[1446571542] 'process raft request' (duration: 504.825446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:29.908018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:29.402737Z","time spent":"505.180925ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:30.130867Z","caller":"traceutil/trace.go:171","msg":"trace[782906787] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"727.10385ms","start":"2026-02-25T19:32:29.403744Z","end":"2026-02-25T19:32:30.130848Z","steps":["trace[782906787] 'process raft request' (duration: 726.935916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:30.131033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:29.403718Z","time spent":"727.222862ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:30.131046Z","caller":"traceutil/trace.go:171","msg":"trace[749055607] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:782; }","duration":"723.675905ms","start":"2026-02-25T19:32:29.407352Z","end":"2026-02-25T19:32:30.131028Z","steps":["trace[749055607] 'read index received' (duration: 500.173422ms)","trace[749055607] 'applied index is now lower than readState.Index' (duration: 223.500993ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:30.131098Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":5639523327675148371,"received-request-id":5639523327675148367} {"level":"warn","ts":"2026-02-25T19:32:30.13129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.014827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:30.131297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.874099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:30.131341Z","caller":"traceutil/trace.go:171","msg":"trace[334558917] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:756; }","duration":"343.066169ms","start":"2026-02-25T19:32:29.788261Z","end":"2026-02-25T19:32:30.131327Z","steps":["trace[334558917] 'agreement among raft nodes before linearized reading' (duration: 342.987807ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:30.131358Z","caller":"traceutil/trace.go:171","msg":"trace[2039023169] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:756; }","duration":"272.007682ms","start":"2026-02-25T19:32:29.859335Z","end":"2026-02-25T19:32:30.131343Z","steps":["trace[2039023169] 'agreement among raft nodes before linearized reading' (duration: 271.880879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:30.13136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.975932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T19:32:30.131426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.641044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T19:32:30.131328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.909591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:30.131464Z","caller":"traceutil/trace.go:171","msg":"trace[225493258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"691.696205ms","start":"2026-02-25T19:32:29.439755Z","end":"2026-02-25T19:32:30.131451Z","steps":["trace[225493258] 'agreement among raft nodes before linearized reading' (duration: 691.571452ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:30.131463Z","caller":"traceutil/trace.go:171","msg":"trace[764245232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"692.106755ms","start":"2026-02-25T19:32:29.43934Z","end":"2026-02-25T19:32:30.131446Z","steps":["trace[764245232] 'agreement among raft nodes before linearized reading' (duration: 691.878579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:30.131497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:29.439745Z","time spent":"691.745536ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:32:30.131512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:29.439326Z","time spent":"692.173787ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T19:32:30.131376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:29.788176Z","time spent":"343.190722ms","remote":"127.0.0.1:44936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:32:30.13153Z","caller":"traceutil/trace.go:171","msg":"trace[453328604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"724.163877ms","start":"2026-02-25T19:32:29.407324Z","end":"2026-02-25T19:32:30.131488Z","steps":["trace[453328604] 'agreement among raft nodes before linearized reading' (duration: 723.820568ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:30.131171Z","caller":"traceutil/trace.go:171","msg":"trace[265030615] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"727.354906ms","start":"2026-02-25T19:32:29.40375Z","end":"2026-02-25T19:32:30.131105Z","steps":["trace[265030615] 'process raft request' (duration: 727.063789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:30.131624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:29.407315Z","time spent":"724.28832ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:30.131817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:29.403732Z","time spent":"727.903569ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:32.089133Z","caller":"traceutil/trace.go:171","msg":"trace[400609159] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"172.030362ms","start":"2026-02-25T19:32:31.917055Z","end":"2026-02-25T19:32:32.089085Z","steps":["trace[400609159] 'process raft request' (duration: 171.814786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:46.381057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.408755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T19:32:46.381116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.054016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:32:46.381168Z","caller":"traceutil/trace.go:171","msg":"trace[1750034416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"143.555249ms","start":"2026-02-25T19:32:46.237585Z","end":"2026-02-25T19:32:46.38114Z","steps":["trace[1750034416] 'range keys from in-memory index tree' (duration: 143.234831ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:46.381186Z","caller":"traceutil/trace.go:171","msg":"trace[1466093972] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:804; }","duration":"309.167949ms","start":"2026-02-25T19:32:46.071998Z","end":"2026-02-25T19:32:46.381166Z","steps":["trace[1466093972] 'count revisions from in-memory index tree' (duration: 308.970604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:46.381466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.071972Z","time spent":"309.430235ms","remote":"127.0.0.1:45112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:46.381057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.814977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T19:32:46.381691Z","caller":"traceutil/trace.go:171","msg":"trace[1047858467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"175.490463ms","start":"2026-02-25T19:32:46.206179Z","end":"2026-02-25T19:32:46.381669Z","steps":["trace[1047858467] 'range keys from in-memory index tree' (duration: 174.628472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:46.980672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.003488ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:32:46.980849Z","caller":"traceutil/trace.go:171","msg":"trace[1550584120] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"656.776293ms","start":"2026-02-25T19:32:46.324054Z","end":"2026-02-25T19:32:46.98083Z","steps":["trace[1550584120] 'read index received' (duration: 58.58662ms)","trace[1550584120] 'applied index is now lower than readState.Index' (duration: 598.188893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:46.980942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.888286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:46.980977Z","caller":"traceutil/trace.go:171","msg":"trace[941872880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"656.974318ms","start":"2026-02-25T19:32:46.323992Z","end":"2026-02-25T19:32:46.980966Z","steps":["trace[941872880] 'agreement among raft nodes before linearized reading' (duration: 656.912607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:46.981009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.323955Z","time spent":"657.04486ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:32:46.981678Z","caller":"traceutil/trace.go:171","msg":"trace[1292064300] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"590.500674ms","start":"2026-02-25T19:32:46.391153Z","end":"2026-02-25T19:32:46.981654Z","steps":["trace[1292064300] 'process raft request' (duration: 589.600742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:46.981824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.391124Z","time spent":"590.625817ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:48.045635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063908699s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-02-25T19:32:48.045965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519885668s","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-25T19:32:48.046053Z","caller":"traceutil/trace.go:171","msg":"trace[2094483647] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:806; }","duration":"1.51998202s","start":"2026-02-25T19:32:46.526043Z","end":"2026-02-25T19:32:48.046025Z","steps":["trace[2094483647] 'agreement among raft nodes before linearized reading' (duration: 1.519795036s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:48.046101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.526012Z","time spent":"1.520079932s","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:32:48.046312Z","caller":"traceutil/trace.go:171","msg":"trace[1153201131] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.654927597s","start":"2026-02-25T19:32:46.39137Z","end":"2026-02-25T19:32:48.046298Z","steps":["trace[1153201131] 'process raft request' (duration: 590.263949ms)","trace[1153201131] 'compare' (duration: 1.063799097s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:48.046405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.391353Z","time spent":"1.654992888s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:48.046548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191333312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:48.046514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564645957s","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-25T19:32:48.046587Z","caller":"traceutil/trace.go:171","msg":"trace[1156623490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"1.191424714s","start":"2026-02-25T19:32:46.855153Z","end":"2026-02-25T19:32:48.046578Z","steps":["trace[1156623490] 'agreement among raft nodes before linearized reading' (duration: 1.191370613s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:48.046613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.855122Z","time spent":"1.191486216s","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:32:48.045767Z","caller":"traceutil/trace.go:171","msg":"trace[2040130325] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:838; }","duration":"1.064873934s","start":"2026-02-25T19:32:46.980875Z","end":"2026-02-25T19:32:48.045749Z","steps":["trace[2040130325] 'read index received' (duration: 72.372µs)","trace[2040130325] 'applied index is now lower than readState.Index' (duration: 1.064799902s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:48.046779Z","caller":"traceutil/trace.go:171","msg":"trace[1547320305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:806; }","duration":"1.564921804s","start":"2026-02-25T19:32:46.481672Z","end":"2026-02-25T19:32:48.046594Z","steps":["trace[1547320305] 'agreement among raft nodes before linearized reading' (duration: 1.564363871s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:48.046933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565463067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T19:32:48.046899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.48165Z","time spent":"1.565229992s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:32:48.047063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.439015ms","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":"warn","ts":"2026-02-25T19:32:48.047084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.611917689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:32:48.047112Z","caller":"traceutil/trace.go:171","msg":"trace[1638481633] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:806; }","duration":"905.543008ms","start":"2026-02-25T19:32:47.141559Z","end":"2026-02-25T19:32:48.047102Z","steps":["trace[1638481633] 'agreement among raft nodes before linearized reading' (duration: 905.476426ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:48.047116Z","caller":"traceutil/trace.go:171","msg":"trace[213874852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"1.611990891s","start":"2026-02-25T19:32:46.435116Z","end":"2026-02-25T19:32:48.047107Z","steps":["trace[213874852] 'agreement among raft nodes before linearized reading' (duration: 1.611900769s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:48.047141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:47.14153Z","time spent":"905.604069ms","remote":"127.0.0.1:44864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:48.047154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.435094Z","time spent":"1.612050183s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:32:48.046998Z","caller":"traceutil/trace.go:171","msg":"trace[1450083386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"1.565583381s","start":"2026-02-25T19:32:46.481397Z","end":"2026-02-25T19:32:48.046981Z","steps":["trace[1450083386] 'agreement among raft nodes before linearized reading' (duration: 1.565406136s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:48.047579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:46.481366Z","time spent":"1.566009902s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:32:48.251046Z","caller":"traceutil/trace.go:171","msg":"trace[1488560775] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"199.989186ms","start":"2026-02-25T19:32:48.051034Z","end":"2026-02-25T19:32:48.251023Z","steps":["trace[1488560775] 'process raft request' (duration: 199.858763ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:48.283036Z","caller":"traceutil/trace.go:171","msg":"trace[5504920] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:840; }","duration":"227.052151ms","start":"2026-02-25T19:32:48.055961Z","end":"2026-02-25T19:32:48.283013Z","steps":["trace[5504920] 'read index received' (duration: 195.016483ms)","trace[5504920] 'applied index is now lower than readState.Index' (duration: 32.034548ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:48.283082Z","caller":"traceutil/trace.go:171","msg":"trace[2042554642] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"231.07935ms","start":"2026-02-25T19:32:48.051971Z","end":"2026-02-25T19:32:48.283051Z","steps":["trace[2042554642] 'process raft request' (duration: 230.881366ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:48.283123Z","caller":"traceutil/trace.go:171","msg":"trace[527786721] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"231.033489ms","start":"2026-02-25T19:32:48.052068Z","end":"2026-02-25T19:32:48.283102Z","steps":["trace[527786721] 'process raft request' (duration: 230.893305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:48.283172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.181034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:48.283211Z","caller":"traceutil/trace.go:171","msg":"trace[1140206220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"227.262026ms","start":"2026-02-25T19:32:48.055939Z","end":"2026-02-25T19:32:48.283201Z","steps":["trace[1140206220] 'agreement among raft nodes before linearized reading' (duration: 227.157614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:00.346406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:00.847064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:01.347827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:01.566514Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.497767152s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:33:01.566942Z","caller":"traceutil/trace.go:171","msg":"trace[1794282164] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"1.721409401s","start":"2026-02-25T19:32:59.845491Z","end":"2026-02-25T19:33:01.5669Z","steps":["trace[1794282164] 'read index received' (duration: 1.721154934s)","trace[1794282164] 'applied index is now lower than readState.Index' (duration: 253.466µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:01.567012Z","caller":"traceutil/trace.go:171","msg":"trace[580152604] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"2.498298285s","start":"2026-02-25T19:32:59.068679Z","end":"2026-02-25T19:33:01.566977Z","steps":["trace[580152604] 'process raft request' (duration: 2.49806831s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.567097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438939438s","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":"warn","ts":"2026-02-25T19:33:01.567089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721563945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:01.567138Z","caller":"traceutil/trace.go:171","msg":"trace[20773382] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:889; }","duration":"1.43903411s","start":"2026-02-25T19:33:00.128093Z","end":"2026-02-25T19:33:01.567127Z","steps":["trace[20773382] 'agreement among raft nodes before linearized reading' (duration: 1.438952568s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.56713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:59.068663Z","time spent":"2.498397877s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:01.567155Z","caller":"traceutil/trace.go:171","msg":"trace[138070648] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:889; }","duration":"1.721681997s","start":"2026-02-25T19:32:59.845452Z","end":"2026-02-25T19:33:01.567134Z","steps":["trace[138070648] 'agreement among raft nodes before linearized reading' (duration: 1.721559284s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.567171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:00.128072Z","time spent":"1.439089142s","remote":"127.0.0.1:44796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:33:01.567201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:59.845436Z","time spent":"1.72175579s","remote":"127.0.0.1:44752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:33:01.567254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184904134s","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-25T19:33:01.567278Z","caller":"traceutil/trace.go:171","msg":"trace[642895371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:889; }","duration":"1.184963606s","start":"2026-02-25T19:33:00.382307Z","end":"2026-02-25T19:33:01.56727Z","steps":["trace[642895371] 'agreement among raft nodes before linearized reading' (duration: 1.184876043s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.567293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:00.382287Z","time spent":"1.185002726s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:33:01.567269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087690785s","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-25T19:33:01.567343Z","caller":"traceutil/trace.go:171","msg":"trace[649249553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"1.087807558s","start":"2026-02-25T19:33:00.479517Z","end":"2026-02-25T19:33:01.567324Z","steps":["trace[649249553] 'agreement among raft nodes before linearized reading' (duration: 1.087571702s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.567386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:00.479505Z","time spent":"1.087872289s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T19:33:01.567461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379113007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:33:01.567475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092865052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:33:01.567497Z","caller":"traceutil/trace.go:171","msg":"trace[1152188357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"1.379148568s","start":"2026-02-25T19:33:00.188338Z","end":"2026-02-25T19:33:01.567486Z","steps":["trace[1152188357] 'agreement among raft nodes before linearized reading' (duration: 1.379001754s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.567512Z","caller":"traceutil/trace.go:171","msg":"trace[952667593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"1.092905983s","start":"2026-02-25T19:33:00.474593Z","end":"2026-02-25T19:33:01.567499Z","steps":["trace[952667593] 'agreement among raft nodes before linearized reading' (duration: 1.09276339s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.567534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:00.18827Z","time spent":"1.379256561s","remote":"127.0.0.1:44886","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-25T19:33:01.56754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:00.474534Z","time spent":"1.092998795s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:33:01.567537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.303949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:01.567578Z","caller":"traceutil/trace.go:171","msg":"trace[568770022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"710.3701ms","start":"2026-02-25T19:33:00.857199Z","end":"2026-02-25T19:33:01.567569Z","steps":["trace[568770022] 'agreement among raft nodes before linearized reading' (duration: 710.305458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.567604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:00.857182Z","time spent":"710.416682ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:01.567622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.508903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:33:01.567672Z","caller":"traceutil/trace.go:171","msg":"trace[1605508548] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:889; }","duration":"782.549415ms","start":"2026-02-25T19:33:00.785101Z","end":"2026-02-25T19:33:01.56765Z","steps":["trace[1605508548] 'agreement among raft nodes before linearized reading' (duration: 782.441942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.567705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:00.785044Z","time spent":"782.654097ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"info","ts":"2026-02-25T19:33:01.670866Z","caller":"traceutil/trace.go:171","msg":"trace[1577931819] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"101.520465ms","start":"2026-02-25T19:33:01.569326Z","end":"2026-02-25T19:33:01.670847Z","steps":["trace[1577931819] 'read index received' (duration: 100.187943ms)","trace[1577931819] 'applied index is now lower than readState.Index' (duration: 1.331482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:01.670971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.618528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:01.670997Z","caller":"traceutil/trace.go:171","msg":"trace[210021079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"101.68718ms","start":"2026-02-25T19:33:01.5693Z","end":"2026-02-25T19:33:01.670987Z","steps":["trace[210021079] 'agreement among raft nodes before linearized reading' (duration: 101.615448ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.702491Z","caller":"traceutil/trace.go:171","msg":"trace[374409099] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"130.234431ms","start":"2026-02-25T19:33:01.572234Z","end":"2026-02-25T19:33:01.702468Z","steps":["trace[374409099] 'process raft request' (duration: 130.034626ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.70279Z","caller":"traceutil/trace.go:171","msg":"trace[1330129909] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"130.455956ms","start":"2026-02-25T19:33:01.572316Z","end":"2026-02-25T19:33:01.702771Z","steps":["trace[1330129909] 'process raft request' (duration: 130.114558ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.702809Z","caller":"traceutil/trace.go:171","msg":"trace[736228419] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"130.417626ms","start":"2026-02-25T19:33:01.572364Z","end":"2026-02-25T19:33:01.702781Z","steps":["trace[736228419] 'process raft request' (duration: 130.290912ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.702847Z","caller":"traceutil/trace.go:171","msg":"trace[1966905471] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"129.909813ms","start":"2026-02-25T19:33:01.572923Z","end":"2026-02-25T19:33:01.702832Z","steps":["trace[1966905471] 'process raft request' (duration: 129.826381ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:06.015166Z","caller":"traceutil/trace.go:171","msg":"trace[1562005645] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"248.123369ms","start":"2026-02-25T19:33:05.767013Z","end":"2026-02-25T19:33:06.015136Z","steps":["trace[1562005645] 'process raft request' (duration: 247.728849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:06.015526Z","caller":"traceutil/trace.go:171","msg":"trace[381958903] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"247.77482ms","start":"2026-02-25T19:33:05.767687Z","end":"2026-02-25T19:33:06.015462Z","steps":["trace[381958903] 'process raft request' (duration: 247.410051ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:12.310751Z","caller":"traceutil/trace.go:171","msg":"trace[1973868354] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"149.3019ms","start":"2026-02-25T19:33:12.16142Z","end":"2026-02-25T19:33:12.310722Z","steps":["trace[1973868354] 'read index received' (duration: 58.045547ms)","trace[1973868354] 'applied index is now lower than readState.Index' (duration: 91.255343ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:12.310898Z","caller":"traceutil/trace.go:171","msg":"trace[513495092] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"184.156647ms","start":"2026-02-25T19:33:12.126728Z","end":"2026-02-25T19:33:12.310885Z","steps":["trace[513495092] 'process raft request' (duration: 92.74229ms)","trace[513495092] 'compare' (duration: 91.12243ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:12.311088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.650978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:33:12.311148Z","caller":"traceutil/trace.go:171","msg":"trace[149525188] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:922; }","duration":"149.762191ms","start":"2026-02-25T19:33:12.161373Z","end":"2026-02-25T19:33:12.311135Z","steps":["trace[149525188] 'agreement among raft nodes before linearized reading' (duration: 149.659459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:16.960889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.015137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:16.961115Z","caller":"traceutil/trace.go:171","msg":"trace[620931414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"637.280504ms","start":"2026-02-25T19:33:16.323807Z","end":"2026-02-25T19:33:16.961087Z","steps":["trace[620931414] 'range keys from in-memory index tree' (duration: 636.929885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:16.961268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.415054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:33:16.961391Z","caller":"traceutil/trace.go:171","msg":"trace[1616120205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"632.582948ms","start":"2026-02-25T19:33:16.328772Z","end":"2026-02-25T19:33:16.961355Z","steps":["trace[1616120205] 'range keys from in-memory index tree' (duration: 632.144347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:16.961472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:16.32875Z","time spent":"632.705102ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:33:16.961269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:16.323791Z","time spent":"637.461168ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:16.961108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.486959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:16.961858Z","caller":"traceutil/trace.go:171","msg":"trace[1337672375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"103.33143ms","start":"2026-02-25T19:33:16.858507Z","end":"2026-02-25T19:33:16.961838Z","steps":["trace[1337672375] 'range keys from in-memory index tree' (duration: 102.364996ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:18.232971Z","caller":"traceutil/trace.go:171","msg":"trace[2035430283] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"102.423748ms","start":"2026-02-25T19:33:18.130511Z","end":"2026-02-25T19:33:18.232934Z","steps":["trace[2035430283] 'process raft request' (duration: 102.250123ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:19.110557Z","caller":"traceutil/trace.go:171","msg":"trace[92848995] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"104.283683ms","start":"2026-02-25T19:33:19.00623Z","end":"2026-02-25T19:33:19.110514Z","steps":["trace[92848995] 'read index received' (duration: 104.115549ms)","trace[92848995] 'applied index is now lower than readState.Index' (duration: 167.244µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:19.110805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.54411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5184"} {"level":"info","ts":"2026-02-25T19:33:19.110873Z","caller":"traceutil/trace.go:171","msg":"trace[738273371] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:953; }","duration":"104.662242ms","start":"2026-02-25T19:33:19.006192Z","end":"2026-02-25T19:33:19.110855Z","steps":["trace[738273371] 'agreement among raft nodes before linearized reading' (duration: 104.431696ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:19.111299Z","caller":"traceutil/trace.go:171","msg":"trace[2035648587] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"114.390942ms","start":"2026-02-25T19:33:18.99689Z","end":"2026-02-25T19:33:19.111281Z","steps":["trace[2035648587] 'process raft request' (duration: 113.50811ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:19.346466Z","caller":"traceutil/trace.go:171","msg":"trace[839183290] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"226.153928ms","start":"2026-02-25T19:33:19.120286Z","end":"2026-02-25T19:33:19.34644Z","steps":["trace[839183290] 'process raft request' (duration: 225.901842ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:19.694161Z","caller":"traceutil/trace.go:171","msg":"trace[1668017761] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"142.681738ms","start":"2026-02-25T19:33:19.551462Z","end":"2026-02-25T19:33:19.694143Z","steps":["trace[1668017761] 'process raft request' (duration: 142.568615ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:20.093165Z","caller":"traceutil/trace.go:171","msg":"trace[286994430] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"539.166163ms","start":"2026-02-25T19:33:19.553975Z","end":"2026-02-25T19:33:20.093141Z","steps":["trace[286994430] 'process raft request' (duration: 538.947827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:20.093312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:19.553958Z","time spent":"539.277855ms","remote":"127.0.0.1:44780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:20.093398Z","caller":"traceutil/trace.go:171","msg":"trace[1589520500] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:998; }","duration":"486.675742ms","start":"2026-02-25T19:33:19.606701Z","end":"2026-02-25T19:33:20.093377Z","steps":["trace[1589520500] 'read index received' (duration: 87.368488ms)","trace[1589520500] 'applied index is now lower than readState.Index' (duration: 399.306394ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:20.093569Z","caller":"traceutil/trace.go:171","msg":"trace[1579993973] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"537.468451ms","start":"2026-02-25T19:33:19.556093Z","end":"2026-02-25T19:33:20.093561Z","steps":["trace[1579993973] 'process raft request' (duration: 536.996329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:20.093647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:19.556076Z","time spent":"537.511112ms","remote":"127.0.0.1:45058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:20.09368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.083723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:20.093742Z","caller":"traceutil/trace.go:171","msg":"trace[688763877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"236.171915ms","start":"2026-02-25T19:33:19.857549Z","end":"2026-02-25T19:33:20.093721Z","steps":["trace[688763877] 'agreement among raft nodes before linearized reading' (duration: 236.096923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:20.093964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.497734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:33:20.09399Z","caller":"traceutil/trace.go:171","msg":"trace[276489777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"199.543014ms","start":"2026-02-25T19:33:19.894439Z","end":"2026-02-25T19:33:20.093982Z","steps":["trace[276489777] 'agreement among raft nodes before linearized reading' (duration: 199.435952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:20.094133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.440541ms","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-25T19:33:20.094149Z","caller":"traceutil/trace.go:171","msg":"trace[1547248512] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:959; }","duration":"487.459031ms","start":"2026-02-25T19:33:19.606685Z","end":"2026-02-25T19:33:20.094144Z","steps":["trace[1547248512] 'agreement among raft nodes before linearized reading' (duration: 487.3876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:20.094166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:19.606591Z","time spent":"487.570284ms","remote":"127.0.0.1:45106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":120,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:33:20.094286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.779337ms","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-25T19:33:20.094302Z","caller":"traceutil/trace.go:171","msg":"trace[1133461137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"137.820318ms","start":"2026-02-25T19:33:19.956477Z","end":"2026-02-25T19:33:20.094297Z","steps":["trace[1133461137] 'agreement among raft nodes before linearized reading' (duration: 137.770727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:20.095168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.455986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:33:20.095221Z","caller":"traceutil/trace.go:171","msg":"trace[1579961393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"215.555238ms","start":"2026-02-25T19:33:19.879654Z","end":"2026-02-25T19:33:20.095209Z","steps":["trace[1579961393] 'agreement among raft nodes before linearized reading' (duration: 214.269837ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:21.113254Z","caller":"traceutil/trace.go:171","msg":"trace[1008652969] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"197.385502ms","start":"2026-02-25T19:33:20.915839Z","end":"2026-02-25T19:33:21.113224Z","steps":["trace[1008652969] 'process raft request' (duration: 128.851577ms)","trace[1008652969] 'compare' (duration: 68.378371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:21.935011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.182564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:21.93519Z","caller":"traceutil/trace.go:171","msg":"trace[702897510] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"816.914681ms","start":"2026-02-25T19:33:21.118207Z","end":"2026-02-25T19:33:21.935122Z","steps":["trace[702897510] 'process raft request' (duration: 340.515051ms)","trace[702897510] 'compare' (duration: 476.076862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:21.935275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.405628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-02-25T19:33:21.935301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:21.118184Z","time spent":"817.054474ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:21.93531Z","caller":"traceutil/trace.go:171","msg":"trace[2077825849] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:965; }","duration":"816.47249ms","start":"2026-02-25T19:33:21.118825Z","end":"2026-02-25T19:33:21.935298Z","steps":["trace[2077825849] 'agreement among raft nodes before linearized reading' (duration: 816.401288ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:21.935192Z","caller":"traceutil/trace.go:171","msg":"trace[215392325] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"816.292215ms","start":"2026-02-25T19:33:21.118871Z","end":"2026-02-25T19:33:21.935163Z","steps":["trace[215392325] 'read index received' (duration: 339.808863ms)","trace[215392325] 'applied index is now lower than readState.Index' (duration: 476.481832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:21.935859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:21.118809Z","time spent":"817.032093ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T19:33:22.680082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.066448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:33:22.680161Z","caller":"traceutil/trace.go:171","msg":"trace[311332216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"506.191342ms","start":"2026-02-25T19:33:22.173951Z","end":"2026-02-25T19:33:22.680143Z","steps":["trace[311332216] 'range keys from in-memory index tree' (duration: 505.875204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:22.680202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:22.173937Z","time spent":"506.255953ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:33:22.680394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.082252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T19:33:22.680484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.690326ms","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-25T19:33:22.680558Z","caller":"traceutil/trace.go:171","msg":"trace[1746602854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"513.800578ms","start":"2026-02-25T19:33:22.166742Z","end":"2026-02-25T19:33:22.680542Z","steps":["trace[1746602854] 'range keys from in-memory index tree' (duration: 513.0492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:22.680611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:22.166721Z","time spent":"513.874931ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:33:22.680631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.632747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T19:33:22.680712Z","caller":"traceutil/trace.go:171","msg":"trace[360715444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"507.75249ms","start":"2026-02-25T19:33:22.172948Z","end":"2026-02-25T19:33:22.6807Z","steps":["trace[360715444] 'range keys from in-memory index tree' (duration: 507.193237ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:22.680496Z","caller":"traceutil/trace.go:171","msg":"trace[1149353986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"380.231006ms","start":"2026-02-25T19:33:22.300247Z","end":"2026-02-25T19:33:22.680478Z","steps":["trace[1149353986] 'range keys from in-memory index tree' (duration: 379.692592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:22.680777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:22.172921Z","time spent":"507.844222ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:33:22.680811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:22.300232Z","time spent":"380.562464ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:33:22.863003Z","caller":"traceutil/trace.go:171","msg":"trace[312100208] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"177.36508ms","start":"2026-02-25T19:33:22.685616Z","end":"2026-02-25T19:33:22.862981Z","steps":["trace[312100208] 'process raft request' (duration: 177.205836ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:23.195755Z","caller":"traceutil/trace.go:171","msg":"trace[1753261578] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1008; }","duration":"338.51062ms","start":"2026-02-25T19:33:22.857223Z","end":"2026-02-25T19:33:23.195733Z","steps":["trace[1753261578] 'read index received' (duration: 5.67439ms)","trace[1753261578] 'applied index is now lower than readState.Index' (duration: 332.83522ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:23.195797Z","caller":"traceutil/trace.go:171","msg":"trace[1000073994] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"510.098388ms","start":"2026-02-25T19:33:22.685678Z","end":"2026-02-25T19:33:23.195776Z","steps":["trace[1000073994] 'process raft request' (duration: 484.331245ms)","trace[1000073994] 'compare' (duration: 25.476496ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:23.1958Z","caller":"traceutil/trace.go:171","msg":"trace[999319657] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"510.014386ms","start":"2026-02-25T19:33:22.685752Z","end":"2026-02-25T19:33:23.195767Z","steps":["trace[999319657] 'process raft request' (duration: 509.890903ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:23.195855Z","caller":"traceutil/trace.go:171","msg":"trace[1389884499] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"510.048957ms","start":"2026-02-25T19:33:22.685787Z","end":"2026-02-25T19:33:23.195836Z","steps":["trace[1389884499] 'process raft request' (duration: 509.908953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:23.195901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.666644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:23.195933Z","caller":"traceutil/trace.go:171","msg":"trace[1448912440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"338.746606ms","start":"2026-02-25T19:33:22.857176Z","end":"2026-02-25T19:33:23.195923Z","steps":["trace[1448912440] 'agreement among raft nodes before linearized reading' (duration: 338.686944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:23.195929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:22.685657Z","time spent":"510.20193ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:23.195958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:22.685782Z","time spent":"510.116598ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:23.195974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:22.685734Z","time spent":"510.143519ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:23.195961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:22.857155Z","time spent":"338.799848ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:33:24.089109Z","caller":"traceutil/trace.go:171","msg":"trace[296863949] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"145.181848ms","start":"2026-02-25T19:33:23.943904Z","end":"2026-02-25T19:33:24.089085Z","steps":["trace[296863949] 'process raft request' (duration: 145.039375ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:24.324418Z","caller":"traceutil/trace.go:171","msg":"trace[2052871407] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"139.427897ms","start":"2026-02-25T19:33:24.184968Z","end":"2026-02-25T19:33:24.324396Z","steps":["trace[2052871407] 'read index received' (duration: 139.308584ms)","trace[2052871407] 'applied index is now lower than readState.Index' (duration: 118.663µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:24.324471Z","caller":"traceutil/trace.go:171","msg":"trace[687804668] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"228.717672ms","start":"2026-02-25T19:33:24.095725Z","end":"2026-02-25T19:33:24.324442Z","steps":["trace[687804668] 'process raft request' (duration: 228.570598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:24.324565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.57941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:24.324606Z","caller":"traceutil/trace.go:171","msg":"trace[23043113] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:972; }","duration":"139.665593ms","start":"2026-02-25T19:33:24.18493Z","end":"2026-02-25T19:33:24.324595Z","steps":["trace[23043113] 'agreement among raft nodes before linearized reading' (duration: 139.55934ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:24.489793Z","caller":"traceutil/trace.go:171","msg":"trace[1197383019] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"122.546992ms","start":"2026-02-25T19:33:24.367212Z","end":"2026-02-25T19:33:24.489759Z","steps":["trace[1197383019] 'process raft request' (duration: 34.787145ms)","trace[1197383019] 'compare' (duration: 87.487761ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:24.489989Z","caller":"traceutil/trace.go:171","msg":"trace[1437082446] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"119.551679ms","start":"2026-02-25T19:33:24.370393Z","end":"2026-02-25T19:33:24.489944Z","steps":["trace[1437082446] 'process raft request' (duration: 119.281792ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:24.549162Z","caller":"traceutil/trace.go:171","msg":"trace[355310531] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"144.104751ms","start":"2026-02-25T19:33:24.405033Z","end":"2026-02-25T19:33:24.549138Z","steps":["trace[355310531] 'process raft request' (duration: 143.990249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:25.25108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.55234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-02-25T19:33:25.252051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.397548ms","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-25T19:33:25.25206Z","caller":"traceutil/trace.go:171","msg":"trace[1423984573] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"697.934484ms","start":"2026-02-25T19:33:24.553918Z","end":"2026-02-25T19:33:25.251853Z","steps":["trace[1423984573] 'process raft request' (duration: 477.832816ms)","trace[1423984573] 'compare' (duration: 218.445338ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:25.252119Z","caller":"traceutil/trace.go:171","msg":"trace[381244501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"369.490621ms","start":"2026-02-25T19:33:24.88261Z","end":"2026-02-25T19:33:25.2521Z","steps":["trace[381244501] 'agreement among raft nodes before linearized reading' (duration: 368.63406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:25.252204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:24.553899Z","time spent":"698.219002ms","remote":"127.0.0.1:44676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:33:25.252201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:24.882597Z","time spent":"369.590843ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:33:25.252257Z","caller":"traceutil/trace.go:171","msg":"trace[1514139377] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"368.554087ms","start":"2026-02-25T19:33:24.882638Z","end":"2026-02-25T19:33:25.251192Z","steps":["trace[1514139377] 'read index received' (duration: 149.129656ms)","trace[1514139377] 'applied index is now lower than readState.Index' (duration: 219.422331ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:25.252708Z","caller":"traceutil/trace.go:171","msg":"trace[1939738925] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"337.899115ms","start":"2026-02-25T19:33:24.914772Z","end":"2026-02-25T19:33:25.252671Z","steps":["trace[1939738925] 'process raft request' (duration: 337.606147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:25.252915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:24.914749Z","time spent":"338.046189ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:25.254139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.944407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:25.254219Z","caller":"traceutil/trace.go:171","msg":"trace[421790040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"369.023649ms","start":"2026-02-25T19:33:24.885165Z","end":"2026-02-25T19:33:25.254188Z","steps":["trace[421790040] 'agreement among raft nodes before linearized reading' (duration: 368.727812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:25.254262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:24.885159Z","time spent":"369.09398ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:25.254707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.871559ms","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-25T19:33:25.255489Z","caller":"traceutil/trace.go:171","msg":"trace[1128643161] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:978; }","duration":"371.936131ms","start":"2026-02-25T19:33:24.882796Z","end":"2026-02-25T19:33:25.254732Z","steps":["trace[1128643161] 'agreement among raft nodes before linearized reading' (duration: 370.006474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:25.255553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:24.882782Z","time spent":"372.738641ms","remote":"127.0.0.1:45028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:33:26.284794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.763256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:26.284876Z","caller":"traceutil/trace.go:171","msg":"trace[1791267542] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.02694443s","start":"2026-02-25T19:33:25.257917Z","end":"2026-02-25T19:33:26.284862Z","steps":["trace[1791267542] 'process raft request' (duration: 557.042301ms)","trace[1791267542] 'compare' (duration: 469.469819ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:26.284924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:25.257903Z","time spent":"1.027004863s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:26.354142Z","caller":"traceutil/trace.go:171","msg":"trace[640113094] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.095597829s","start":"2026-02-25T19:33:25.258515Z","end":"2026-02-25T19:33:26.354113Z","steps":["trace[640113094] 'process raft request' (duration: 1.095383644s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:26.354166Z","caller":"traceutil/trace.go:171","msg":"trace[1640776521] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.095342073s","start":"2026-02-25T19:33:25.258799Z","end":"2026-02-25T19:33:26.354141Z","steps":["trace[1640776521] 'process raft request' (duration: 1.095274561s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:26.354248Z","caller":"traceutil/trace.go:171","msg":"trace[285362139] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.095598278s","start":"2026-02-25T19:33:25.258618Z","end":"2026-02-25T19:33:26.354216Z","steps":["trace[285362139] 'process raft request' (duration: 1.095399774s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:26.354289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:25.258506Z","time spent":"1.095702061s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:26.354342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:25.258602Z","time spent":"1.09569064s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:26.354432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:25.258792Z","time spent":"1.095441205s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:26.534391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.440146ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:33:26.535281Z","caller":"traceutil/trace.go:171","msg":"trace[503783225] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"501.754442ms","start":"2026-02-25T19:33:26.033497Z","end":"2026-02-25T19:33:26.535251Z","steps":["trace[503783225] 'process raft request' (duration: 501.297261ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:26.534969Z","caller":"traceutil/trace.go:171","msg":"trace[905232390] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1022; }","duration":"441.252445ms","start":"2026-02-25T19:33:26.093696Z","end":"2026-02-25T19:33:26.534948Z","steps":["trace[905232390] 'read index received' (duration: 260.249137ms)","trace[905232390] 'applied index is now lower than readState.Index' (duration: 181.002277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:26.535482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:26.033477Z","time spent":"501.885115ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:26.535604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.329178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:33:26.535609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.88642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:33:26.535751Z","caller":"traceutil/trace.go:171","msg":"trace[2126073990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"442.053014ms","start":"2026-02-25T19:33:26.093655Z","end":"2026-02-25T19:33:26.535708Z","steps":["trace[2126073990] 'agreement among raft nodes before linearized reading' (duration: 441.667485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:26.535823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:26.093637Z","time spent":"442.152836ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:33:26.535681Z","caller":"traceutil/trace.go:171","msg":"trace[1688507631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"212.446351ms","start":"2026-02-25T19:33:26.323216Z","end":"2026-02-25T19:33:26.535662Z","steps":["trace[1688507631] 'agreement among raft nodes before linearized reading' (duration: 212.339538ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:28.511285Z","caller":"traceutil/trace.go:171","msg":"trace[1023546427] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"152.241172ms","start":"2026-02-25T19:33:28.358992Z","end":"2026-02-25T19:33:28.511234Z","steps":["trace[1023546427] 'read index received' (duration: 151.905914ms)","trace[1023546427] 'applied index is now lower than readState.Index' (duration: 334.238µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:28.511342Z","caller":"traceutil/trace.go:171","msg":"trace[615036868] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"217.2265ms","start":"2026-02-25T19:33:28.29409Z","end":"2026-02-25T19:33:28.511316Z","steps":["trace[615036868] 'process raft request' (duration: 216.914883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:28.511634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.604621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T19:33:28.51172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.610631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:33:28.511728Z","caller":"traceutil/trace.go:171","msg":"trace[1581442939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"152.755145ms","start":"2026-02-25T19:33:28.358953Z","end":"2026-02-25T19:33:28.511708Z","steps":["trace[1581442939] 'agreement among raft nodes before linearized reading' (duration: 152.471278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:28.511737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.668682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T19:33:28.511781Z","caller":"traceutil/trace.go:171","msg":"trace[2063785822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"152.733774ms","start":"2026-02-25T19:33:28.359031Z","end":"2026-02-25T19:33:28.511765Z","steps":["trace[2063785822] 'agreement among raft nodes before linearized reading' (duration: 152.500109ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:28.51183Z","caller":"traceutil/trace.go:171","msg":"trace[196049141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"152.838197ms","start":"2026-02-25T19:33:28.358942Z","end":"2026-02-25T19:33:28.51178Z","steps":["trace[196049141] 'agreement among raft nodes before linearized reading' (duration: 152.54909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:28.99263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.260107ms","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-25T19:33:28.992824Z","caller":"traceutil/trace.go:171","msg":"trace[573322852] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:985; }","duration":"450.470052ms","start":"2026-02-25T19:33:28.542335Z","end":"2026-02-25T19:33:28.992805Z","steps":["trace[573322852] 'range keys from in-memory index tree' (duration: 450.245177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:28.993522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.147243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:28.993602Z","caller":"traceutil/trace.go:171","msg":"trace[352441122] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"475.385555ms","start":"2026-02-25T19:33:28.518201Z","end":"2026-02-25T19:33:28.993587Z","steps":["trace[352441122] 'process raft request' (duration: 34.108678ms)","trace[352441122] 'compare' (duration: 440.741603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:28.993642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:28.518184Z","time spent":"475.441127ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:29.075146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148924,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:33:29.165881Z","caller":"traceutil/trace.go:171","msg":"trace[1984832677] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"591.306415ms","start":"2026-02-25T19:33:28.57454Z","end":"2026-02-25T19:33:29.165847Z","steps":["trace[1984832677] 'read index received' (duration: 565.387058ms)","trace[1984832677] 'applied index is now lower than readState.Index' (duration: 25.918097ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:29.165904Z","caller":"traceutil/trace.go:171","msg":"trace[1673584886] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"647.322601ms","start":"2026-02-25T19:33:28.518547Z","end":"2026-02-25T19:33:29.16587Z","steps":["trace[1673584886] 'process raft request' (duration: 621.294211ms)","trace[1673584886] 'compare' (duration: 25.747323ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:29.165967Z","caller":"traceutil/trace.go:171","msg":"trace[1319977213] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"647.308101ms","start":"2026-02-25T19:33:28.518623Z","end":"2026-02-25T19:33:29.165931Z","steps":["trace[1319977213] 'process raft request' (duration: 647.169217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:29.166113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.54707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:33:29.166119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:28.518526Z","time spent":"647.470814ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:29.166155Z","caller":"traceutil/trace.go:171","msg":"trace[480508970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:988; }","duration":"591.644813ms","start":"2026-02-25T19:33:28.574494Z","end":"2026-02-25T19:33:29.166139Z","steps":["trace[480508970] 'agreement among raft nodes before linearized reading' (duration: 591.466558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:29.166184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:28.574477Z","time spent":"591.701204ms","remote":"127.0.0.1:44886","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-25T19:33:29.166204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:28.518605Z","time spent":"647.467074ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:30.274384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.535841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:30.274484Z","caller":"traceutil/trace.go:171","msg":"trace[1476473788] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"780.031993ms","start":"2026-02-25T19:33:29.494435Z","end":"2026-02-25T19:33:30.274467Z","steps":["trace[1476473788] 'read index received' (duration: 109.203824ms)","trace[1476473788] 'applied index is now lower than readState.Index' (duration: 670.824849ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:30.274564Z","caller":"traceutil/trace.go:171","msg":"trace[574648384] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.104835486s","start":"2026-02-25T19:33:29.169717Z","end":"2026-02-25T19:33:30.274552Z","steps":["trace[574648384] 'process raft request' (duration: 434.041988ms)","trace[574648384] 'compare' (duration: 669.593428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:30.274619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:29.169675Z","time spent":"1.104913248s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:30.2747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.648005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:30.274772Z","caller":"traceutil/trace.go:171","msg":"trace[2137088332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"419.738477ms","start":"2026-02-25T19:33:29.855014Z","end":"2026-02-25T19:33:30.274753Z","steps":["trace[2137088332] 'agreement among raft nodes before linearized reading' (duration: 419.570173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:30.274772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.33859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-02-25T19:33:30.274817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:29.854957Z","time spent":"419.85199ms","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:33:30.274826Z","caller":"traceutil/trace.go:171","msg":"trace[1882415753] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:989; }","duration":"780.436122ms","start":"2026-02-25T19:33:29.494374Z","end":"2026-02-25T19:33:30.27481Z","steps":["trace[1882415753] 'agreement among raft nodes before linearized reading' (duration: 780.227727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:30.274867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:29.494343Z","time spent":"780.514994ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T19:33:31.359229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:31.859959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:31.960371Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172376608s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:33:31.960764Z","caller":"traceutil/trace.go:171","msg":"trace[1673610109] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"1.102553561s","start":"2026-02-25T19:33:30.858188Z","end":"2026-02-25T19:33:31.960742Z","steps":["trace[1673610109] 'read index received' (duration: 1.102413838s)","trace[1673610109] 'applied index is now lower than readState.Index' (duration: 138.363µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:31.960801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:30.78776Z","time spent":"1.173032654s","remote":"127.0.0.1:44634","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T19:33:31.961011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102752906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:33:31.961035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.085069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:33:31.961035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.321193ms","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-25T19:33:31.961165Z","caller":"traceutil/trace.go:171","msg":"trace[796476422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"964.514428ms","start":"2026-02-25T19:33:30.996639Z","end":"2026-02-25T19:33:31.961153Z","steps":["trace[796476422] 'agreement among raft nodes before linearized reading' (duration: 964.256931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:31.961155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.271803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:33:31.961097Z","caller":"traceutil/trace.go:171","msg":"trace[919058195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"1.10291985s","start":"2026-02-25T19:33:30.858145Z","end":"2026-02-25T19:33:31.961065Z","steps":["trace[919058195] 'agreement among raft nodes before linearized reading' (duration: 1.102680214s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:31.961223Z","caller":"traceutil/trace.go:171","msg":"trace[1636275450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"790.397347ms","start":"2026-02-25T19:33:31.170806Z","end":"2026-02-25T19:33:31.961203Z","steps":["trace[1636275450] 'agreement among raft nodes before linearized reading' (duration: 790.172851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:31.961235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:30.85813Z","time spent":"1.103095314s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:31.961227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:30.996618Z","time spent":"964.59398ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T19:33:31.961227Z","caller":"traceutil/trace.go:171","msg":"trace[514121884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"790.263153ms","start":"2026-02-25T19:33:31.170877Z","end":"2026-02-25T19:33:31.96114Z","steps":["trace[514121884] 'agreement among raft nodes before linearized reading' (duration: 790.040068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:31.961342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:31.170858Z","time spent":"790.472748ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:33:31.961289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:31.170784Z","time spent":"790.496719ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:33:32.469692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148942,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:32.970821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148942,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:33.471996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148942,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:33.969173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000060559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:33:33.969266Z","caller":"traceutil/trace.go:171","msg":"trace[1834028422] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000215373s","start":"2026-02-25T19:33:31.969032Z","end":"2026-02-25T19:33:33.969247Z","steps":["trace[1834028422] 'agreement among raft nodes before linearized reading' (duration: 2.00008774s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:33.969313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:31.969019Z","time spent":"2.000285034s","remote":"127.0.0.1:44584","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-25T19:33:33.972701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523327675148942,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:34.642378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.281883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:34.64251Z","caller":"traceutil/trace.go:171","msg":"trace[2117433726] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"2.679672515s","start":"2026-02-25T19:33:31.962815Z","end":"2026-02-25T19:33:34.642487Z","steps":["trace[2117433726] 'process raft request' (duration: 2.383218798s)","trace[2117433726] 'compare' (duration: 295.484283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:34.642953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:31.962804Z","time spent":"2.679736116s","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:34.648929Z","caller":"traceutil/trace.go:171","msg":"trace[1803589508] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"2.682808641s","start":"2026-02-25T19:33:31.966096Z","end":"2026-02-25T19:33:34.648905Z","steps":["trace[1803589508] 'process raft request' (duration: 2.682649848s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:34.649074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:31.966075Z","time spent":"2.682923384s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:34.649074Z","caller":"traceutil/trace.go:171","msg":"trace[1425702511] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"2.682878663s","start":"2026-02-25T19:33:31.96618Z","end":"2026-02-25T19:33:34.649059Z","steps":["trace[1425702511] 'process raft request' (duration: 2.682689549s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:34.649164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:31.966173Z","time spent":"2.682949815s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:34.649237Z","caller":"traceutil/trace.go:171","msg":"trace[407203631] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1036; }","duration":"2.680154436s","start":"2026-02-25T19:33:31.969072Z","end":"2026-02-25T19:33:34.649226Z","steps":["trace[407203631] 'read index received' (duration: 2.377013925s)","trace[407203631] 'applied index is now lower than readState.Index' (duration: 303.139841ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:34.649297Z","caller":"traceutil/trace.go:171","msg":"trace[1748041651] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"2.682065983s","start":"2026-02-25T19:33:31.967223Z","end":"2026-02-25T19:33:34.649289Z","steps":["trace[1748041651] 'process raft request' (duration: 2.681774966s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:34.649334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:31.96721Z","time spent":"2.682105504s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:34.649379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.370389792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:33:34.649414Z","caller":"traceutil/trace.go:171","msg":"trace[224562933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"2.370471184s","start":"2026-02-25T19:33:32.278932Z","end":"2026-02-25T19:33:34.649404Z","steps":["trace[224562933] 'agreement among raft nodes before linearized reading' (duration: 2.370363051s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:34.649437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:32.278907Z","time spent":"2.370523925s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:33:34.649436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.916292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:34.649464Z","caller":"traceutil/trace.go:171","msg":"trace[955995565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"679.967654ms","start":"2026-02-25T19:33:33.969488Z","end":"2026-02-25T19:33:34.649456Z","steps":["trace[955995565] 'agreement among raft nodes before linearized reading' (duration: 679.922683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:34.649484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:33.969474Z","time spent":"680.006165ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:34.649617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17044423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:34.649637Z","caller":"traceutil/trace.go:171","msg":"trace[2107943155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"1.170509401s","start":"2026-02-25T19:33:33.479121Z","end":"2026-02-25T19:33:34.64963Z","steps":["trace[2107943155] 'agreement among raft nodes before linearized reading' (duration: 1.17047341s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:34.649654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:33.479093Z","time spent":"1.170557583s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:34.649806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.123741999s","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-25T19:33:34.649843Z","caller":"traceutil/trace.go:171","msg":"trace[384020181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"2.123817071s","start":"2026-02-25T19:33:32.526016Z","end":"2026-02-25T19:33:34.649833Z","steps":["trace[384020181] 'agreement among raft nodes before linearized reading' (duration: 2.123725859s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:34.649867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:32.525995Z","time spent":"2.123867143s","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:36:33.694123Z","caller":"traceutil/trace.go:171","msg":"trace[749874059] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"165.478742ms","start":"2026-02-25T19:36:33.528619Z","end":"2026-02-25T19:36:33.694098Z","steps":["trace[749874059] 'process raft request' (duration: 165.334849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:36:33.695461Z","caller":"traceutil/trace.go:171","msg":"trace[1195966165] linearizableReadLoop","detail":"{readStateIndex:1860; appliedIndex:1858; }","duration":"161.653128ms","start":"2026-02-25T19:36:33.533776Z","end":"2026-02-25T19:36:33.69543Z","steps":["trace[1195966165] 'read index received' (duration: 160.260054ms)","trace[1195966165] 'applied index is now lower than readState.Index' (duration: 1.391624ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:36:33.695612Z","caller":"traceutil/trace.go:171","msg":"trace[142681698] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"165.840941ms","start":"2026-02-25T19:36:33.529757Z","end":"2026-02-25T19:36:33.695598Z","steps":["trace[142681698] 'process raft request' (duration: 165.512493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:36:33.695871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.071469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-25T19:36:33.695906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.589788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T19:36:33.695947Z","caller":"traceutil/trace.go:171","msg":"trace[1888536794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1778; }","duration":"162.156451ms","start":"2026-02-25T19:36:33.533771Z","end":"2026-02-25T19:36:33.695928Z","steps":["trace[1888536794] 'agreement among raft nodes before linearized reading' (duration: 161.965337ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:36:33.695978Z","caller":"traceutil/trace.go:171","msg":"trace[1702447023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1778; }","duration":"118.712652ms","start":"2026-02-25T19:36:33.577244Z","end":"2026-02-25T19:36:33.695957Z","steps":["trace[1702447023] 'agreement among raft nodes before linearized reading' (duration: 118.560378ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:36:34.428543Z","caller":"traceutil/trace.go:171","msg":"trace[7691375] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"379.535191ms","start":"2026-02-25T19:36:34.048987Z","end":"2026-02-25T19:36:34.428522Z","steps":["trace[7691375] 'process raft request' (duration: 379.419078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:36:34.428699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:36:34.048974Z","time spent":"379.626093ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}