{"level":"warn","ts":"2026-02-18T14:44:27.2339Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T14:44:27.234221Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.111: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.111:2380","--initial-cluster=instance=https://199.19.213.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.111:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-18T14:44:27.234359Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T14:44:27.234405Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-02-18T14:44:27.235368Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-18T14:44:27.237942Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"]} {"level":"info","ts":"2026-02-18T14:44:27.239512Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.111:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-18T14:44:27.2469Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.088889ms"} {"level":"info","ts":"2026-02-18T14:44:27.271325Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4edbe0dc99dceaa8","cluster-id":"9f725a00b80fcc82"} {"level":"info","ts":"2026-02-18T14:44:27.271425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T14:44:27.271458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 0"} {"level":"info","ts":"2026-02-18T14:44:27.271484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4edbe0dc99dceaa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T14:44:27.271519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 1"} {"level":"info","ts":"2026-02-18T14:44:27.271593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"warn","ts":"2026-02-18T14:44:27.280055Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T14:44:27.28853Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T14:44:27.298244Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-18T14:44:27.310143Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4edbe0dc99dceaa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T14:44:27.334257Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-18T14:44:27.334631Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4edbe0dc99dceaa8","initial-advertise-peer-urls":["https://199.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T14:44:27.334787Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T14:44:27.336022Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-02-18T14:44:27.336037Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-02-18T14:44:27.336938Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4edbe0dc99dceaa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T14:44:27.339364Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T14:44:27.339398Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T14:44:27.339405Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T14:44:27.340545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"info","ts":"2026-02-18T14:44:27.340706Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","added-peer-id":"4edbe0dc99dceaa8","added-peer-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-02-18T14:44:28.172136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T14:44:28.172182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T14:44:28.172217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgPreVoteResp from 4edbe0dc99dceaa8 at term 1"} {"level":"info","ts":"2026-02-18T14:44:28.172231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became candidate at term 2"} {"level":"info","ts":"2026-02-18T14:44:28.172239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgVoteResp from 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-02-18T14:44:28.172249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became leader at term 2"} {"level":"info","ts":"2026-02-18T14:44:28.172258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4edbe0dc99dceaa8 elected leader 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-02-18T14:44:28.174155Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4edbe0dc99dceaa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.111:2379]}","request-path":"/0/members/4edbe0dc99dceaa8/attributes","cluster-id":"9f725a00b80fcc82","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T14:44:28.174314Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:44:28.175265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:44:28.175366Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T14:44:28.175458Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T14:44:28.175538Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:44:28.175544Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:44:28.176152Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:44:28.176999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.111:2379"} {"level":"info","ts":"2026-02-18T14:44:28.178651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T14:44:28.179916Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-18T14:44:40.048846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.637587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:44:40.049485Z","caller":"traceutil/trace.go:171","msg":"trace[1473830386] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"439.252205ms","start":"2026-02-18T14:44:39.610213Z","end":"2026-02-18T14:44:40.049465Z","steps":["trace[1473830386] 'process raft request' (duration: 309.437006ms)","trace[1473830386] 'compare' (duration: 127.851057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:40.0499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:39.610193Z","time spent":"439.668167ms","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:40.272571Z","caller":"traceutil/trace.go:171","msg":"trace[1360612400] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"216.026447ms","start":"2026-02-18T14:44:40.056526Z","end":"2026-02-18T14:44:40.272552Z","steps":["trace[1360612400] 'process raft request' (duration: 172.495007ms)","trace[1360612400] 'compare' (duration: 43.442908ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:43.829976Z","caller":"traceutil/trace.go:171","msg":"trace[944684264] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"328.75725ms","start":"2026-02-18T14:44:43.501199Z","end":"2026-02-18T14:44:43.829956Z","steps":["trace[944684264] 'process raft request' (duration: 249.250851ms)","trace[944684264] 'compare' (duration: 79.396006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:43.830094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:43.501182Z","time spent":"328.859663ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:46.145362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.755038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-18T14:44:46.145444Z","caller":"traceutil/trace.go:171","msg":"trace[356685154] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:381; }","duration":"260.887582ms","start":"2026-02-18T14:44:45.884538Z","end":"2026-02-18T14:44:46.145426Z","steps":["trace[356685154] 'range keys from in-memory index tree' (duration: 260.657266ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:49.470096Z","caller":"traceutil/trace.go:171","msg":"trace[1692811201] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"288.937339ms","start":"2026-02-18T14:44:49.181121Z","end":"2026-02-18T14:44:49.470058Z","steps":["trace[1692811201] 'process raft request' (duration: 288.676962ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:51.889492Z","caller":"traceutil/trace.go:171","msg":"trace[936280944] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"407.320303ms","start":"2026-02-18T14:44:51.482156Z","end":"2026-02-18T14:44:51.889476Z","steps":["trace[936280944] 'read index received' (duration: 407.19538ms)","trace[936280944] 'applied index is now lower than readState.Index' (duration: 123.813µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:51.889555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:51.269939Z","time spent":"619.614201ms","remote":"127.0.0.1:40782","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T14:44:51.889728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.56618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T14:44:51.889744Z","caller":"traceutil/trace.go:171","msg":"trace[343800449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"407.631942ms","start":"2026-02-18T14:44:51.482106Z","end":"2026-02-18T14:44:51.889738Z","steps":["trace[343800449] 'agreement among raft nodes before linearized reading' (duration: 407.582771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:51.889758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:51.482092Z","time spent":"407.662873ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:51.889758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.248162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:44:51.889803Z","caller":"traceutil/trace.go:171","msg":"trace[419311245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"392.335325ms","start":"2026-02-18T14:44:51.497455Z","end":"2026-02-18T14:44:51.88979Z","steps":["trace[419311245] 'agreement among raft nodes before linearized reading' (duration: 392.254463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:51.889834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:51.497429Z","time spent":"392.397766ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:44:51.889917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.536096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:44:51.88993Z","caller":"traceutil/trace.go:171","msg":"trace[249616519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"390.580507ms","start":"2026-02-18T14:44:51.499346Z","end":"2026-02-18T14:44:51.889926Z","steps":["trace[249616519] 'agreement among raft nodes before linearized reading' (duration: 390.554587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:51.889942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:51.499329Z","time spent":"390.609829ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:44:52.835985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.334609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:44:52.836126Z","caller":"traceutil/trace.go:171","msg":"trace[17820972] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"944.286353ms","start":"2026-02-18T14:44:51.891822Z","end":"2026-02-18T14:44:52.836109Z","steps":["trace[17820972] 'process raft request' (duration: 773.755889ms)","trace[17820972] 'compare' (duration: 170.211296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:52.83619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:51.891803Z","time spent":"944.355716ms","remote":"127.0.0.1:40782","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-18T14:44:52.843166Z","caller":"traceutil/trace.go:171","msg":"trace[1341656313] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:443; }","duration":"602.619429ms","start":"2026-02-18T14:44:52.240494Z","end":"2026-02-18T14:44:52.843113Z","steps":["trace[1341656313] 'read index received' (duration: 425.097029ms)","trace[1341656313] 'applied index is now lower than readState.Index' (duration: 177.52082ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:52.843593Z","caller":"traceutil/trace.go:171","msg":"trace[1144980516] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"950.822567ms","start":"2026-02-18T14:44:51.892757Z","end":"2026-02-18T14:44:52.84358Z","steps":["trace[1144980516] 'process raft request' (duration: 949.825131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:52.843733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:51.892751Z","time spent":"950.881949ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:52.844119Z","caller":"traceutil/trace.go:171","msg":"trace[1491608422] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"950.55295ms","start":"2026-02-18T14:44:51.893556Z","end":"2026-02-18T14:44:52.844109Z","steps":["trace[1491608422] 'process raft request' (duration: 949.40304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:52.844166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:51.893549Z","time spent":"950.591801ms","remote":"127.0.0.1:41004","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-18T14:44:52.844283Z","caller":"traceutil/trace.go:171","msg":"trace[2116244751] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"946.252726ms","start":"2026-02-18T14:44:51.898022Z","end":"2026-02-18T14:44:52.844274Z","steps":["trace[2116244751] 'process raft request' (duration: 945.028693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:52.844332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:51.897999Z","time spent":"946.309477ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:52.844454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.958384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:52.844479Z","caller":"traceutil/trace.go:171","msg":"trace[1001136888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"604.019006ms","start":"2026-02-18T14:44:52.240452Z","end":"2026-02-18T14:44:52.844471Z","steps":["trace[1001136888] 'agreement among raft nodes before linearized reading' (duration: 603.975065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:52.844501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:52.240336Z","time spent":"604.16029ms","remote":"127.0.0.1:40760","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-18T14:44:52.844673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.977991ms","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-18T14:44:52.844717Z","caller":"traceutil/trace.go:171","msg":"trace[38945903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"453.062823ms","start":"2026-02-18T14:44:52.391641Z","end":"2026-02-18T14:44:52.844704Z","steps":["trace[38945903] 'agreement among raft nodes before linearized reading' (duration: 452.991741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:52.844748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:52.391622Z","time spent":"453.117554ms","remote":"127.0.0.1:41004","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-18T14:45:01.425329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908936811007839020,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:45:01.917578Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.003204387s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:45:01.917819Z","caller":"traceutil/trace.go:171","msg":"trace[1741690696] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.003493524s","start":"2026-02-18T14:45:00.914309Z","end":"2026-02-18T14:45:01.917803Z","steps":["trace[1741690696] 'process raft request' (duration: 1.003394721s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:01.917922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:00.914301Z","time spent":"1.003566676s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:01.919532Z","caller":"traceutil/trace.go:171","msg":"trace[1493410964] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"995.150152ms","start":"2026-02-18T14:45:00.924366Z","end":"2026-02-18T14:45:01.919516Z","steps":["trace[1493410964] 'read index received' (duration: 993.837707ms)","trace[1493410964] 'applied index is now lower than readState.Index' (duration: 1.311835ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:01.919715Z","caller":"traceutil/trace.go:171","msg":"trace[691222595] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.004700746s","start":"2026-02-18T14:45:00.915005Z","end":"2026-02-18T14:45:01.919705Z","steps":["trace[691222595] 'process raft request' (duration: 1.004427119s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:01.919817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:00.914986Z","time spent":"1.004779958s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:01.919967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.597984ms","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-18T14:45:01.919995Z","caller":"traceutil/trace.go:171","msg":"trace[2030911167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"995.625935ms","start":"2026-02-18T14:45:00.924362Z","end":"2026-02-18T14:45:01.919988Z","steps":["trace[2030911167] 'agreement among raft nodes before linearized reading' (duration: 995.575443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:01.920047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:00.924338Z","time spent":"995.704676ms","remote":"127.0.0.1:41004","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-18T14:45:01.920146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.476038ms","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-18T14:45:01.920169Z","caller":"traceutil/trace.go:171","msg":"trace[701065272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"990.496818ms","start":"2026-02-18T14:45:00.929666Z","end":"2026-02-18T14:45:01.920163Z","steps":["trace[701065272] 'agreement among raft nodes before linearized reading' (duration: 990.458937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:01.920187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:00.929651Z","time spent":"990.530509ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:45:01.920316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.345038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-18T14:45:01.920339Z","caller":"traceutil/trace.go:171","msg":"trace[250561918] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:476; }","duration":"620.390949ms","start":"2026-02-18T14:45:01.299943Z","end":"2026-02-18T14:45:01.920334Z","steps":["trace[250561918] 'agreement among raft nodes before linearized reading' (duration: 620.349898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:01.920359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:01.299933Z","time spent":"620.42305ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T14:45:01.920617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.589112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.111\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:45:01.920656Z","caller":"traceutil/trace.go:171","msg":"trace[11293192] range","detail":"{range_begin:/registry/masterleases/199.19.213.111; range_end:; response_count:1; response_revision:476; }","duration":"650.631123ms","start":"2026-02-18T14:45:01.270018Z","end":"2026-02-18T14:45:01.920649Z","steps":["trace[11293192] 'agreement among raft nodes before linearized reading' (duration: 650.561842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:01.920675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:01.269948Z","time spent":"650.722646ms","remote":"127.0.0.1:40782","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.111\" "} {"level":"warn","ts":"2026-02-18T14:45:01.92069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.722267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:01.920717Z","caller":"traceutil/trace.go:171","msg":"trace[185802816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"682.773029ms","start":"2026-02-18T14:45:01.237935Z","end":"2026-02-18T14:45:01.920708Z","steps":["trace[185802816] 'agreement among raft nodes before linearized reading' (duration: 682.729468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:01.920739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:01.23792Z","time spent":"682.813991ms","remote":"127.0.0.1:40760","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-18T14:45:08.73761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908936811007839063,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:45:09.238013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908936811007839063,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:45:09.703931Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.631156556s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:45:09.704346Z","caller":"traceutil/trace.go:171","msg":"trace[1787686454] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.631589748s","start":"2026-02-18T14:45:08.072725Z","end":"2026-02-18T14:45:09.704315Z","steps":["trace[1787686454] 'process raft request' (duration: 1.631477945s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:09.704494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:08.072715Z","time spent":"1.631714301s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:09.706419Z","caller":"traceutil/trace.go:171","msg":"trace[1566842324] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"1.469170446s","start":"2026-02-18T14:45:08.237235Z","end":"2026-02-18T14:45:09.706405Z","steps":["trace[1566842324] 'read index received' (duration: 1.467696247s)","trace[1566842324] 'applied index is now lower than readState.Index' (duration: 1.473539ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:09.707163Z","caller":"traceutil/trace.go:171","msg":"trace[1719039787] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.634334351s","start":"2026-02-18T14:45:08.072814Z","end":"2026-02-18T14:45:09.707149Z","steps":["trace[1719039787] 'process raft request' (duration: 1.63352977s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:09.707251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:08.072808Z","time spent":"1.634404033s","remote":"127.0.0.1:41004","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-18T14:45:09.707404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470170403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:09.707435Z","caller":"traceutil/trace.go:171","msg":"trace[1969415114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"1.470220034s","start":"2026-02-18T14:45:08.237205Z","end":"2026-02-18T14:45:09.707425Z","steps":["trace[1969415114] 'agreement among raft nodes before linearized reading' (duration: 1.470173783s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:09.707462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:08.237193Z","time spent":"1.470264555s","remote":"127.0.0.1:40760","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-18T14:45:10.390779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908936811007839068,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:45:10.470696Z","caller":"traceutil/trace.go:171","msg":"trace[985007341] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"580.043426ms","start":"2026-02-18T14:45:09.890632Z","end":"2026-02-18T14:45:10.470676Z","steps":["trace[985007341] 'read index received' (duration: 579.892122ms)","trace[985007341] 'applied index is now lower than readState.Index' (duration: 150.384µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:10.470806Z","caller":"traceutil/trace.go:171","msg":"trace[1676783916] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"610.393484ms","start":"2026-02-18T14:45:09.8604Z","end":"2026-02-18T14:45:10.470793Z","steps":["trace[1676783916] 'process raft request' (duration: 610.168688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:10.470893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:09.860386Z","time spent":"610.450355ms","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:45:10.471201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.558869ms","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-18T14:45:10.471236Z","caller":"traceutil/trace.go:171","msg":"trace[690735584] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:496; }","duration":"580.59692ms","start":"2026-02-18T14:45:09.890629Z","end":"2026-02-18T14:45:10.471226Z","steps":["trace[690735584] 'agreement among raft nodes before linearized reading' (duration: 580.473107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:10.471264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:09.89059Z","time spent":"580.667292ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-18T14:45:10.471446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.815328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:45:10.471498Z","caller":"traceutil/trace.go:171","msg":"trace[1793147797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"485.87064ms","start":"2026-02-18T14:45:09.985596Z","end":"2026-02-18T14:45:10.471466Z","steps":["trace[1793147797] 'agreement among raft nodes before linearized reading' (duration: 485.817208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:10.471527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:09.98558Z","time spent":"485.941272ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:45:10.47191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.200432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:45:10.471944Z","caller":"traceutil/trace.go:171","msg":"trace[485957820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"476.250994ms","start":"2026-02-18T14:45:09.995684Z","end":"2026-02-18T14:45:10.471935Z","steps":["trace[485957820] 'agreement among raft nodes before linearized reading' (duration: 476.190983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:10.471971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:09.995677Z","time spent":"476.288555ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:45:11.07144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.529909ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:11.07153Z","caller":"traceutil/trace.go:171","msg":"trace[534471574] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:496; }","duration":"211.632552ms","start":"2026-02-18T14:45:10.859884Z","end":"2026-02-18T14:45:11.071517Z","steps":["trace[534471574] 'range keys from in-memory index tree' (duration: 211.511549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:11.071733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.209382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:11.071771Z","caller":"traceutil/trace.go:171","msg":"trace[282718156] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"591.438519ms","start":"2026-02-18T14:45:10.480326Z","end":"2026-02-18T14:45:11.071764Z","steps":["trace[282718156] 'process raft request' (duration: 106.145685ms)","trace[282718156] 'compare' (duration: 484.717869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:11.071804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:10.480307Z","time spent":"591.48112ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:11.077529Z","caller":"traceutil/trace.go:171","msg":"trace[1525678218] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"593.540774ms","start":"2026-02-18T14:45:10.483978Z","end":"2026-02-18T14:45:11.077519Z","steps":["trace[1525678218] 'read index received' (duration: 102.502568ms)","trace[1525678218] 'applied index is now lower than readState.Index' (duration: 491.037426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:11.077616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.629667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-18T14:45:11.07764Z","caller":"traceutil/trace.go:171","msg":"trace[359647982] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:499; }","duration":"593.674398ms","start":"2026-02-18T14:45:10.483959Z","end":"2026-02-18T14:45:11.077633Z","steps":["trace[359647982] 'agreement among raft nodes before linearized reading' (duration: 593.611807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:11.077663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:10.48395Z","time spent":"593.707819ms","remote":"127.0.0.1:40838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6557,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"info","ts":"2026-02-18T14:45:11.078088Z","caller":"traceutil/trace.go:171","msg":"trace[1022880638] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"597.641414ms","start":"2026-02-18T14:45:10.48042Z","end":"2026-02-18T14:45:11.078061Z","steps":["trace[1022880638] 'process raft request' (duration: 597.005277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:11.078235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:10.480397Z","time spent":"597.769528ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:11.078321Z","caller":"traceutil/trace.go:171","msg":"trace[185130034] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"585.87478ms","start":"2026-02-18T14:45:10.492435Z","end":"2026-02-18T14:45:11.07831Z","steps":["trace[185130034] 'process raft request' (duration: 585.045678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:11.078395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:10.492424Z","time spent":"585.936263ms","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:11.085521Z","caller":"traceutil/trace.go:171","msg":"trace[819987878] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"269.611085ms","start":"2026-02-18T14:45:10.815898Z","end":"2026-02-18T14:45:11.085509Z","steps":["trace[819987878] 'process raft request' (duration: 269.523833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:15.766163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.474976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:15.766385Z","caller":"traceutil/trace.go:171","msg":"trace[2116359397] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"102.483347ms","start":"2026-02-18T14:45:15.663881Z","end":"2026-02-18T14:45:15.766365Z","steps":["trace[2116359397] 'read index received' (duration: 22.37µs)","trace[2116359397] 'applied index is now lower than readState.Index' (duration: 102.459397ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:15.766558Z","caller":"traceutil/trace.go:171","msg":"trace[1261450277] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"192.665317ms","start":"2026-02-18T14:45:15.57388Z","end":"2026-02-18T14:45:15.766545Z","steps":["trace[1261450277] 'process raft request' (duration: 71.711058ms)","trace[1261450277] 'compare' (duration: 120.292281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:15.767071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.176705ms","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-18T14:45:15.767183Z","caller":"traceutil/trace.go:171","msg":"trace[1320297704] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"103.315269ms","start":"2026-02-18T14:45:15.663853Z","end":"2026-02-18T14:45:15.767168Z","steps":["trace[1320297704] 'agreement among raft nodes before linearized reading' (duration: 103.106124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:24.094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.68264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:24.094131Z","caller":"traceutil/trace.go:171","msg":"trace[1644931978] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"416.655748ms","start":"2026-02-18T14:45:23.677462Z","end":"2026-02-18T14:45:24.094118Z","steps":["trace[1644931978] 'process raft request' (duration: 416.610187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:24.094174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:23.677456Z","time spent":"416.698019ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:24.095969Z","caller":"traceutil/trace.go:171","msg":"trace[991147435] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"420.597633ms","start":"2026-02-18T14:45:23.675359Z","end":"2026-02-18T14:45:24.095957Z","steps":["trace[991147435] 'process raft request' (duration: 276.898029ms)","trace[991147435] 'compare' (duration: 141.546607ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:24.09605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:23.675348Z","time spent":"420.655104ms","remote":"127.0.0.1:41004","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-18T14:45:25.160051Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.210109453s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:45:26.240561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999825447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:45:26.240625Z","caller":"traceutil/trace.go:171","msg":"trace[1417016434] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99993285s","start":"2026-02-18T14:45:24.240678Z","end":"2026-02-18T14:45:26.240611Z","steps":["trace[1417016434] 'agreement among raft nodes before linearized reading' (duration: 1.999853217s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:26.240655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:24.24066Z","time spent":"1.999990071s","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:45:26.529705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369456961s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:26.529981Z","caller":"traceutil/trace.go:171","msg":"trace[512105465] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.895078059s","start":"2026-02-18T14:45:24.634885Z","end":"2026-02-18T14:45:26.529963Z","steps":["trace[512105465] 'process raft request' (duration: 1.895036527s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:26.530044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:24.634869Z","time spent":"1.895144911s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:26.530341Z","caller":"traceutil/trace.go:171","msg":"trace[657952362] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"2.842490511s","start":"2026-02-18T14:45:23.687838Z","end":"2026-02-18T14:45:26.530328Z","steps":["trace[657952362] 'process raft request' (duration: 1.472328421s)","trace[657952362] 'compare' (duration: 1.368816944s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:26.530397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:23.687824Z","time spent":"2.842543312s","remote":"127.0.0.1:40900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1018,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:26.530602Z","caller":"traceutil/trace.go:171","msg":"trace[1007826129] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"2.839786529s","start":"2026-02-18T14:45:23.690805Z","end":"2026-02-18T14:45:26.530592Z","steps":["trace[1007826129] 'process raft request' (duration: 2.838983928s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:26.530654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:23.690799Z","time spent":"2.83982655s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:26.530825Z","caller":"traceutil/trace.go:171","msg":"trace[1262459134] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"2.839869581s","start":"2026-02-18T14:45:23.690947Z","end":"2026-02-18T14:45:26.530816Z","steps":["trace[1262459134] 'process raft request' (duration: 2.838899515s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:26.530875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:23.690943Z","time spent":"2.839904702s","remote":"127.0.0.1:41018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:26.53099Z","caller":"traceutil/trace.go:171","msg":"trace[1156704779] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"2.811495436s","start":"2026-02-18T14:45:23.719487Z","end":"2026-02-18T14:45:26.530982Z","steps":["trace[1156704779] 'process raft request' (duration: 2.810395776s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:26.531059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:23.719469Z","time spent":"2.811542477s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:26.531173Z","caller":"traceutil/trace.go:171","msg":"trace[233158640] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:676; }","duration":"2.632334908s","start":"2026-02-18T14:45:23.898829Z","end":"2026-02-18T14:45:26.531164Z","steps":["trace[233158640] 'read index received' (duration: 53.436602ms)","trace[233158640] 'applied index is now lower than readState.Index' (duration: 2.578897196s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:26.531529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.632694498s","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-18T14:45:26.531562Z","caller":"traceutil/trace.go:171","msg":"trace[114846239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"2.632748869s","start":"2026-02-18T14:45:23.898804Z","end":"2026-02-18T14:45:26.531552Z","steps":["trace[114846239] 'agreement among raft nodes before linearized reading' (duration: 2.632641376s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:26.531591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:23.89879Z","time spent":"2.632795101s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:45:27.031731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908936811007839364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:45:27.710476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.180154ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T14:45:27.710801Z","caller":"traceutil/trace.go:171","msg":"trace[1275833699] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:683; }","duration":"1.179589901s","start":"2026-02-18T14:45:26.531194Z","end":"2026-02-18T14:45:27.710784Z","steps":["trace[1275833699] 'read index received' (duration: 854.100339ms)","trace[1275833699] 'applied index is now lower than readState.Index' (duration: 325.488972ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:27.710828Z","caller":"traceutil/trace.go:171","msg":"trace[42538623] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.163299006s","start":"2026-02-18T14:45:26.547513Z","end":"2026-02-18T14:45:27.710812Z","steps":["trace[42538623] 'process raft request' (duration: 1.163233925s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:27.710913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:26.547504Z","time spent":"1.163363019s","remote":"127.0.0.1:41004","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-18T14:45:27.710929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469981768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:27.710955Z","caller":"traceutil/trace.go:171","msg":"trace[873760141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"1.470036439s","start":"2026-02-18T14:45:26.240911Z","end":"2026-02-18T14:45:27.710947Z","steps":["trace[873760141] 'agreement among raft nodes before linearized reading' (duration: 1.469968467s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:27.710978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:26.240901Z","time spent":"1.470071821s","remote":"127.0.0.1:40764","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-18T14:45:27.711218Z","caller":"traceutil/trace.go:171","msg":"trace[754864113] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.168347191s","start":"2026-02-18T14:45:26.542862Z","end":"2026-02-18T14:45:27.71121Z","steps":["trace[754864113] 'process raft request' (duration: 1.167758765s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:27.711256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604058376s","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-18T14:45:27.71126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:26.542847Z","time spent":"1.168391163s","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:27.711277Z","caller":"traceutil/trace.go:171","msg":"trace[1939257324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.604096897s","start":"2026-02-18T14:45:26.107174Z","end":"2026-02-18T14:45:27.711271Z","steps":["trace[1939257324] 'agreement among raft nodes before linearized reading' (duration: 1.604011894s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:27.711298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:26.107161Z","time spent":"1.604132918s","remote":"127.0.0.1:41004","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-18T14:45:27.711519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.533689743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T14:45:27.71154Z","caller":"traceutil/trace.go:171","msg":"trace[1273147047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"2.533741595s","start":"2026-02-18T14:45:25.177793Z","end":"2026-02-18T14:45:27.711535Z","steps":["trace[1273147047] 'agreement among raft nodes before linearized reading' (duration: 2.533633562s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:27.711557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:25.177771Z","time spent":"2.533782136s","remote":"127.0.0.1:41004","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-18T14:45:27.711606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.537517885s","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-18T14:45:27.71163Z","caller":"traceutil/trace.go:171","msg":"trace[546311007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"2.537568587s","start":"2026-02-18T14:45:25.174054Z","end":"2026-02-18T14:45:27.711622Z","steps":["trace[546311007] 'agreement among raft nodes before linearized reading' (duration: 2.537481915s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:27.711649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:25.174037Z","time spent":"2.537607978s","remote":"127.0.0.1:41004","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-18T14:45:27.711855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.807696986s","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-18T14:45:27.711877Z","caller":"traceutil/trace.go:171","msg":"trace[244956495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"3.807741388s","start":"2026-02-18T14:45:23.904129Z","end":"2026-02-18T14:45:27.71187Z","steps":["trace[244956495] 'agreement among raft nodes before linearized reading' (duration: 3.807681406s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:27.711894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:23.904117Z","time spent":"3.807772768s","remote":"127.0.0.1:41004","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-18T14:45:27.711977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167970241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-gz89t\" ","response":"range_response_count:1 size:4812"} {"level":"info","ts":"2026-02-18T14:45:27.712Z","caller":"traceutil/trace.go:171","msg":"trace[1858033306] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-gz89t; range_end:; response_count:1; response_revision:655; }","duration":"1.168010052s","start":"2026-02-18T14:45:26.543982Z","end":"2026-02-18T14:45:27.711992Z","steps":["trace[1858033306] 'agreement among raft nodes before linearized reading' (duration: 1.16794733s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:27.712048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:26.543974Z","time spent":"1.168069694s","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4835,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-gz89t\" "} {"level":"info","ts":"2026-02-18T14:45:34.071224Z","caller":"traceutil/trace.go:171","msg":"trace[1721780274] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"307.538814ms","start":"2026-02-18T14:45:33.763654Z","end":"2026-02-18T14:45:34.071193Z","steps":["trace[1721780274] 'process raft request' (duration: 307.370999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:34.07144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:33.763641Z","time spent":"307.708708ms","remote":"127.0.0.1:41004","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-18T14:45:34.249707Z","caller":"traceutil/trace.go:171","msg":"trace[429058705] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:718; }","duration":"459.149629ms","start":"2026-02-18T14:45:33.79054Z","end":"2026-02-18T14:45:34.249689Z","steps":["trace[429058705] 'read index received' (duration: 281.034799ms)","trace[429058705] 'applied index is now lower than readState.Index' (duration: 178.11411ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:34.249784Z","caller":"traceutil/trace.go:171","msg":"trace[2006407369] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"485.422727ms","start":"2026-02-18T14:45:33.764355Z","end":"2026-02-18T14:45:34.249777Z","steps":["trace[2006407369] 'process raft request' (duration: 483.607399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:34.249851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:33.764347Z","time spent":"485.452438ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:34.249905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.441083ms","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-18T14:45:34.249934Z","caller":"traceutil/trace.go:171","msg":"trace[655692016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"448.469964ms","start":"2026-02-18T14:45:33.801456Z","end":"2026-02-18T14:45:34.249926Z","steps":["trace[655692016] 'agreement among raft nodes before linearized reading' (duration: 448.383982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:34.249955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:33.801424Z","time spent":"448.525436ms","remote":"127.0.0.1:41004","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-18T14:45:34.250054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.61692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-18T14:45:34.250099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.303592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:45:34.250118Z","caller":"traceutil/trace.go:171","msg":"trace[105786698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"415.352183ms","start":"2026-02-18T14:45:33.834761Z","end":"2026-02-18T14:45:34.250113Z","steps":["trace[105786698] 'agreement among raft nodes before linearized reading' (duration: 415.299642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:34.250153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:33.834746Z","time spent":"415.385274ms","remote":"127.0.0.1:41004","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-18T14:45:34.250151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.610471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:45:34.250196Z","caller":"traceutil/trace.go:171","msg":"trace[1839110972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"459.675592ms","start":"2026-02-18T14:45:33.790515Z","end":"2026-02-18T14:45:34.250191Z","steps":["trace[1839110972] 'agreement among raft nodes before linearized reading' (duration: 459.59655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:34.250214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:33.790503Z","time spent":"459.706734ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T14:45:34.2501Z","caller":"traceutil/trace.go:171","msg":"trace[1005164021] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:687; }","duration":"285.670072ms","start":"2026-02-18T14:45:33.964399Z","end":"2026-02-18T14:45:34.250069Z","steps":["trace[1005164021] 'agreement among raft nodes before linearized reading' (duration: 285.633831ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:47.548439Z","caller":"traceutil/trace.go:171","msg":"trace[160022481] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"308.304465ms","start":"2026-02-18T14:45:47.240114Z","end":"2026-02-18T14:45:47.548418Z","steps":["trace[160022481] 'read index received' (duration: 308.084309ms)","trace[160022481] 'applied index is now lower than readState.Index' (duration: 219.196µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:47.548543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.413177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:47.548568Z","caller":"traceutil/trace.go:171","msg":"trace[937180734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"308.483349ms","start":"2026-02-18T14:45:47.240077Z","end":"2026-02-18T14:45:47.54856Z","steps":["trace[937180734] 'agreement among raft nodes before linearized reading' (duration: 308.424018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:47.54861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:47.240061Z","time spent":"308.542891ms","remote":"127.0.0.1:40760","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-18T14:45:47.548667Z","caller":"traceutil/trace.go:171","msg":"trace[1721931357] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"350.826515ms","start":"2026-02-18T14:45:47.197822Z","end":"2026-02-18T14:45:47.548648Z","steps":["trace[1721931357] 'process raft request' (duration: 350.427624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:47.548758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:47.197779Z","time spent":"350.926868ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:50.360072Z","caller":"traceutil/trace.go:171","msg":"trace[2127210672] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"118.635068ms","start":"2026-02-18T14:45:50.241415Z","end":"2026-02-18T14:45:50.36005Z","steps":["trace[2127210672] 'read index received' (duration: 118.383111ms)","trace[2127210672] 'applied index is now lower than readState.Index' (duration: 251.287µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:50.360153Z","caller":"traceutil/trace.go:171","msg":"trace[144618296] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"222.936473ms","start":"2026-02-18T14:45:50.137211Z","end":"2026-02-18T14:45:50.360147Z","steps":["trace[144618296] 'process raft request' (duration: 222.689357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:50.360288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.860014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:50.360309Z","caller":"traceutil/trace.go:171","msg":"trace[1741267265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"118.915754ms","start":"2026-02-18T14:45:50.241387Z","end":"2026-02-18T14:45:50.360303Z","steps":["trace[1741267265] 'agreement among raft nodes before linearized reading' (duration: 118.867483ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:50.522652Z","caller":"traceutil/trace.go:171","msg":"trace[1945141426] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"142.79265ms","start":"2026-02-18T14:45:50.37981Z","end":"2026-02-18T14:45:50.522603Z","steps":["trace[1945141426] 'read index received' (duration: 29.250409ms)","trace[1945141426] 'applied index is now lower than readState.Index' (duration: 113.541031ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:50.522762Z","caller":"traceutil/trace.go:171","msg":"trace[1010428806] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"156.800372ms","start":"2026-02-18T14:45:50.36595Z","end":"2026-02-18T14:45:50.52275Z","steps":["trace[1010428806] 'process raft request' (duration: 129.208158ms)","trace[1010428806] 'compare' (duration: 26.744642ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:50.523735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.753158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:50.523831Z","caller":"traceutil/trace.go:171","msg":"trace[598998369] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:738; }","duration":"142.880062ms","start":"2026-02-18T14:45:50.380934Z","end":"2026-02-18T14:45:50.523814Z","steps":["trace[598998369] 'agreement among raft nodes before linearized reading' (duration: 142.05859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:50.523945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.120695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-18T14:45:50.524331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.309961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:45:50.524391Z","caller":"traceutil/trace.go:171","msg":"trace[1212744060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"141.403932ms","start":"2026-02-18T14:45:50.382974Z","end":"2026-02-18T14:45:50.524378Z","steps":["trace[1212744060] 'agreement among raft nodes before linearized reading' (duration: 141.215927ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:50.52399Z","caller":"traceutil/trace.go:171","msg":"trace[1900657714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"144.196217ms","start":"2026-02-18T14:45:50.379779Z","end":"2026-02-18T14:45:50.523975Z","steps":["trace[1900657714] 'agreement among raft nodes before linearized reading' (duration: 144.067693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:51.382137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.33472ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:51.382254Z","caller":"traceutil/trace.go:171","msg":"trace[269677452] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:738; }","duration":"522.459213ms","start":"2026-02-18T14:45:50.859778Z","end":"2026-02-18T14:45:51.382237Z","steps":["trace[269677452] 'range keys from in-memory index tree' (duration: 522.321019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:51.382965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.308347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:51.383093Z","caller":"traceutil/trace.go:171","msg":"trace[128404960] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:773; }","duration":"109.881834ms","start":"2026-02-18T14:45:51.2732Z","end":"2026-02-18T14:45:51.383081Z","steps":["trace[128404960] 'read index received' (duration: 73.773913ms)","trace[128404960] 'applied index is now lower than readState.Index' (duration: 36.107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:51.383226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.029468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.111\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:45:51.383261Z","caller":"traceutil/trace.go:171","msg":"trace[378341321] range","detail":"{range_begin:/registry/masterleases/199.19.213.111; range_end:; response_count:1; response_revision:740; }","duration":"110.090989ms","start":"2026-02-18T14:45:51.273161Z","end":"2026-02-18T14:45:51.383252Z","steps":["trace[378341321] 'agreement among raft nodes before linearized reading' (duration: 109.963466ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:51.383543Z","caller":"traceutil/trace.go:171","msg":"trace[167339615] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"854.266582ms","start":"2026-02-18T14:45:50.529263Z","end":"2026-02-18T14:45:51.383529Z","steps":["trace[167339615] 'process raft request' (duration: 351.332369ms)","trace[167339615] 'compare' (duration: 501.878145ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:51.383613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:50.529242Z","time spent":"854.336494ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:51.383744Z","caller":"traceutil/trace.go:171","msg":"trace[1078808711] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"854.125498ms","start":"2026-02-18T14:45:50.52961Z","end":"2026-02-18T14:45:51.383735Z","steps":["trace[1078808711] 'process raft request' (duration: 853.421469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:51.383795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:50.529591Z","time spent":"854.1754ms","remote":"127.0.0.1:41004","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-18T14:45:52.664629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.989968ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-18T14:45:52.664725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:51.385819Z","time spent":"1.278902724s","remote":"127.0.0.1:40782","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T14:45:52.707446Z","caller":"traceutil/trace.go:171","msg":"trace[421804679] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"822.990641ms","start":"2026-02-18T14:45:51.884439Z","end":"2026-02-18T14:45:52.70743Z","steps":["trace[421804679] 'process raft request' (duration: 822.869388ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:52.707499Z","caller":"traceutil/trace.go:171","msg":"trace[1666138668] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:777; }","duration":"465.731524ms","start":"2026-02-18T14:45:52.241747Z","end":"2026-02-18T14:45:52.707478Z","steps":["trace[1666138668] 'read index received' (duration: 465.722463ms)","trace[1666138668] 'applied index is now lower than readState.Index' (duration: 8.141µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:52.707563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:51.884414Z","time spent":"823.092413ms","remote":"127.0.0.1:41004","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-18T14:45:52.707688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.919929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:52.707713Z","caller":"traceutil/trace.go:171","msg":"trace[899463973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"465.998921ms","start":"2026-02-18T14:45:52.241707Z","end":"2026-02-18T14:45:52.707706Z","steps":["trace[899463973] 'agreement among raft nodes before linearized reading' (duration: 465.861147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:52.707739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:52.241685Z","time spent":"466.048243ms","remote":"127.0.0.1:40760","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-18T14:45:52.718854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.939725ms","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-18T14:45:52.718907Z","caller":"traceutil/trace.go:171","msg":"trace[844057838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"187.032927ms","start":"2026-02-18T14:45:52.531861Z","end":"2026-02-18T14:45:52.718894Z","steps":["trace[844057838] 'agreement among raft nodes before linearized reading' (duration: 186.817852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:52.718917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.902065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T14:45:52.718948Z","caller":"traceutil/trace.go:171","msg":"trace[1483374954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"351.965966ms","start":"2026-02-18T14:45:52.366973Z","end":"2026-02-18T14:45:52.718939Z","steps":["trace[1483374954] 'agreement among raft nodes before linearized reading' (duration: 351.859574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:52.718969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:52.366956Z","time spent":"352.008367ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:45:52.719056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.238372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:45:52.719072Z","caller":"traceutil/trace.go:171","msg":"trace[1057369475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"305.279364ms","start":"2026-02-18T14:45:52.413787Z","end":"2026-02-18T14:45:52.719067Z","steps":["trace[1057369475] 'agreement among raft nodes before linearized reading' (duration: 305.226812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:52.719089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:52.413772Z","time spent":"305.312804ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:45:52.892616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.688072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:52.892758Z","caller":"traceutil/trace.go:171","msg":"trace[47038147] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"165.289339ms","start":"2026-02-18T14:45:52.727454Z","end":"2026-02-18T14:45:52.892743Z","steps":["trace[47038147] 'process raft request' (duration: 44.106214ms)","trace[47038147] 'compare' (duration: 120.554908ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:52.893469Z","caller":"traceutil/trace.go:171","msg":"trace[581727625] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"165.943036ms","start":"2026-02-18T14:45:52.727514Z","end":"2026-02-18T14:45:52.893457Z","steps":["trace[581727625] 'process raft request' (duration: 165.186636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:53.418839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.739538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:53.418889Z","caller":"traceutil/trace.go:171","msg":"trace[1756645313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"163.83845ms","start":"2026-02-18T14:45:53.255038Z","end":"2026-02-18T14:45:53.418876Z","steps":["trace[1756645313] 'range keys from in-memory index tree' (duration: 163.685496ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:53.579906Z","caller":"traceutil/trace.go:171","msg":"trace[1046229718] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"158.219361ms","start":"2026-02-18T14:45:53.421669Z","end":"2026-02-18T14:45:53.579889Z","steps":["trace[1046229718] 'process raft request' (duration: 158.093317ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:53.606875Z","caller":"traceutil/trace.go:171","msg":"trace[1382791236] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"184.041828ms","start":"2026-02-18T14:45:53.422818Z","end":"2026-02-18T14:45:53.606859Z","steps":["trace[1382791236] 'process raft request' (duration: 183.954125ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:56.875671Z","caller":"traceutil/trace.go:171","msg":"trace[1248014695] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"110.134961ms","start":"2026-02-18T14:45:56.765512Z","end":"2026-02-18T14:45:56.875647Z","steps":["trace[1248014695] 'process raft request' (duration: 109.996598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:57.404238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908936811007839617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:45:58.163963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.959533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T14:45:58.164207Z","caller":"traceutil/trace.go:171","msg":"trace[576230590] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"1.260524893s","start":"2026-02-18T14:45:56.903664Z","end":"2026-02-18T14:45:58.164189Z","steps":["trace[576230590] 'read index received' (duration: 583.278152ms)","trace[576230590] 'applied index is now lower than readState.Index' (duration: 677.245611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:58.164725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261049237s","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-18T14:45:58.164769Z","caller":"traceutil/trace.go:171","msg":"trace[1445311849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"1.261133519s","start":"2026-02-18T14:45:56.903624Z","end":"2026-02-18T14:45:58.164758Z","steps":["trace[1445311849] 'agreement among raft nodes before linearized reading' (duration: 1.260996416s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:58.164803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:56.903604Z","time spent":"1.261189941s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T14:45:58.165619Z","caller":"traceutil/trace.go:171","msg":"trace[68723654] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"317.107378ms","start":"2026-02-18T14:45:57.848492Z","end":"2026-02-18T14:45:58.165599Z","steps":["trace[68723654] 'process raft request' (duration: 315.597158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:58.165703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:57.848468Z","time spent":"317.18351ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:58.466141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.883873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:58.466591Z","caller":"traceutil/trace.go:171","msg":"trace[1871999641] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:754; }","duration":"715.342835ms","start":"2026-02-18T14:45:57.751167Z","end":"2026-02-18T14:45:58.46651Z","steps":["trace[1871999641] 'agreement among raft nodes before linearized reading' (duration: 414.861409ms)","trace[1871999641] 'count revisions from in-memory index tree' (duration: 300.006224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:58.466949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.646989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:58.467009Z","caller":"traceutil/trace.go:171","msg":"trace[538021034] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"225.86895ms","start":"2026-02-18T14:45:58.241126Z","end":"2026-02-18T14:45:58.466995Z","steps":["trace[538021034] 'read index received' (duration: 79.802µs)","trace[538021034] 'applied index is now lower than readState.Index' (duration: 225.788098ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:58.467065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.939382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:58.467082Z","caller":"traceutil/trace.go:171","msg":"trace[1545769125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"225.983343ms","start":"2026-02-18T14:45:58.241094Z","end":"2026-02-18T14:45:58.467077Z","steps":["trace[1545769125] 'agreement among raft nodes before linearized reading' (duration: 225.940112ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:58.467224Z","caller":"traceutil/trace.go:171","msg":"trace[1438048766] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"296.093199ms","start":"2026-02-18T14:45:58.171123Z","end":"2026-02-18T14:45:58.467217Z","steps":["trace[1438048766] 'process raft request' (duration: 18.131402ms)","trace[1438048766] 'compare' (duration: 276.700814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:58.468235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:57.751153Z","time spent":"717.058281ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:45:58.466215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.360232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:45:58.469081Z","caller":"traceutil/trace.go:171","msg":"trace[1344856424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"852.33801ms","start":"2026-02-18T14:45:57.616723Z","end":"2026-02-18T14:45:58.469061Z","steps":["trace[1344856424] 'agreement among raft nodes before linearized reading' (duration: 549.323247ms)","trace[1344856424] 'range keys from in-memory index tree' (duration: 299.996393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:58.469905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:57.616709Z","time spent":"853.181663ms","remote":"127.0.0.1:41004","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-18T14:45:58.466277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.718096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:45:58.470255Z","caller":"traceutil/trace.go:171","msg":"trace[1321125184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"878.724813ms","start":"2026-02-18T14:45:57.591518Z","end":"2026-02-18T14:45:58.470243Z","steps":["trace[1321125184] 'agreement among raft nodes before linearized reading' (duration: 574.535348ms)","trace[1321125184] 'range keys from in-memory index tree' (duration: 300.192049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:58.470355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:57.591498Z","time spent":"878.844216ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:45:58.466346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562121939s","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-18T14:45:58.470727Z","caller":"traceutil/trace.go:171","msg":"trace[1678255062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"1.566518586s","start":"2026-02-18T14:45:56.904197Z","end":"2026-02-18T14:45:58.470716Z","steps":["trace[1678255062] 'agreement among raft nodes before linearized reading' (duration: 1.261383766s)","trace[1678255062] 'range keys from in-memory index tree' (duration: 300.706343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:58.470834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:56.904186Z","time spent":"1.566638219s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T14:45:58.617838Z","caller":"traceutil/trace.go:171","msg":"trace[1166721211] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"132.568498ms","start":"2026-02-18T14:45:58.485247Z","end":"2026-02-18T14:45:58.617815Z","steps":["trace[1166721211] 'process raft request' (duration: 132.496096ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:58.618374Z","caller":"traceutil/trace.go:171","msg":"trace[990399209] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"134.786667ms","start":"2026-02-18T14:45:58.483565Z","end":"2026-02-18T14:45:58.618352Z","steps":["trace[990399209] 'process raft request' (duration: 119.417058ms)","trace[990399209] 'compare' (duration: 14.65476ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:08.647271Z","caller":"traceutil/trace.go:171","msg":"trace[1670360129] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"113.76036ms","start":"2026-02-18T14:46:08.533496Z","end":"2026-02-18T14:46:08.647256Z","steps":["trace[1670360129] 'process raft request' (duration: 113.600525ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:08.673062Z","caller":"traceutil/trace.go:171","msg":"trace[676983082] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:873; }","duration":"107.068352ms","start":"2026-02-18T14:46:08.565982Z","end":"2026-02-18T14:46:08.67305Z","steps":["trace[676983082] 'read index received' (duration: 81.165155ms)","trace[676983082] 'applied index is now lower than readState.Index' (duration: 25.902567ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:08.673215Z","caller":"traceutil/trace.go:171","msg":"trace[508552371] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"137.309915ms","start":"2026-02-18T14:46:08.535896Z","end":"2026-02-18T14:46:08.673206Z","steps":["trace[508552371] 'process raft request' (duration: 137.083309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:08.673357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.36468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:46:08.673373Z","caller":"traceutil/trace.go:171","msg":"trace[2084017690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:834; }","duration":"107.441982ms","start":"2026-02-18T14:46:08.565926Z","end":"2026-02-18T14:46:08.673368Z","steps":["trace[2084017690] 'agreement among raft nodes before linearized reading' (duration: 107.3766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:09.135645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.27793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:46:09.135717Z","caller":"traceutil/trace.go:171","msg":"trace[1351838117] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"459.215039ms","start":"2026-02-18T14:46:08.676491Z","end":"2026-02-18T14:46:09.135706Z","steps":["trace[1351838117] 'process raft request' (duration: 317.829476ms)","trace[1351838117] 'compare' (duration: 141.160117ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:09.135751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:08.676481Z","time spent":"459.25374ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:46:09.136099Z","caller":"traceutil/trace.go:171","msg":"trace[1545604981] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:875; }","duration":"441.164771ms","start":"2026-02-18T14:46:08.694917Z","end":"2026-02-18T14:46:09.136082Z","steps":["trace[1545604981] 'read index received' (duration: 299.411128ms)","trace[1545604981] 'applied index is now lower than readState.Index' (duration: 141.753093ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:09.136186Z","caller":"traceutil/trace.go:171","msg":"trace[955458260] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"459.62288ms","start":"2026-02-18T14:46:08.676558Z","end":"2026-02-18T14:46:09.13618Z","steps":["trace[955458260] 'process raft request' (duration: 459.476286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:09.136213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:08.676554Z","time spent":"459.64384ms","remote":"127.0.0.1:41004","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-18T14:46:09.136298Z","caller":"traceutil/trace.go:171","msg":"trace[367961219] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"458.238744ms","start":"2026-02-18T14:46:08.678054Z","end":"2026-02-18T14:46:09.136293Z","steps":["trace[367961219] 'process raft request' (duration: 458.008738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:09.136334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:08.678046Z","time spent":"458.272955ms","remote":"127.0.0.1:41004","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-18T14:46:09.1364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.48253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:46:09.136414Z","caller":"traceutil/trace.go:171","msg":"trace[605110134] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:837; }","duration":"441.532161ms","start":"2026-02-18T14:46:08.694876Z","end":"2026-02-18T14:46:09.136409Z","steps":["trace[605110134] 'agreement among raft nodes before linearized reading' (duration: 441.50726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:09.136428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:08.694865Z","time spent":"441.559691ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:46:09.13656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.030094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:46:09.136573Z","caller":"traceutil/trace.go:171","msg":"trace[1850262590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:837; }","duration":"222.066825ms","start":"2026-02-18T14:46:08.914502Z","end":"2026-02-18T14:46:09.136569Z","steps":["trace[1850262590] 'agreement among raft nodes before linearized reading' (duration: 222.013564ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:10.799015Z","caller":"traceutil/trace.go:171","msg":"trace[643194874] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"145.674957ms","start":"2026-02-18T14:46:10.653321Z","end":"2026-02-18T14:46:10.798996Z","steps":["trace[643194874] 'process raft request' (duration: 145.564444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.246082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386546007s","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-18T14:46:12.246146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.903895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:12.246195Z","caller":"traceutil/trace.go:171","msg":"trace[667636915] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:839; }","duration":"1.38666897s","start":"2026-02-18T14:46:10.859508Z","end":"2026-02-18T14:46:12.246177Z","steps":["trace[667636915] 'range keys from in-memory index tree' (duration: 1.386531527s)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:12.246216Z","caller":"traceutil/trace.go:171","msg":"trace[513524387] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:839; }","duration":"742.014248ms","start":"2026-02-18T14:46:11.504185Z","end":"2026-02-18T14:46:12.246199Z","steps":["trace[513524387] 'count revisions from in-memory index tree' (duration: 741.851553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.24626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:11.504172Z","time spent":"742.078079ms","remote":"127.0.0.1:41066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:46:12.246848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103902465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:46:12.246884Z","caller":"traceutil/trace.go:171","msg":"trace[667541900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:839; }","duration":"1.103965456s","start":"2026-02-18T14:46:11.142908Z","end":"2026-02-18T14:46:12.246873Z","steps":["trace[667541900] 'range keys from in-memory index tree' (duration: 1.103771991s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.246916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:11.142894Z","time spent":"1.104014058s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:46:12.246974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.038167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:46:12.247011Z","caller":"traceutil/trace.go:171","msg":"trace[1733808234] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:839; }","duration":"311.095548ms","start":"2026-02-18T14:46:11.935904Z","end":"2026-02-18T14:46:12.246999Z","steps":["trace[1733808234] 'count revisions from in-memory index tree' (duration: 310.956785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.247042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:11.935889Z","time spent":"311.14237ms","remote":"127.0.0.1:41094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:46:12.24712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103251227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:46:12.247151Z","caller":"traceutil/trace.go:171","msg":"trace[1623524773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:839; }","duration":"1.103286498s","start":"2026-02-18T14:46:11.143855Z","end":"2026-02-18T14:46:12.247142Z","steps":["trace[1623524773] 'range keys from in-memory index tree' (duration: 1.103197576s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.247177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:11.143853Z","time spent":"1.103317289s","remote":"127.0.0.1:41004","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-18T14:46:12.247242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.025307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:12.247274Z","caller":"traceutil/trace.go:171","msg":"trace[1301309560] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:839; }","duration":"221.076678ms","start":"2026-02-18T14:46:12.026188Z","end":"2026-02-18T14:46:12.247264Z","steps":["trace[1301309560] 'count revisions from in-memory index tree' (duration: 220.966416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.247356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103515564s","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-18T14:46:12.247432Z","caller":"traceutil/trace.go:171","msg":"trace[946081236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"1.103553715s","start":"2026-02-18T14:46:11.143825Z","end":"2026-02-18T14:46:12.247378Z","steps":["trace[946081236] 'range keys from in-memory index tree' (duration: 1.103430342s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.247459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:11.14382Z","time spent":"1.103632947s","remote":"127.0.0.1:41004","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-18T14:46:12.247618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099453476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:46:12.247645Z","caller":"traceutil/trace.go:171","msg":"trace[1201989580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:839; }","duration":"1.099487927s","start":"2026-02-18T14:46:11.148149Z","end":"2026-02-18T14:46:12.247637Z","steps":["trace[1201989580] 'range keys from in-memory index tree' (duration: 1.099394494s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.247672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:11.148144Z","time spent":"1.099521198s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:46:12.247785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010904336s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:12.247809Z","caller":"traceutil/trace.go:171","msg":"trace[242324397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"1.010947866s","start":"2026-02-18T14:46:11.236853Z","end":"2026-02-18T14:46:12.247801Z","steps":["trace[242324397] 'range keys from in-memory index tree' (duration: 1.010864585s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.247834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:11.236833Z","time spent":"1.010993998s","remote":"127.0.0.1:40760","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-18T14:46:12.248066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.945988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.111\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:46:12.248094Z","caller":"traceutil/trace.go:171","msg":"trace[1101010841] range","detail":"{range_begin:/registry/masterleases/199.19.213.111; range_end:; response_count:1; response_revision:839; }","duration":"972.987159ms","start":"2026-02-18T14:46:11.275099Z","end":"2026-02-18T14:46:12.248086Z","steps":["trace[1101010841] 'range keys from in-memory index tree' (duration: 972.831875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:12.248119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:11.275089Z","time spent":"973.02462ms","remote":"127.0.0.1:40782","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.111\" "} {"level":"info","ts":"2026-02-18T14:46:12.541224Z","caller":"traceutil/trace.go:171","msg":"trace[2098007380] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"284.83761ms","start":"2026-02-18T14:46:12.256364Z","end":"2026-02-18T14:46:12.541202Z","steps":["trace[2098007380] 'process raft request' (duration: 281.324057ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:12.541261Z","caller":"traceutil/trace.go:171","msg":"trace[653920543] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"105.304975ms","start":"2026-02-18T14:46:12.435916Z","end":"2026-02-18T14:46:12.541221Z","steps":["trace[653920543] 'process raft request' (duration: 105.279845ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:12.541687Z","caller":"traceutil/trace.go:171","msg":"trace[1317559008] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"283.735401ms","start":"2026-02-18T14:46:12.257945Z","end":"2026-02-18T14:46:12.541681Z","steps":["trace[1317559008] 'process raft request' (duration: 283.229147ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:12.541747Z","caller":"traceutil/trace.go:171","msg":"trace[1402891879] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"285.253991ms","start":"2026-02-18T14:46:12.256489Z","end":"2026-02-18T14:46:12.541743Z","steps":["trace[1402891879] 'process raft request' (duration: 284.644645ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:12.541798Z","caller":"traceutil/trace.go:171","msg":"trace[510904812] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"285.235071ms","start":"2026-02-18T14:46:12.256559Z","end":"2026-02-18T14:46:12.541794Z","steps":["trace[510904812] 'process raft request' (duration: 284.600064ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:12.541951Z","caller":"traceutil/trace.go:171","msg":"trace[974126586] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"285.507618ms","start":"2026-02-18T14:46:12.256437Z","end":"2026-02-18T14:46:12.541945Z","steps":["trace[974126586] 'read index received' (duration: 179.083592ms)","trace[974126586] 'applied index is now lower than readState.Index' (duration: 106.423346ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:12.542011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.560989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:12.542025Z","caller":"traceutil/trace.go:171","msg":"trace[1438413606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"285.597721ms","start":"2026-02-18T14:46:12.256422Z","end":"2026-02-18T14:46:12.54202Z","steps":["trace[1438413606] 'agreement among raft nodes before linearized reading' (duration: 285.539959ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:19.068708Z","caller":"traceutil/trace.go:171","msg":"trace[1136963008] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"226.713289ms","start":"2026-02-18T14:46:18.841973Z","end":"2026-02-18T14:46:19.068687Z","steps":["trace[1136963008] 'process raft request' (duration: 226.541874ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:19.333967Z","caller":"traceutil/trace.go:171","msg":"trace[1767907464] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"331.281314ms","start":"2026-02-18T14:46:19.002656Z","end":"2026-02-18T14:46:19.333937Z","steps":["trace[1767907464] 'process raft request' (duration: 330.269297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:19.334128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:19.002633Z","time spent":"331.426258ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:46:21.590055Z","caller":"traceutil/trace.go:171","msg":"trace[999144515] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"350.70262ms","start":"2026-02-18T14:46:21.239337Z","end":"2026-02-18T14:46:21.59004Z","steps":["trace[999144515] 'read index received' (duration: 350.581606ms)","trace[999144515] 'applied index is now lower than readState.Index' (duration: 118.833µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:21.590212Z","caller":"traceutil/trace.go:171","msg":"trace[1225007385] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"516.306956ms","start":"2026-02-18T14:46:21.0739Z","end":"2026-02-18T14:46:21.590207Z","steps":["trace[1225007385] 'process raft request' (duration: 516.055339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:21.590274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:21.073891Z","time spent":"516.339217ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:46:21.590369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.054299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:21.590384Z","caller":"traceutil/trace.go:171","msg":"trace[1536740897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:867; }","duration":"351.122481ms","start":"2026-02-18T14:46:21.239257Z","end":"2026-02-18T14:46:21.590379Z","steps":["trace[1536740897] 'agreement among raft nodes before linearized reading' (duration: 351.09932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:21.590405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:21.23924Z","time spent":"351.162712ms","remote":"127.0.0.1:40760","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-18T14:46:21.590483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.133344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:21.590495Z","caller":"traceutil/trace.go:171","msg":"trace[810773331] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:867; }","duration":"324.162605ms","start":"2026-02-18T14:46:21.266329Z","end":"2026-02-18T14:46:21.590491Z","steps":["trace[810773331] 'agreement among raft nodes before linearized reading' (duration: 324.142225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:21.590506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:21.266321Z","time spent":"324.182856ms","remote":"127.0.0.1:41040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:46:21.590638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.898009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.111\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:46:21.59065Z","caller":"traceutil/trace.go:171","msg":"trace[1590267639] range","detail":"{range_begin:/registry/masterleases/199.19.213.111; range_end:; response_count:1; response_revision:867; }","duration":"314.9104ms","start":"2026-02-18T14:46:21.275736Z","end":"2026-02-18T14:46:21.590646Z","steps":["trace[1590267639] 'agreement among raft nodes before linearized reading' (duration: 314.856478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:21.590663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:21.27572Z","time spent":"314.93918ms","remote":"127.0.0.1:40782","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.111\" "} {"level":"info","ts":"2026-02-18T14:46:22.728413Z","caller":"traceutil/trace.go:171","msg":"trace[1378773796] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"101.087824ms","start":"2026-02-18T14:46:22.627303Z","end":"2026-02-18T14:46:22.728391Z","steps":["trace[1378773796] 'process raft request' (duration: 100.262532ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:23.94033Z","caller":"traceutil/trace.go:171","msg":"trace[1625967206] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"344.911726ms","start":"2026-02-18T14:46:23.595404Z","end":"2026-02-18T14:46:23.940316Z","steps":["trace[1625967206] 'process raft request' (duration: 344.822604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:23.940437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:23.595396Z","time spent":"344.975678ms","remote":"127.0.0.1:41004","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-18T14:46:24.640305Z","caller":"traceutil/trace.go:171","msg":"trace[1049340052] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"801.335473ms","start":"2026-02-18T14:46:23.838948Z","end":"2026-02-18T14:46:24.640284Z","steps":["trace[1049340052] 'read index received' (duration: 101.721591ms)","trace[1049340052] 'applied index is now lower than readState.Index' (duration: 699.612802ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:24.640612Z","caller":"traceutil/trace.go:171","msg":"trace[365552057] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"1.029077008s","start":"2026-02-18T14:46:23.611509Z","end":"2026-02-18T14:46:24.640586Z","steps":["trace[365552057] 'process raft request' (duration: 1.028658627s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:24.640713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:23.6115Z","time spent":"1.0291409s","remote":"127.0.0.1:41004","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-18T14:46:24.640912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.956309ms","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-18T14:46:24.640954Z","caller":"traceutil/trace.go:171","msg":"trace[1195802591] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:874; }","duration":"802.02115ms","start":"2026-02-18T14:46:23.838921Z","end":"2026-02-18T14:46:24.640942Z","steps":["trace[1195802591] 'agreement among raft nodes before linearized reading' (duration: 801.950589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:24.640991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:23.838909Z","time spent":"802.072392ms","remote":"127.0.0.1:41240","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-18T14:46:24.641148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.655478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:46:24.641183Z","caller":"traceutil/trace.go:171","msg":"trace[624148368] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:874; }","duration":"527.711889ms","start":"2026-02-18T14:46:24.113462Z","end":"2026-02-18T14:46:24.641174Z","steps":["trace[624148368] 'agreement among raft nodes before linearized reading' (duration: 527.656757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:24.641213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:24.11345Z","time spent":"527.75715ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-02-18T14:46:24.924824Z","caller":"traceutil/trace.go:171","msg":"trace[632231552] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"192.900501ms","start":"2026-02-18T14:46:24.731907Z","end":"2026-02-18T14:46:24.924808Z","steps":["trace[632231552] 'read index received' (duration: 172.92732ms)","trace[632231552] 'applied index is now lower than readState.Index' (duration: 19.970681ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:24.924959Z","caller":"traceutil/trace.go:171","msg":"trace[257897342] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"279.592652ms","start":"2026-02-18T14:46:24.645359Z","end":"2026-02-18T14:46:24.924952Z","steps":["trace[257897342] 'process raft request' (duration: 259.527169ms)","trace[257897342] 'compare' (duration: 19.743374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:24.925116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.197199ms","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-18T14:46:24.925133Z","caller":"traceutil/trace.go:171","msg":"trace[248698746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:875; }","duration":"193.26301ms","start":"2026-02-18T14:46:24.731865Z","end":"2026-02-18T14:46:24.925128Z","steps":["trace[248698746] 'agreement among raft nodes before linearized reading' (duration: 193.185908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:24.925222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.515545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:46:24.925233Z","caller":"traceutil/trace.go:171","msg":"trace[323916954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:875; }","duration":"188.548535ms","start":"2026-02-18T14:46:24.736681Z","end":"2026-02-18T14:46:24.92523Z","steps":["trace[323916954] 'agreement among raft nodes before linearized reading' (duration: 188.513944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:24.925347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.056036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:46:24.925359Z","caller":"traceutil/trace.go:171","msg":"trace[1319395585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"187.090726ms","start":"2026-02-18T14:46:24.738265Z","end":"2026-02-18T14:46:24.925355Z","steps":["trace[1319395585] 'agreement among raft nodes before linearized reading' (duration: 187.051725ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:25.322226Z","caller":"traceutil/trace.go:171","msg":"trace[1512889903] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"393.759923ms","start":"2026-02-18T14:46:24.92844Z","end":"2026-02-18T14:46:25.3222Z","steps":["trace[1512889903] 'process raft request' (duration: 352.728054ms)","trace[1512889903] 'compare' (duration: 40.67411ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:25.322308Z","caller":"traceutil/trace.go:171","msg":"trace[1205217990] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:919; }","duration":"161.183259ms","start":"2026-02-18T14:46:25.161103Z","end":"2026-02-18T14:46:25.322286Z","steps":["trace[1205217990] 'read index received' (duration: 120.152169ms)","trace[1205217990] 'applied index is now lower than readState.Index' (duration: 41.030189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:25.322353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:24.928429Z","time spent":"393.860016ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:46:25.322498Z","caller":"traceutil/trace.go:171","msg":"trace[739423941] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"392.475849ms","start":"2026-02-18T14:46:24.930013Z","end":"2026-02-18T14:46:25.322489Z","steps":["trace[739423941] 'process raft request' (duration: 392.107219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:25.322685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.575429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-18T14:46:25.322684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:24.930007Z","time spent":"392.52024ms","remote":"127.0.0.1:41004","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-18T14:46:25.322721Z","caller":"traceutil/trace.go:171","msg":"trace[590296527] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:878; }","duration":"161.648811ms","start":"2026-02-18T14:46:25.161062Z","end":"2026-02-18T14:46:25.322711Z","steps":["trace[590296527] 'agreement among raft nodes before linearized reading' (duration: 161.587819ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:25.322864Z","caller":"traceutil/trace.go:171","msg":"trace[1898220233] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"392.806928ms","start":"2026-02-18T14:46:24.930048Z","end":"2026-02-18T14:46:25.322855Z","steps":["trace[1898220233] 'process raft request' (duration: 392.15572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:25.322917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:24.930045Z","time spent":"392.839798ms","remote":"127.0.0.1:41004","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-18T14:46:29.809946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.786753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:29.810013Z","caller":"traceutil/trace.go:171","msg":"trace[780257299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"147.881476ms","start":"2026-02-18T14:46:29.662118Z","end":"2026-02-18T14:46:29.81Z","steps":["trace[780257299] 'range keys from in-memory index tree' (duration: 147.732462ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:30.188922Z","caller":"traceutil/trace.go:171","msg":"trace[1359726886] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"311.741314ms","start":"2026-02-18T14:46:29.877159Z","end":"2026-02-18T14:46:30.188901Z","steps":["trace[1359726886] 'read index received' (duration: 311.557619ms)","trace[1359726886] 'applied index is now lower than readState.Index' (duration: 182.795µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:30.1893Z","caller":"traceutil/trace.go:171","msg":"trace[2030850791] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"367.112235ms","start":"2026-02-18T14:46:29.822103Z","end":"2026-02-18T14:46:30.189215Z","steps":["trace[2030850791] 'process raft request' (duration: 366.675243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:30.189398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:29.822076Z","time spent":"367.259339ms","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11176,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:46:30.189587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.421342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:30.189625Z","caller":"traceutil/trace.go:171","msg":"trace[1861680086] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:906; }","duration":"312.497344ms","start":"2026-02-18T14:46:29.877118Z","end":"2026-02-18T14:46:30.189615Z","steps":["trace[1861680086] 'agreement among raft nodes before linearized reading' (duration: 312.438583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:30.189679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:29.877105Z","time spent":"312.566447ms","remote":"127.0.0.1:41190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:46:30.189833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.957336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:30.189879Z","caller":"traceutil/trace.go:171","msg":"trace[59237744] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:906; }","duration":"267.005697ms","start":"2026-02-18T14:46:29.922864Z","end":"2026-02-18T14:46:30.189869Z","steps":["trace[59237744] 'agreement among raft nodes before linearized reading' (duration: 266.944735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:30.19024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.940523ms","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-18T14:46:30.190276Z","caller":"traceutil/trace.go:171","msg":"trace[1602268246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"230.000795ms","start":"2026-02-18T14:46:29.960267Z","end":"2026-02-18T14:46:30.190268Z","steps":["trace[1602268246] 'agreement among raft nodes before linearized reading' (duration: 229.896102ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:30.337796Z","caller":"traceutil/trace.go:171","msg":"trace[1045489089] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"136.823122ms","start":"2026-02-18T14:46:30.200947Z","end":"2026-02-18T14:46:30.337771Z","steps":["trace[1045489089] 'process raft request' (duration: 91.831298ms)","trace[1045489089] 'compare' (duration: 44.759338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:31.887518Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.594660222s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:46:32.24133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9999163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:46:32.241426Z","caller":"traceutil/trace.go:171","msg":"trace[1246128140] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000025733s","start":"2026-02-18T14:46:30.241367Z","end":"2026-02-18T14:46:32.241393Z","steps":["trace[1246128140] 'agreement among raft nodes before linearized reading' (duration: 1.999932731s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.241466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:30.241325Z","time spent":"2.000133246s","remote":"127.0.0.1:40764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:46:32.375459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516310882s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:32.375571Z","caller":"traceutil/trace.go:171","msg":"trace[658166252] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:907; }","duration":"1.516433956s","start":"2026-02-18T14:46:30.859122Z","end":"2026-02-18T14:46:32.375556Z","steps":["trace[658166252] 'range keys from in-memory index tree' (duration: 1.516295642s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.380354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.610408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:46:32.380533Z","caller":"traceutil/trace.go:171","msg":"trace[64244793] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:951; }","duration":"2.139125796s","start":"2026-02-18T14:46:30.241394Z","end":"2026-02-18T14:46:32.38052Z","steps":["trace[64244793] 'read index received' (duration: 51.395504ms)","trace[64244793] 'applied index is now lower than readState.Index' (duration: 2.087729442s)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:32.380866Z","caller":"traceutil/trace.go:171","msg":"trace[1255020434] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"2.178051829s","start":"2026-02-18T14:46:30.202783Z","end":"2026-02-18T14:46:32.380834Z","steps":["trace[1255020434] 'process raft request' (duration: 1.684901787s)","trace[1255020434] 'compare' (duration: 492.349711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:32.387328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:30.202774Z","time spent":"2.184480849s","remote":"127.0.0.1:41004","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-18T14:46:32.382366Z","caller":"traceutil/trace.go:171","msg":"trace[38452680] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"2.175403869s","start":"2026-02-18T14:46:30.206947Z","end":"2026-02-18T14:46:32.382351Z","steps":["trace[38452680] 'process raft request' (duration: 2.173525349s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.387685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:30.206884Z","time spent":"2.180781062s","remote":"127.0.0.1:41198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:46:32.382501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364149723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:46:32.388132Z","caller":"traceutil/trace.go:171","msg":"trace[2137508266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"1.369803793s","start":"2026-02-18T14:46:31.018317Z","end":"2026-02-18T14:46:32.38812Z","steps":["trace[2137508266] 'agreement among raft nodes before linearized reading' (duration: 1.364087631s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.388286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:31.018304Z","time spent":"1.369968257s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:46:32.382762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.060919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:32.388791Z","caller":"traceutil/trace.go:171","msg":"trace[1262394651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"140.13165ms","start":"2026-02-18T14:46:32.248652Z","end":"2026-02-18T14:46:32.388784Z","steps":["trace[1262394651] 'agreement among raft nodes before linearized reading' (duration: 134.087409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.382808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.134175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:46:32.3889Z","caller":"traceutil/trace.go:171","msg":"trace[1308219929] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:910; }","duration":"159.262348ms","start":"2026-02-18T14:46:32.22963Z","end":"2026-02-18T14:46:32.388893Z","steps":["trace[1308219929] 'agreement among raft nodes before linearized reading' (duration: 153.152906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.382874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.268758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:46:32.388996Z","caller":"traceutil/trace.go:171","msg":"trace[1248231169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"999.39042ms","start":"2026-02-18T14:46:31.389599Z","end":"2026-02-18T14:46:32.388989Z","steps":["trace[1248231169] 'agreement among raft nodes before linearized reading' (duration: 993.223917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.389015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:31.389579Z","time spent":"999.428722ms","remote":"127.0.0.1:41004","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-18T14:46:32.383066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024178828s","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-18T14:46:32.389131Z","caller":"traceutil/trace.go:171","msg":"trace[782078322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"1.030378873s","start":"2026-02-18T14:46:31.358747Z","end":"2026-02-18T14:46:32.389126Z","steps":["trace[782078322] 'agreement among raft nodes before linearized reading' (duration: 1.024140377s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.389147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:31.358723Z","time spent":"1.030418814s","remote":"127.0.0.1:41004","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-18T14:46:32.383178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025720899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-18T14:46:32.383245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106390101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.111\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:46:32.391629Z","caller":"traceutil/trace.go:171","msg":"trace[225685574] range","detail":"{range_begin:/registry/masterleases/199.19.213.111; range_end:; response_count:1; response_revision:910; }","duration":"1.114808424s","start":"2026-02-18T14:46:31.276812Z","end":"2026-02-18T14:46:32.39162Z","steps":["trace[225685574] 'agreement among raft nodes before linearized reading' (duration: 1.10638276s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.391655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:31.276794Z","time spent":"1.114852675s","remote":"127.0.0.1:40782","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.111\" "} {"level":"info","ts":"2026-02-18T14:46:32.383436Z","caller":"traceutil/trace.go:171","msg":"trace[1122432897] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"2.180501594s","start":"2026-02-18T14:46:30.202922Z","end":"2026-02-18T14:46:32.383424Z","steps":["trace[1122432897] 'process raft request' (duration: 2.177496625s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.391786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:30.202905Z","time spent":"2.188838666s","remote":"127.0.0.1:40900","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-18T14:46:32.394505Z","caller":"traceutil/trace.go:171","msg":"trace[624804127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:910; }","duration":"1.034185084s","start":"2026-02-18T14:46:31.357391Z","end":"2026-02-18T14:46:32.391576Z","steps":["trace[624804127] 'agreement among raft nodes before linearized reading' (duration: 1.025698349s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:32.394535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:31.357375Z","time spent":"1.037151432s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T14:46:32.613867Z","caller":"traceutil/trace.go:171","msg":"trace[481230155] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"207.642213ms","start":"2026-02-18T14:46:32.406214Z","end":"2026-02-18T14:46:32.613856Z","steps":["trace[481230155] 'process raft request' (duration: 207.449138ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:32.614018Z","caller":"traceutil/trace.go:171","msg":"trace[1802781483] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"207.868548ms","start":"2026-02-18T14:46:32.406145Z","end":"2026-02-18T14:46:32.614013Z","steps":["trace[1802781483] 'process raft request' (duration: 207.427906ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:32.614033Z","caller":"traceutil/trace.go:171","msg":"trace[142761802] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"207.778936ms","start":"2026-02-18T14:46:32.40624Z","end":"2026-02-18T14:46:32.614019Z","steps":["trace[142761802] 'process raft request' (duration: 207.456307ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:32.614168Z","caller":"traceutil/trace.go:171","msg":"trace[1847478139] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"206.091031ms","start":"2026-02-18T14:46:32.408072Z","end":"2026-02-18T14:46:32.614163Z","steps":["trace[1847478139] 'process raft request' (duration: 205.652819ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:46:36.709222Z","caller":"traceutil/trace.go:171","msg":"trace[2100644539] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"303.467936ms","start":"2026-02-18T14:46:36.405729Z","end":"2026-02-18T14:46:36.709196Z","steps":["trace[2100644539] 'process raft request' (duration: 303.308522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:36.709394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:36.405715Z","time spent":"303.579409ms","remote":"127.0.0.1:41004","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-18T14:46:37.040253Z","caller":"traceutil/trace.go:171","msg":"trace[103562912] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"324.573596ms","start":"2026-02-18T14:46:36.715659Z","end":"2026-02-18T14:46:37.040233Z","steps":["trace[103562912] 'process raft request' (duration: 225.149357ms)","trace[103562912] 'compare' (duration: 99.037048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:37.040358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:36.715654Z","time spent":"324.661699ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:46:37.044745Z","caller":"traceutil/trace.go:171","msg":"trace[1375146040] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"329.111516ms","start":"2026-02-18T14:46:36.715627Z","end":"2026-02-18T14:46:37.044738Z","steps":["trace[1375146040] 'process raft request' (duration: 329.038415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:37.044792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:36.715611Z","time spent":"329.160988ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:46:37.044865Z","caller":"traceutil/trace.go:171","msg":"trace[844856118] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"326.232871ms","start":"2026-02-18T14:46:36.718628Z","end":"2026-02-18T14:46:37.044861Z","steps":["trace[844856118] 'process raft request' (duration: 326.089247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:37.044889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:36.718612Z","time spent":"326.263781ms","remote":"127.0.0.1:41004","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-18T14:46:37.046077Z","caller":"traceutil/trace.go:171","msg":"trace[1251836805] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"327.33768ms","start":"2026-02-18T14:46:36.718732Z","end":"2026-02-18T14:46:37.04607Z","steps":["trace[1251836805] 'process raft request' (duration: 326.111617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:37.046112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:36.718728Z","time spent":"327.365ms","remote":"127.0.0.1:41004","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-18T14:46:46.089431Z","caller":"traceutil/trace.go:171","msg":"trace[1030922460] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"127.784562ms","start":"2026-02-18T14:46:45.961623Z","end":"2026-02-18T14:46:46.089408Z","steps":["trace[1030922460] 'process raft request' (duration: 127.645159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:46.555154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.982835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:46.555629Z","caller":"traceutil/trace.go:171","msg":"trace[60184187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"314.497458ms","start":"2026-02-18T14:46:46.241115Z","end":"2026-02-18T14:46:46.555613Z","steps":["trace[60184187] 'range keys from in-memory index tree' (duration: 313.789119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:46.555892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:46.241097Z","time spent":"314.772985ms","remote":"127.0.0.1:40760","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-18T14:46:47.399072Z","caller":"traceutil/trace.go:171","msg":"trace[371482466] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"429.129201ms","start":"2026-02-18T14:46:46.96992Z","end":"2026-02-18T14:46:47.399049Z","steps":["trace[371482466] 'process raft request' (duration: 428.968447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:47.399444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:46.969908Z","time spent":"429.45889ms","remote":"127.0.0.1:41004","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-18T14:46:48.687636Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.288719317s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:46:48.688604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:48.294315Z","time spent":"394.283796ms","remote":"127.0.0.1:47660","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T14:46:48.688956Z","caller":"traceutil/trace.go:171","msg":"trace[1221258803] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"1.584301973s","start":"2026-02-18T14:46:47.104635Z","end":"2026-02-18T14:46:48.688937Z","steps":["trace[1221258803] 'read index received' (duration: 294.482066ms)","trace[1221258803] 'applied index is now lower than readState.Index' (duration: 1.289816417s)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:46:48.689185Z","caller":"traceutil/trace.go:171","msg":"trace[961030399] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.689987869s","start":"2026-02-18T14:46:46.999187Z","end":"2026-02-18T14:46:48.689175Z","steps":["trace[961030399] 'process raft request' (duration: 1.688673754s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:48.689275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:46.999162Z","time spent":"1.690054841s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:46:48.689469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584834108s","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-18T14:46:48.689499Z","caller":"traceutil/trace.go:171","msg":"trace[305036197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"1.584889139s","start":"2026-02-18T14:46:47.104601Z","end":"2026-02-18T14:46:48.68949Z","steps":["trace[305036197] 'agreement among raft nodes before linearized reading' (duration: 1.584784556s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:48.68953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:47.104587Z","time spent":"1.58493555s","remote":"127.0.0.1:41004","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-18T14:46:48.829347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.070164ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T14:46:48.830163Z","caller":"traceutil/trace.go:171","msg":"trace[1443679248] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"141.144537ms","start":"2026-02-18T14:46:48.688985Z","end":"2026-02-18T14:46:48.830129Z","steps":["trace[1443679248] 'read index received' (duration: 26.039152ms)","trace[1443679248] 'applied index is now lower than readState.Index' (duration: 115.102675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:48.830595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.723230881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T14:46:48.830661Z","caller":"traceutil/trace.go:171","msg":"trace[777412296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:996; }","duration":"1.723320173s","start":"2026-02-18T14:46:47.107318Z","end":"2026-02-18T14:46:48.830639Z","steps":["trace[777412296] 'agreement among raft nodes before linearized reading' (duration: 1.722924083s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:48.830715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:47.107311Z","time spent":"1.723393066s","remote":"127.0.0.1:41004","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-18T14:46:48.831817Z","caller":"traceutil/trace.go:171","msg":"trace[850340894] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"136.7425ms","start":"2026-02-18T14:46:48.695039Z","end":"2026-02-18T14:46:48.831781Z","steps":["trace[850340894] 'process raft request' (duration: 134.444389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:48.836218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.380844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:48.836708Z","caller":"traceutil/trace.go:171","msg":"trace[251948090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"595.795575ms","start":"2026-02-18T14:46:48.240768Z","end":"2026-02-18T14:46:48.836564Z","steps":["trace[251948090] 'agreement among raft nodes before linearized reading' (duration: 590.404361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:48.837005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:48.240748Z","time spent":"596.203496ms","remote":"127.0.0.1:40764","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-18T14:46:48.837586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156388545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:46:48.837796Z","caller":"traceutil/trace.go:171","msg":"trace[329790112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"1.156620661s","start":"2026-02-18T14:46:47.681162Z","end":"2026-02-18T14:46:48.837783Z","steps":["trace[329790112] 'agreement among raft nodes before linearized reading' (duration: 1.150027196s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:48.838075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:47.681147Z","time spent":"1.156908629s","remote":"127.0.0.1:40760","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-18T14:46:48.83899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465825729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-18T14:46:48.839479Z","caller":"traceutil/trace.go:171","msg":"trace[1888322765] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:997; }","duration":"1.466302512s","start":"2026-02-18T14:46:47.373078Z","end":"2026-02-18T14:46:48.83938Z","steps":["trace[1888322765] 'agreement among raft nodes before linearized reading' (duration: 1.458125054s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:48.839819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:47.373059Z","time spent":"1.466740883s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T14:46:48.840268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.728598924s","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-18T14:46:48.840794Z","caller":"traceutil/trace.go:171","msg":"trace[1093182732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"1.729143159s","start":"2026-02-18T14:46:47.111643Z","end":"2026-02-18T14:46:48.840786Z","steps":["trace[1093182732] 'agreement among raft nodes before linearized reading' (duration: 1.719572045s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:46:48.845416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:47.11164Z","time spent":"1.733749861s","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:46:48.847882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736236487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T14:46:48.847931Z","caller":"traceutil/trace.go:171","msg":"trace[1972438592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"1.736300818s","start":"2026-02-18T14:46:47.111615Z","end":"2026-02-18T14:46:48.847916Z","steps":["trace[1972438592] 'agreement among raft nodes before linearized reading' (duration: 1.719634895s)","trace[1972438592] 'range keys from in-memory index tree' (duration: 16.540969ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:46:48.847956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:47.111606Z","time spent":"1.73634363s","remote":"127.0.0.1:41004","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-18T14:47:02.980219Z","caller":"traceutil/trace.go:171","msg":"trace[9357061] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"209.047409ms","start":"2026-02-18T14:47:02.771146Z","end":"2026-02-18T14:47:02.980194Z","steps":["trace[9357061] 'process raft request' (duration: 208.909325ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:03.218537Z","caller":"traceutil/trace.go:171","msg":"trace[264594569] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"221.788458ms","start":"2026-02-18T14:47:02.996713Z","end":"2026-02-18T14:47:03.218502Z","steps":["trace[264594569] 'process raft request' (duration: 148.100202ms)","trace[264594569] 'compare' (duration: 73.301926ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:03.219252Z","caller":"traceutil/trace.go:171","msg":"trace[174016408] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"219.485867ms","start":"2026-02-18T14:47:02.99883Z","end":"2026-02-18T14:47:03.218316Z","steps":["trace[174016408] 'read index received' (duration: 145.911284ms)","trace[174016408] 'applied index is now lower than readState.Index' (duration: 73.573333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:03.219677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.800832ms","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-18T14:47:03.219723Z","caller":"traceutil/trace.go:171","msg":"trace[1493679946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"220.905425ms","start":"2026-02-18T14:47:02.998804Z","end":"2026-02-18T14:47:03.21971Z","steps":["trace[1493679946] 'agreement among raft nodes before linearized reading' (duration: 220.610487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:03.219954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.523198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T14:47:03.219985Z","caller":"traceutil/trace.go:171","msg":"trace[1026121594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1056; }","duration":"219.574049ms","start":"2026-02-18T14:47:03.000402Z","end":"2026-02-18T14:47:03.219977Z","steps":["trace[1026121594] 'agreement among raft nodes before linearized reading' (duration: 219.471597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:03.220177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.715513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:47:03.22022Z","caller":"traceutil/trace.go:171","msg":"trace[847355102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"219.768194ms","start":"2026-02-18T14:47:03.000442Z","end":"2026-02-18T14:47:03.220211Z","steps":["trace[847355102] 'agreement among raft nodes before linearized reading' (duration: 219.627401ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:03.381326Z","caller":"traceutil/trace.go:171","msg":"trace[1301297547] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"150.418093ms","start":"2026-02-18T14:47:03.230883Z","end":"2026-02-18T14:47:03.381301Z","steps":["trace[1301297547] 'process raft request' (duration: 150.228788ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:03.381484Z","caller":"traceutil/trace.go:171","msg":"trace[409242014] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1109; }","duration":"143.549441ms","start":"2026-02-18T14:47:03.237908Z","end":"2026-02-18T14:47:03.381457Z","steps":["trace[409242014] 'read index received' (duration: 35.814821ms)","trace[409242014] 'applied index is now lower than readState.Index' (duration: 107.73336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:03.381601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.676505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:47:03.382509Z","caller":"traceutil/trace.go:171","msg":"trace[1851522577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"144.62449ms","start":"2026-02-18T14:47:03.23787Z","end":"2026-02-18T14:47:03.382495Z","steps":["trace[1851522577] 'agreement among raft nodes before linearized reading' (duration: 143.665885ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:03.381741Z","caller":"traceutil/trace.go:171","msg":"trace[1970639735] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"150.207058ms","start":"2026-02-18T14:47:03.231522Z","end":"2026-02-18T14:47:03.381729Z","steps":["trace[1970639735] 'process raft request' (duration: 149.737655ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:04.865648Z","caller":"traceutil/trace.go:171","msg":"trace[1792526573] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"155.365784ms","start":"2026-02-18T14:47:04.710256Z","end":"2026-02-18T14:47:04.865621Z","steps":["trace[1792526573] 'process raft request' (duration: 113.42562ms)","trace[1792526573] 'compare' (duration: 41.78903ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:05.373984Z","caller":"traceutil/trace.go:171","msg":"trace[968284999] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"105.580752ms","start":"2026-02-18T14:47:05.268375Z","end":"2026-02-18T14:47:05.373955Z","steps":["trace[968284999] 'process raft request' (duration: 12.744498ms)","trace[968284999] 'compare' (duration: 92.3045ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:33.926606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.52971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:47:33.926711Z","caller":"traceutil/trace.go:171","msg":"trace[1515626130] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"136.612715ms","start":"2026-02-18T14:47:33.790081Z","end":"2026-02-18T14:47:33.926694Z","steps":["trace[1515626130] 'read index received' (duration: 13.841438ms)","trace[1515626130] 'applied index is now lower than readState.Index' (duration: 122.770277ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:33.926835Z","caller":"traceutil/trace.go:171","msg":"trace[1337371056] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"184.990062ms","start":"2026-02-18T14:47:33.741827Z","end":"2026-02-18T14:47:33.926817Z","steps":["trace[1337371056] 'process raft request' (duration: 62.143123ms)","trace[1337371056] 'compare' (duration: 122.385937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:33.926856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.774779ms","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-18T14:47:33.926889Z","caller":"traceutil/trace.go:171","msg":"trace[190746201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1183; }","duration":"136.833191ms","start":"2026-02-18T14:47:33.790046Z","end":"2026-02-18T14:47:33.926879Z","steps":["trace[190746201] 'agreement among raft nodes before linearized reading' (duration: 136.692517ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:10.23262Z","caller":"traceutil/trace.go:171","msg":"trace[1742936499] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"145.007138ms","start":"2026-02-18T14:48:10.087564Z","end":"2026-02-18T14:48:10.232571Z","steps":["trace[1742936499] 'read index received' (duration: 84.9452ms)","trace[1742936499] 'applied index is now lower than readState.Index' (duration: 60.060247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:10.23353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.795319ms","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-18T14:48:10.233914Z","caller":"traceutil/trace.go:171","msg":"trace[1057209665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1328; }","duration":"146.372075ms","start":"2026-02-18T14:48:10.087517Z","end":"2026-02-18T14:48:10.233889Z","steps":["trace[1057209665] 'agreement among raft nodes before linearized reading' (duration: 145.687116ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:10.234807Z","caller":"traceutil/trace.go:171","msg":"trace[916940946] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"293.527059ms","start":"2026-02-18T14:48:09.941248Z","end":"2026-02-18T14:48:10.234775Z","steps":["trace[916940946] 'process raft request' (duration: 231.201241ms)","trace[916940946] 'compare' (duration: 59.828862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:12.59009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.513763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:12.59026Z","caller":"traceutil/trace.go:171","msg":"trace[52778156] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"168.455612ms","start":"2026-02-18T14:48:12.421787Z","end":"2026-02-18T14:48:12.590243Z","steps":["trace[52778156] 'process raft request' (duration: 37.721373ms)","trace[52778156] 'compare' (duration: 130.36924ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:14.085685Z","caller":"traceutil/trace.go:171","msg":"trace[2030484380] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"115.71541ms","start":"2026-02-18T14:48:13.96995Z","end":"2026-02-18T14:48:14.085666Z","steps":["trace[2030484380] 'process raft request' (duration: 115.415252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:16.756888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.591129ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T14:48:16.757423Z","caller":"traceutil/trace.go:171","msg":"trace[1042421241] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1420; }","duration":"280.005139ms","start":"2026-02-18T14:48:16.477376Z","end":"2026-02-18T14:48:16.757381Z","steps":["trace[1042421241] 'read index received' (duration: 108.613243ms)","trace[1042421241] 'applied index is now lower than readState.Index' (duration: 171.390586ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:16.758194Z","caller":"traceutil/trace.go:171","msg":"trace[497482049] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"286.021667ms","start":"2026-02-18T14:48:16.472155Z","end":"2026-02-18T14:48:16.758177Z","steps":["trace[497482049] 'process raft request' (duration: 284.810095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:16.787881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.468212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:48:16.787947Z","caller":"traceutil/trace.go:171","msg":"trace[1202165177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1355; }","duration":"310.560124ms","start":"2026-02-18T14:48:16.477371Z","end":"2026-02-18T14:48:16.787932Z","steps":["trace[1202165177] 'agreement among raft nodes before linearized reading' (duration: 280.864831ms)","trace[1202165177] 'range keys from in-memory index tree' (duration: 29.527188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:16.787996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:16.477341Z","time spent":"310.647446ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:48:16.788438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.279255ms","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-18T14:48:16.788463Z","caller":"traceutil/trace.go:171","msg":"trace[1716776171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"146.327156ms","start":"2026-02-18T14:48:16.64213Z","end":"2026-02-18T14:48:16.788457Z","steps":["trace[1716776171] 'agreement among raft nodes before linearized reading' (duration: 146.265884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:16.791038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.265385ms","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-18T14:48:16.791208Z","caller":"traceutil/trace.go:171","msg":"trace[817442718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"313.46167ms","start":"2026-02-18T14:48:16.47773Z","end":"2026-02-18T14:48:16.791192Z","steps":["trace[817442718] 'agreement among raft nodes before linearized reading' (duration: 310.170423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:16.791459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:16.477716Z","time spent":"313.704437ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T14:48:16.971095Z","caller":"traceutil/trace.go:171","msg":"trace[1840106802] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"174.91445ms","start":"2026-02-18T14:48:16.796167Z","end":"2026-02-18T14:48:16.971082Z","steps":["trace[1840106802] 'process raft request' (duration: 174.884229ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:16.971292Z","caller":"traceutil/trace.go:171","msg":"trace[483366967] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"176.652075ms","start":"2026-02-18T14:48:16.794633Z","end":"2026-02-18T14:48:16.971285Z","steps":["trace[483366967] 'process raft request' (duration: 130.594031ms)","trace[483366967] 'compare' (duration: 45.621533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:17.964158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.402277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:48:17.964211Z","caller":"traceutil/trace.go:171","msg":"trace[1169248193] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1360; }","duration":"215.4963ms","start":"2026-02-18T14:48:17.748703Z","end":"2026-02-18T14:48:17.964199Z","steps":["trace[1169248193] 'count revisions from in-memory index tree' (duration: 215.345656ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:18.284797Z","caller":"traceutil/trace.go:171","msg":"trace[1832105093] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"184.391149ms","start":"2026-02-18T14:48:18.100371Z","end":"2026-02-18T14:48:18.284762Z","steps":["trace[1832105093] 'process raft request' (duration: 183.933857ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:27.90377Z","caller":"traceutil/trace.go:171","msg":"trace[1189890617] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"121.770669ms","start":"2026-02-18T14:48:27.781974Z","end":"2026-02-18T14:48:27.903745Z","steps":["trace[1189890617] 'process raft request' (duration: 121.630335ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:30.960559Z","caller":"traceutil/trace.go:171","msg":"trace[1436965427] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"103.209119ms","start":"2026-02-18T14:48:30.857305Z","end":"2026-02-18T14:48:30.960514Z","steps":["trace[1436965427] 'process raft request' (duration: 102.984763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:31.141693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.862044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:48:31.141765Z","caller":"traceutil/trace.go:171","msg":"trace[1332421139] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1412; }","duration":"156.974607ms","start":"2026-02-18T14:48:30.984775Z","end":"2026-02-18T14:48:31.14175Z","steps":["trace[1332421139] 'count revisions from in-memory index tree' (duration: 156.806783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:31.141994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.660657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T14:48:31.142023Z","caller":"traceutil/trace.go:171","msg":"trace[1367832440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1412; }","duration":"140.725808ms","start":"2026-02-18T14:48:31.001288Z","end":"2026-02-18T14:48:31.142014Z","steps":["trace[1367832440] 'range keys from in-memory index tree' (duration: 140.519853ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:53.031998Z","caller":"traceutil/trace.go:171","msg":"trace[1466128107] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"260.041653ms","start":"2026-02-18T14:48:52.771935Z","end":"2026-02-18T14:48:53.031977Z","steps":["trace[1466128107] 'process raft request' (duration: 259.93545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:53.265235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.657922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:53.265531Z","caller":"traceutil/trace.go:171","msg":"trace[619415917] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"200.530554ms","start":"2026-02-18T14:48:53.064979Z","end":"2026-02-18T14:48:53.265509Z","steps":["trace[619415917] 'process raft request' (duration: 52.518904ms)","trace[619415917] 'compare' (duration: 147.275401ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:53.483606Z","caller":"traceutil/trace.go:171","msg":"trace[1699659943] linearizableReadLoop","detail":"{readStateIndex:1578; appliedIndex:1577; }","duration":"129.612976ms","start":"2026-02-18T14:48:53.353978Z","end":"2026-02-18T14:48:53.483591Z","steps":["trace[1699659943] 'read index received' (duration: 127.242333ms)","trace[1699659943] 'applied index is now lower than readState.Index' (duration: 2.369993ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:53.483747Z","caller":"traceutil/trace.go:171","msg":"trace[117735406] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"210.892246ms","start":"2026-02-18T14:48:53.272848Z","end":"2026-02-18T14:48:53.48374Z","steps":["trace[117735406] 'process raft request' (duration: 208.432741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:53.483952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.930944ms","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-18T14:48:53.483977Z","caller":"traceutil/trace.go:171","msg":"trace[1354251367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1504; }","duration":"130.030117ms","start":"2026-02-18T14:48:53.35394Z","end":"2026-02-18T14:48:53.48397Z","steps":["trace[1354251367] 'agreement among raft nodes before linearized reading' (duration: 129.909784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:53.484112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.341199ms","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-18T14:48:53.48413Z","caller":"traceutil/trace.go:171","msg":"trace[785035160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1504; }","duration":"129.380309ms","start":"2026-02-18T14:48:53.354745Z","end":"2026-02-18T14:48:53.484125Z","steps":["trace[785035160] 'agreement among raft nodes before linearized reading' (duration: 129.326838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:15.932124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.783761ms","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-18T14:49:15.932207Z","caller":"traceutil/trace.go:171","msg":"trace[1720985893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1596; }","duration":"200.900724ms","start":"2026-02-18T14:49:15.73129Z","end":"2026-02-18T14:49:15.932191Z","steps":["trace[1720985893] 'range keys from in-memory index tree' (duration: 200.596626ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:21.739248Z","caller":"traceutil/trace.go:171","msg":"trace[1136009411] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"107.024794ms","start":"2026-02-18T14:49:21.630728Z","end":"2026-02-18T14:49:21.737753Z","steps":["trace[1136009411] 'process raft request' (duration: 106.774527ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:24.644441Z","caller":"traceutil/trace.go:171","msg":"trace[356710932] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"159.856919ms","start":"2026-02-18T14:49:24.484559Z","end":"2026-02-18T14:49:24.644416Z","steps":["trace[356710932] 'process raft request' (duration: 91.271636ms)","trace[356710932] 'compare' (duration: 68.283524ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:42.346123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.574939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:49:42.346347Z","caller":"traceutil/trace.go:171","msg":"trace[1427909604] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"332.289101ms","start":"2026-02-18T14:49:42.014037Z","end":"2026-02-18T14:49:42.346326Z","steps":["trace[1427909604] 'process raft request' (duration: 157.430164ms)","trace[1427909604] 'compare' (duration: 174.429535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:42.346454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:49:42.014025Z","time spent":"332.375814ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:49:42.346327Z","caller":"traceutil/trace.go:171","msg":"trace[851049858] linearizableReadLoop","detail":"{readStateIndex:1788; appliedIndex:1787; }","duration":"159.761515ms","start":"2026-02-18T14:49:42.186547Z","end":"2026-02-18T14:49:42.346309Z","steps":["trace[851049858] 'read index received' (duration: 35.111µs)","trace[851049858] 'applied index is now lower than readState.Index' (duration: 159.725114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:42.346639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.086485ms","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-18T14:49:42.346735Z","caller":"traceutil/trace.go:171","msg":"trace[1365503796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1704; }","duration":"160.241278ms","start":"2026-02-18T14:49:42.186481Z","end":"2026-02-18T14:49:42.346722Z","steps":["trace[1365503796] 'agreement among raft nodes before linearized reading' (duration: 159.95343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:42.346657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.597246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:49:42.346889Z","caller":"traceutil/trace.go:171","msg":"trace[695663352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1704; }","duration":"105.855623ms","start":"2026-02-18T14:49:42.241024Z","end":"2026-02-18T14:49:42.34688Z","steps":["trace[695663352] 'agreement among raft nodes before linearized reading' (duration: 105.594736ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:52.252803Z","caller":"traceutil/trace.go:171","msg":"trace[1124099042] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"111.3975ms","start":"2026-02-18T14:49:52.141384Z","end":"2026-02-18T14:49:52.252781Z","steps":["trace[1124099042] 'process raft request' (duration: 110.805245ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:52.541011Z","caller":"traceutil/trace.go:171","msg":"trace[2027531807] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"148.405624ms","start":"2026-02-18T14:49:52.392589Z","end":"2026-02-18T14:49:52.540995Z","steps":["trace[2027531807] 'process raft request' (duration: 148.047094ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:57.984096Z","caller":"traceutil/trace.go:171","msg":"trace[126775484] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"136.777305ms","start":"2026-02-18T14:49:57.847295Z","end":"2026-02-18T14:49:57.984072Z","steps":["trace[126775484] 'process raft request' (duration: 106.12031ms)","trace[126775484] 'compare' (duration: 30.222593ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:59.021085Z","caller":"traceutil/trace.go:171","msg":"trace[1587777563] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"115.487639ms","start":"2026-02-18T14:49:58.905573Z","end":"2026-02-18T14:49:59.021061Z","steps":["trace[1587777563] 'process raft request' (duration: 110.955629ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:01.356667Z","caller":"traceutil/trace.go:171","msg":"trace[1728819870] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"108.520633ms","start":"2026-02-18T14:50:01.248124Z","end":"2026-02-18T14:50:01.356644Z","steps":["trace[1728819870] 'read index received' (duration: 108.137982ms)","trace[1728819870] 'applied index is now lower than readState.Index' (duration: 381.751µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:50:01.35708Z","caller":"traceutil/trace.go:171","msg":"trace[1535312012] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"109.052587ms","start":"2026-02-18T14:50:01.248002Z","end":"2026-02-18T14:50:01.357054Z","steps":["trace[1535312012] 'process raft request' (duration: 108.328898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:01.357176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.024376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:50:01.357436Z","caller":"traceutil/trace.go:171","msg":"trace[1643560203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1801; }","duration":"109.227822ms","start":"2026-02-18T14:50:01.248094Z","end":"2026-02-18T14:50:01.357322Z","steps":["trace[1643560203] 'agreement among raft nodes before linearized reading' (duration: 108.988395ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:02.79121Z","caller":"traceutil/trace.go:171","msg":"trace[544736185] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"155.802591ms","start":"2026-02-18T14:50:02.635381Z","end":"2026-02-18T14:50:02.791184Z","steps":["trace[544736185] 'process raft request' (duration: 57.459857ms)","trace[544736185] 'compare' (duration: 98.082657ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:50:08.542334Z","caller":"traceutil/trace.go:171","msg":"trace[410643241] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"102.530404ms","start":"2026-02-18T14:50:08.439781Z","end":"2026-02-18T14:50:08.542311Z","steps":["trace[410643241] 'process raft request' (duration: 102.400621ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:12.34415Z","caller":"traceutil/trace.go:171","msg":"trace[1115240107] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"115.737836ms","start":"2026-02-18T14:50:12.228389Z","end":"2026-02-18T14:50:12.344127Z","steps":["trace[1115240107] 'process raft request' (duration: 115.571321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:12.769954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.92438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-18T14:50:12.770034Z","caller":"traceutil/trace.go:171","msg":"trace[1438625599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"116.048504ms","start":"2026-02-18T14:50:12.653968Z","end":"2026-02-18T14:50:12.770017Z","steps":["trace[1438625599] 'range keys from in-memory index tree' (duration: 115.778396ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:13.208504Z","caller":"traceutil/trace.go:171","msg":"trace[1074306725] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"280.72968ms","start":"2026-02-18T14:50:12.927753Z","end":"2026-02-18T14:50:13.208483Z","steps":["trace[1074306725] 'read index received' (duration: 280.085173ms)","trace[1074306725] 'applied index is now lower than readState.Index' (duration: 643.227µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:50:13.210736Z","caller":"traceutil/trace.go:171","msg":"trace[1302359573] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"359.221817ms","start":"2026-02-18T14:50:12.851495Z","end":"2026-02-18T14:50:13.210717Z","steps":["trace[1302359573] 'process raft request' (duration: 356.402482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:13.211102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:50:12.851486Z","time spent":"359.299869ms","remote":"127.0.0.1:41004","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-18T14:50:13.211532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.770641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:50:13.211583Z","caller":"traceutil/trace.go:171","msg":"trace[1770394234] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1852; }","duration":"283.857013ms","start":"2026-02-18T14:50:12.927712Z","end":"2026-02-18T14:50:13.211569Z","steps":["trace[1770394234] 'agreement among raft nodes before linearized reading' (duration: 283.783871ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:14.920537Z","caller":"traceutil/trace.go:171","msg":"trace[1989114870] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"106.399138ms","start":"2026-02-18T14:50:14.814118Z","end":"2026-02-18T14:50:14.920517Z","steps":["trace[1989114870] 'process raft request' (duration: 106.290035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:15.201232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.981411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:50:15.201334Z","caller":"traceutil/trace.go:171","msg":"trace[13928498] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"324.37204ms","start":"2026-02-18T14:50:14.876944Z","end":"2026-02-18T14:50:15.201316Z","steps":["trace[13928498] 'process raft request' (duration: 79.229575ms)","trace[13928498] 'compare' (duration: 244.799596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:50:15.201397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:50:14.876921Z","time spent":"324.442102ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:50:23.179495Z","caller":"traceutil/trace.go:171","msg":"trace[1496943943] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"567.364968ms","start":"2026-02-18T14:50:22.612115Z","end":"2026-02-18T14:50:23.17948Z","steps":["trace[1496943943] 'process raft request' (duration: 567.263245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:23.179598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:50:22.612099Z","time spent":"567.447829ms","remote":"127.0.0.1:41004","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-18T14:50:23.179929Z","caller":"traceutil/trace.go:171","msg":"trace[150043207] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1990; }","duration":"232.757715ms","start":"2026-02-18T14:50:22.947163Z","end":"2026-02-18T14:50:23.179921Z","steps":["trace[150043207] 'read index received' (duration: 232.754935ms)","trace[150043207] 'applied index is now lower than readState.Index' (duration: 2.15µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:50:23.180054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.886579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-18T14:50:23.180075Z","caller":"traceutil/trace.go:171","msg":"trace[1689156417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1897; }","duration":"232.93474ms","start":"2026-02-18T14:50:22.947134Z","end":"2026-02-18T14:50:23.180069Z","steps":["trace[1689156417] 'agreement among raft nodes before linearized reading' (duration: 232.832087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:23.180186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.391922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:50:23.18021Z","caller":"traceutil/trace.go:171","msg":"trace[1730546499] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1897; }","duration":"198.442594ms","start":"2026-02-18T14:50:22.981761Z","end":"2026-02-18T14:50:23.180204Z","steps":["trace[1730546499] 'agreement among raft nodes before linearized reading' (duration: 198.401433ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:41.153376Z","caller":"traceutil/trace.go:171","msg":"trace[2019018097] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"141.022858ms","start":"2026-02-18T14:50:41.012328Z","end":"2026-02-18T14:50:41.153351Z","steps":["trace[2019018097] 'process raft request' (duration: 140.888084ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:45.6791Z","caller":"traceutil/trace.go:171","msg":"trace[754980507] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"102.823693ms","start":"2026-02-18T14:50:45.576262Z","end":"2026-02-18T14:50:45.679086Z","steps":["trace[754980507] 'process raft request' (duration: 68.762578ms)","trace[754980507] 'compare' (duration: 33.785067ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:50:46.107149Z","caller":"traceutil/trace.go:171","msg":"trace[2012124428] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"145.412095ms","start":"2026-02-18T14:50:45.961714Z","end":"2026-02-18T14:50:46.107126Z","steps":["trace[2012124428] 'read index received' (duration: 145.23608ms)","trace[2012124428] 'applied index is now lower than readState.Index' (duration: 175.144µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:50:46.107567Z","caller":"traceutil/trace.go:171","msg":"trace[418756712] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"190.196475ms","start":"2026-02-18T14:50:45.917356Z","end":"2026-02-18T14:50:46.107552Z","steps":["trace[418756712] 'process raft request' (duration: 189.65363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:46.107789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.055502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-02-18T14:50:46.107824Z","caller":"traceutil/trace.go:171","msg":"trace[1854811671] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1999; }","duration":"146.124264ms","start":"2026-02-18T14:50:45.961688Z","end":"2026-02-18T14:50:46.107812Z","steps":["trace[1854811671] 'agreement among raft nodes before linearized reading' (duration: 146.044482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:46.108148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.135691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-18T14:50:46.108199Z","caller":"traceutil/trace.go:171","msg":"trace[2040554049] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1999; }","duration":"106.212193ms","start":"2026-02-18T14:50:46.001974Z","end":"2026-02-18T14:50:46.108186Z","steps":["trace[2040554049] 'agreement among raft nodes before linearized reading' (duration: 106.075589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:46.108497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.275285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1000"} {"level":"info","ts":"2026-02-18T14:50:46.108519Z","caller":"traceutil/trace.go:171","msg":"trace[1887940890] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1999; }","duration":"100.311146ms","start":"2026-02-18T14:50:46.008202Z","end":"2026-02-18T14:50:46.108513Z","steps":["trace[1887940890] 'agreement among raft nodes before linearized reading' (duration: 100.271145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:46.108609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.248464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:50:46.108626Z","caller":"traceutil/trace.go:171","msg":"trace[652595813] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1999; }","duration":"103.279865ms","start":"2026-02-18T14:50:46.005341Z","end":"2026-02-18T14:50:46.108621Z","steps":["trace[652595813] 'agreement among raft nodes before linearized reading' (duration: 103.250224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:46.451096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.92573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:50:46.451253Z","caller":"traceutil/trace.go:171","msg":"trace[1951652665] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2097; }","duration":"103.738907ms","start":"2026-02-18T14:50:46.347499Z","end":"2026-02-18T14:50:46.451238Z","steps":["trace[1951652665] 'read index received' (duration: 23.381µs)","trace[1951652665] 'applied index is now lower than readState.Index' (duration: 103.713886ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:50:46.451505Z","caller":"traceutil/trace.go:171","msg":"trace[852511528] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"183.184908ms","start":"2026-02-18T14:50:46.268306Z","end":"2026-02-18T14:50:46.451491Z","steps":["trace[852511528] 'process raft request' (duration: 57.797066ms)","trace[852511528] 'compare' (duration: 124.720584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:50:46.451859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.348913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:12 size:8411"} {"level":"info","ts":"2026-02-18T14:50:46.451972Z","caller":"traceutil/trace.go:171","msg":"trace[1916519847] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:12; response_revision:2001; }","duration":"104.489116ms","start":"2026-02-18T14:50:46.347472Z","end":"2026-02-18T14:50:46.451961Z","steps":["trace[1916519847] 'agreement among raft nodes before linearized reading' (duration: 104.25324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:46.681154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.162464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-18T14:50:46.681205Z","caller":"traceutil/trace.go:171","msg":"trace[1916260017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2001; }","duration":"117.251016ms","start":"2026-02-18T14:50:46.563942Z","end":"2026-02-18T14:50:46.681193Z","steps":["trace[1916260017] 'range keys from in-memory index tree' (duration: 117.053931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:47.037544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.947725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2896"} {"level":"info","ts":"2026-02-18T14:50:47.037587Z","caller":"traceutil/trace.go:171","msg":"trace[577437136] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:2002; }","duration":"179.032338ms","start":"2026-02-18T14:50:46.858545Z","end":"2026-02-18T14:50:47.037578Z","steps":["trace[577437136] 'range keys from in-memory index tree' (duration: 178.891624ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:47.286521Z","caller":"traceutil/trace.go:171","msg":"trace[929512610] linearizableReadLoop","detail":"{readStateIndex:2101; appliedIndex:2100; }","duration":"146.76ms","start":"2026-02-18T14:50:47.139734Z","end":"2026-02-18T14:50:47.286494Z","steps":["trace[929512610] 'read index received' (duration: 146.555374ms)","trace[929512610] 'applied index is now lower than readState.Index' (duration: 203.586µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:50:47.286929Z","caller":"traceutil/trace.go:171","msg":"trace[1248301821] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"165.385405ms","start":"2026-02-18T14:50:47.121522Z","end":"2026-02-18T14:50:47.286908Z","steps":["trace[1248301821] 'process raft request' (duration: 164.82837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:47.287237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.480169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:11093"} {"level":"info","ts":"2026-02-18T14:50:47.287301Z","caller":"traceutil/trace.go:171","msg":"trace[1445394799] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2003; }","duration":"147.589832ms","start":"2026-02-18T14:50:47.139696Z","end":"2026-02-18T14:50:47.287286Z","steps":["trace[1445394799] 'agreement among raft nodes before linearized reading' (duration: 147.404947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:47.287579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.950774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2896"} {"level":"info","ts":"2026-02-18T14:50:47.287636Z","caller":"traceutil/trace.go:171","msg":"trace[981133153] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:2003; }","duration":"124.009586ms","start":"2026-02-18T14:50:47.163613Z","end":"2026-02-18T14:50:47.287622Z","steps":["trace[981133153] 'agreement among raft nodes before linearized reading' (duration: 123.914873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:47.288184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.817974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:50:47.288245Z","caller":"traceutil/trace.go:171","msg":"trace[34502075] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2003; }","duration":"119.897667ms","start":"2026-02-18T14:50:47.168332Z","end":"2026-02-18T14:50:47.288229Z","steps":["trace[34502075] 'agreement among raft nodes before linearized reading' (duration: 119.804424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:47.541212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.168498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-02-18T14:50:47.541329Z","caller":"traceutil/trace.go:171","msg":"trace[1932115788] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:2003; }","duration":"116.326771ms","start":"2026-02-18T14:50:47.424973Z","end":"2026-02-18T14:50:47.541299Z","steps":["trace[1932115788] 'range keys from in-memory index tree' (duration: 115.976663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:47.541468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.986906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4687"} {"level":"info","ts":"2026-02-18T14:50:47.541531Z","caller":"traceutil/trace.go:171","msg":"trace[1462572117] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:2003; }","duration":"147.078089ms","start":"2026-02-18T14:50:47.394435Z","end":"2026-02-18T14:50:47.541513Z","steps":["trace[1462572117] 'range keys from in-memory index tree' (duration: 146.899174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:51.328849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.563903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-02-18T14:50:51.328925Z","caller":"traceutil/trace.go:171","msg":"trace[143217584] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:2018; }","duration":"123.664646ms","start":"2026-02-18T14:50:51.20524Z","end":"2026-02-18T14:50:51.328905Z","steps":["trace[143217584] 'range keys from in-memory index tree' (duration: 123.498913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:51.32916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.209274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-02-18T14:50:51.329196Z","caller":"traceutil/trace.go:171","msg":"trace[1350621548] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2018; }","duration":"123.265726ms","start":"2026-02-18T14:50:51.205922Z","end":"2026-02-18T14:50:51.329187Z","steps":["trace[1350621548] 'range keys from in-memory index tree' (duration: 123.106991ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:51.472429Z","caller":"traceutil/trace.go:171","msg":"trace[1264797096] linearizableReadLoop","detail":"{readStateIndex:2118; appliedIndex:2117; }","duration":"120.45573ms","start":"2026-02-18T14:50:51.351954Z","end":"2026-02-18T14:50:51.47241Z","steps":["trace[1264797096] 'read index received' (duration: 81.498496ms)","trace[1264797096] 'applied index is now lower than readState.Index' (duration: 38.956254ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:50:51.472703Z","caller":"traceutil/trace.go:171","msg":"trace[2021749463] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"135.371437ms","start":"2026-02-18T14:50:51.33732Z","end":"2026-02-18T14:50:51.472692Z","steps":["trace[2021749463] 'process raft request' (duration: 96.124124ms)","trace[2021749463] 'compare' (duration: 38.839652ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:50:51.472949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.1302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"info","ts":"2026-02-18T14:50:51.47298Z","caller":"traceutil/trace.go:171","msg":"trace[1497063162] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:2019; }","duration":"138.193641ms","start":"2026-02-18T14:50:51.334778Z","end":"2026-02-18T14:50:51.472972Z","steps":["trace[1497063162] 'agreement among raft nodes before linearized reading' (duration: 138.114299ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:51.482034Z","caller":"traceutil/trace.go:171","msg":"trace[1513291958] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"127.371644ms","start":"2026-02-18T14:50:51.35464Z","end":"2026-02-18T14:50:51.482011Z","steps":["trace[1513291958] 'process raft request' (duration: 127.262981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:50:55.146672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.124334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"warn","ts":"2026-02-18T14:50:55.146692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.424051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-18T14:50:55.146737Z","caller":"traceutil/trace.go:171","msg":"trace[623367064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:2034; }","duration":"102.231997ms","start":"2026-02-18T14:50:55.044493Z","end":"2026-02-18T14:50:55.146725Z","steps":["trace[623367064] 'range keys from in-memory index tree' (duration: 102.044632ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:50:55.146752Z","caller":"traceutil/trace.go:171","msg":"trace[1342475748] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:2034; }","duration":"120.523873ms","start":"2026-02-18T14:50:55.026217Z","end":"2026-02-18T14:50:55.146741Z","steps":["trace[1342475748] 'range keys from in-memory index tree' (duration: 120.356659ms)"],"step_count":1}