{"level":"warn","ts":"2026-02-18T10:06:29.297866Z","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-18T10:06:29.297962Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86: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.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-18T10:06:29.29803Z","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-18T10:06:29.298041Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-02-18T10:06:29.29807Z","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-18T10:06:29.298666Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-02-18T10:06:29.298732Z","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.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-18T10:06:29.303066Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.12063ms"} {"level":"info","ts":"2026-02-18T10:06:29.340281Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-02-18T10:06:29.340356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T10:06:29.340377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-02-18T10:06:29.340388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T10:06:29.340394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-02-18T10:06:29.340424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-02-18T10:06:29.352192Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T10:06:29.355355Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T10:06:29.359512Z","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-18T10:06:29.363733Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T10:06:29.371426Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T10:06:29.371758Z","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-18T10:06:29.371972Z","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-18T10:06:29.372115Z","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-18T10:06:29.374593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-02-18T10:06:29.37472Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-02-18T10:06:29.374888Z","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-18T10:06:29.378609Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-02-18T10:06:29.378624Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-02-18T10:06:29.379833Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T10:06:29.379855Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T10:06:30.241244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T10:06:30.241306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T10:06:30.241512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-02-18T10:06:30.24159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-02-18T10:06:30.241605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-02-18T10:06:30.241681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-02-18T10:06:30.241697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-02-18T10:06:30.364661Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T10:06:30.368802Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T10:06:30.368827Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T10:06:30.369288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T10:06:30.370827Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T10:06:30.371016Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T10:06:30.37103Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T10:06:30.372388Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-02-18T10:06:30.381025Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T10:06:30.38111Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T10:06:30.38113Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T10:06:35.797182Z","caller":"traceutil/trace.go:171","msg":"trace[626655295] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"115.936726ms","start":"2026-02-18T10:06:35.681224Z","end":"2026-02-18T10:06:35.79716Z","steps":["trace[626655295] 'process raft request' (duration: 115.433596ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:36.17154Z","caller":"traceutil/trace.go:171","msg":"trace[52016176] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"118.197946ms","start":"2026-02-18T10:06:36.053327Z","end":"2026-02-18T10:06:36.171525Z","steps":["trace[52016176] 'process raft request' (duration: 118.096484ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:36.172759Z","caller":"traceutil/trace.go:171","msg":"trace[1940031916] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"115.504856ms","start":"2026-02-18T10:06:36.057243Z","end":"2026-02-18T10:06:36.172748Z","steps":["trace[1940031916] 'process raft request' (duration: 115.297212ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:36.481493Z","caller":"traceutil/trace.go:171","msg":"trace[979585582] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"120.368238ms","start":"2026-02-18T10:06:36.361082Z","end":"2026-02-18T10:06:36.48145Z","steps":["trace[979585582] 'process raft request' (duration: 77.731068ms)","trace[979585582] 'compare' (duration: 42.564168ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:47.266563Z","caller":"traceutil/trace.go:171","msg":"trace[475052657] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"135.698653ms","start":"2026-02-18T10:06:47.130848Z","end":"2026-02-18T10:06:47.266546Z","steps":["trace[475052657] 'process raft request' (duration: 135.599201ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:47.527078Z","caller":"traceutil/trace.go:171","msg":"trace[2069720672] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"254.148119ms","start":"2026-02-18T10:06:47.272914Z","end":"2026-02-18T10:06:47.527062Z","steps":["trace[2069720672] 'process raft request' (duration: 217.177041ms)","trace[2069720672] 'compare' (duration: 36.906936ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:47.864025Z","caller":"traceutil/trace.go:171","msg":"trace[910256506] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"326.083877ms","start":"2026-02-18T10:06:47.537924Z","end":"2026-02-18T10:06:47.864008Z","steps":["trace[910256506] 'process raft request' (duration: 326.059056ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:47.864321Z","caller":"traceutil/trace.go:171","msg":"trace[781412726] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"330.093789ms","start":"2026-02-18T10:06:47.534199Z","end":"2026-02-18T10:06:47.864293Z","steps":["trace[781412726] 'process raft request' (duration: 256.313127ms)","trace[781412726] 'compare' (duration: 73.294303ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:06:47.864347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:06:47.537918Z","time spent":"326.158779ms","remote":"127.0.0.1:51876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T10:06:47.864449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:06:47.534184Z","time spent":"330.20747ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3541,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T10:06:47.864522Z","caller":"traceutil/trace.go:171","msg":"trace[925044011] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"326.892142ms","start":"2026-02-18T10:06:47.537618Z","end":"2026-02-18T10:06:47.86451Z","steps":["trace[925044011] 'process raft request' (duration: 326.313961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:47.864655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:06:47.537608Z","time spent":"326.931212ms","remote":"127.0.0.1:52272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:06:48.146529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.099165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T10:06:48.146871Z","caller":"traceutil/trace.go:171","msg":"trace[86740710] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"221.816331ms","start":"2026-02-18T10:06:47.925037Z","end":"2026-02-18T10:06:48.146853Z","steps":["trace[86740710] 'process raft request' (duration: 221.779671ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.147159Z","caller":"traceutil/trace.go:171","msg":"trace[974675759] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"271.157829ms","start":"2026-02-18T10:06:47.875986Z","end":"2026-02-18T10:06:48.147144Z","steps":["trace[974675759] 'process raft request' (duration: 92.170869ms)","trace[974675759] 'compare' (duration: 177.958742ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:48.147287Z","caller":"traceutil/trace.go:171","msg":"trace[1226734044] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"271.108038ms","start":"2026-02-18T10:06:47.876169Z","end":"2026-02-18T10:06:48.147277Z","steps":["trace[1226734044] 'process raft request' (duration: 270.468377ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.147532Z","caller":"traceutil/trace.go:171","msg":"trace[1211565167] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"271.22ms","start":"2026-02-18T10:06:47.8763Z","end":"2026-02-18T10:06:48.14752Z","steps":["trace[1211565167] 'process raft request' (duration: 270.403186ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.147757Z","caller":"traceutil/trace.go:171","msg":"trace[232505126] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"271.289351ms","start":"2026-02-18T10:06:47.876454Z","end":"2026-02-18T10:06:48.147744Z","steps":["trace[232505126] 'process raft request' (duration: 270.313964ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.147888Z","caller":"traceutil/trace.go:171","msg":"trace[1995654559] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:431; }","duration":"271.639807ms","start":"2026-02-18T10:06:47.876236Z","end":"2026-02-18T10:06:48.147876Z","steps":["trace[1995654559] 'read index received' (duration: 42.317112ms)","trace[1995654559] 'applied index is now lower than readState.Index' (duration: 229.321595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:06:48.148261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.008744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-18T10:06:48.148301Z","caller":"traceutil/trace.go:171","msg":"trace[294819600] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:423; }","duration":"272.071715ms","start":"2026-02-18T10:06:47.876217Z","end":"2026-02-18T10:06:48.148289Z","steps":["trace[294819600] 'agreement among raft nodes before linearized reading' (duration: 271.986813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:48.148492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.378903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-bfbkd\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-18T10:06:48.148536Z","caller":"traceutil/trace.go:171","msg":"trace[872558801] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-bfbkd; range_end:; response_count:1; response_revision:423; }","duration":"226.446864ms","start":"2026-02-18T10:06:47.922076Z","end":"2026-02-18T10:06:48.148523Z","steps":["trace[872558801] 'agreement among raft nodes before linearized reading' (duration: 226.378753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:48.148827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.264922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:06:48.148862Z","caller":"traceutil/trace.go:171","msg":"trace[1183751318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"221.314833ms","start":"2026-02-18T10:06:47.927536Z","end":"2026-02-18T10:06:48.148851Z","steps":["trace[1183751318] 'agreement among raft nodes before linearized reading' (duration: 221.262922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:48.149033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.932589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-18T10:06:48.149068Z","caller":"traceutil/trace.go:171","msg":"trace[544716166] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:423; }","duration":"223.97671ms","start":"2026-02-18T10:06:47.92508Z","end":"2026-02-18T10:06:48.149056Z","steps":["trace[544716166] 'agreement among raft nodes before linearized reading' (duration: 223.910569ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.404884Z","caller":"traceutil/trace.go:171","msg":"trace[1467968587] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"241.721117ms","start":"2026-02-18T10:06:48.163148Z","end":"2026-02-18T10:06:48.404869Z","steps":["trace[1467968587] 'process raft request' (duration: 241.675976ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.405209Z","caller":"traceutil/trace.go:171","msg":"trace[1622057821] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"246.019063ms","start":"2026-02-18T10:06:48.159074Z","end":"2026-02-18T10:06:48.405093Z","steps":["trace[1622057821] 'process raft request' (duration: 178.007579ms)","trace[1622057821] 'compare' (duration: 67.657458ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:48.405379Z","caller":"traceutil/trace.go:171","msg":"trace[80506940] transaction","detail":"{read_only:false; number_of_response:1; response_revision:426; }","duration":"242.104514ms","start":"2026-02-18T10:06:48.163213Z","end":"2026-02-18T10:06:48.405317Z","steps":["trace[80506940] 'process raft request' (duration: 241.636275ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.419124Z","caller":"traceutil/trace.go:171","msg":"trace[163566233] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:437; }","duration":"142.267418ms","start":"2026-02-18T10:06:48.276845Z","end":"2026-02-18T10:06:48.419112Z","steps":["trace[163566233] 'read index received' (duration: 60.319517ms)","trace[163566233] 'applied index is now lower than readState.Index' (duration: 81.947351ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:48.419189Z","caller":"traceutil/trace.go:171","msg":"trace[1962808047] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"242.967939ms","start":"2026-02-18T10:06:48.176215Z","end":"2026-02-18T10:06:48.419183Z","steps":["trace[1962808047] 'process raft request' (duration: 242.858597ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.41918Z","caller":"traceutil/trace.go:171","msg":"trace[381904045] transaction","detail":"{read_only:false; number_of_response:1; response_revision:426; }","duration":"246.237567ms","start":"2026-02-18T10:06:48.17292Z","end":"2026-02-18T10:06:48.419158Z","steps":["trace[381904045] 'process raft request' (duration: 246.095704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:48.419305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.454301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T10:06:48.419328Z","caller":"traceutil/trace.go:171","msg":"trace[1187914440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"142.519253ms","start":"2026-02-18T10:06:48.276802Z","end":"2026-02-18T10:06:48.419321Z","steps":["trace[1187914440] 'agreement among raft nodes before linearized reading' (duration: 142.457491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:48.41949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.76294ms","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-18T10:06:48.419507Z","caller":"traceutil/trace.go:171","msg":"trace[354785379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"123.810272ms","start":"2026-02-18T10:06:48.295691Z","end":"2026-02-18T10:06:48.419502Z","steps":["trace[354785379] 'agreement among raft nodes before linearized reading' (duration: 123.740171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:48.419594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.52238ms","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-18T10:06:48.419617Z","caller":"traceutil/trace.go:171","msg":"trace[1658841022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"104.586411ms","start":"2026-02-18T10:06:48.315024Z","end":"2026-02-18T10:06:48.41961Z","steps":["trace[1658841022] 'agreement among raft nodes before linearized reading' (duration: 104.52431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:48.708122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.202034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T10:06:48.708305Z","caller":"traceutil/trace.go:171","msg":"trace[1096340341] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"266.351544ms","start":"2026-02-18T10:06:48.441926Z","end":"2026-02-18T10:06:48.708277Z","steps":["trace[1096340341] 'process raft request' (duration: 83.933886ms)","trace[1096340341] 'compare' (duration: 182.088872ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:48.708408Z","caller":"traceutil/trace.go:171","msg":"trace[1877931951] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"254.556685ms","start":"2026-02-18T10:06:48.453832Z","end":"2026-02-18T10:06:48.708388Z","steps":["trace[1877931951] 'process raft request' (duration: 254.518764ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.708655Z","caller":"traceutil/trace.go:171","msg":"trace[1515018667] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"254.862971ms","start":"2026-02-18T10:06:48.453783Z","end":"2026-02-18T10:06:48.708645Z","steps":["trace[1515018667] 'process raft request' (duration: 254.512564ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.708801Z","caller":"traceutil/trace.go:171","msg":"trace[398070615] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"265.615331ms","start":"2026-02-18T10:06:48.443157Z","end":"2026-02-18T10:06:48.708773Z","steps":["trace[398070615] 'process raft request' (duration: 265.065181ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.793667Z","caller":"traceutil/trace.go:171","msg":"trace[527367796] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"276.28725ms","start":"2026-02-18T10:06:48.517357Z","end":"2026-02-18T10:06:48.793644Z","steps":["trace[527367796] 'process raft request' (duration: 276.246419ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.794239Z","caller":"traceutil/trace.go:171","msg":"trace[417248491] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"282.065812ms","start":"2026-02-18T10:06:48.512161Z","end":"2026-02-18T10:06:48.794227Z","steps":["trace[417248491] 'process raft request' (duration: 281.121155ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.794686Z","caller":"traceutil/trace.go:171","msg":"trace[1701535001] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"280.29831ms","start":"2026-02-18T10:06:48.514376Z","end":"2026-02-18T10:06:48.794674Z","steps":["trace[1701535001] 'process raft request' (duration: 279.029327ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.795139Z","caller":"traceutil/trace.go:171","msg":"trace[1429527333] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"277.813106ms","start":"2026-02-18T10:06:48.517306Z","end":"2026-02-18T10:06:48.795119Z","steps":["trace[1429527333] 'process raft request' (duration: 276.144886ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:48.883692Z","caller":"traceutil/trace.go:171","msg":"trace[1241654685] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"164.47493ms","start":"2026-02-18T10:06:48.719201Z","end":"2026-02-18T10:06:48.883676Z","steps":["trace[1241654685] 'process raft request' (duration: 133.428121ms)","trace[1241654685] 'compare' (duration: 30.911067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:06:48.974498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.108738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-18T10:06:48.974684Z","caller":"traceutil/trace.go:171","msg":"trace[916005993] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:445; }","duration":"158.320852ms","start":"2026-02-18T10:06:48.816341Z","end":"2026-02-18T10:06:48.974662Z","steps":["trace[916005993] 'agreement among raft nodes before linearized reading' (duration: 158.008236ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:49.10967Z","caller":"traceutil/trace.go:171","msg":"trace[208211925] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"127.173352ms","start":"2026-02-18T10:06:48.982473Z","end":"2026-02-18T10:06:49.109646Z","steps":["trace[208211925] 'process raft request' (duration: 93.793823ms)","trace[208211925] 'compare' (duration: 32.453873ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:49.113817Z","caller":"traceutil/trace.go:171","msg":"trace[2037893617] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"124.295501ms","start":"2026-02-18T10:06:48.985967Z","end":"2026-02-18T10:06:49.110262Z","steps":["trace[2037893617] 'process raft request' (duration: 123.634059ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:52.48123Z","caller":"traceutil/trace.go:171","msg":"trace[522805634] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"104.447777ms","start":"2026-02-18T10:06:52.376761Z","end":"2026-02-18T10:06:52.481209Z","steps":["trace[522805634] 'process raft request' (duration: 104.321194ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:06:52.945006Z","caller":"traceutil/trace.go:171","msg":"trace[627519377] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"220.115778ms","start":"2026-02-18T10:06:52.724872Z","end":"2026-02-18T10:06:52.944988Z","steps":["trace[627519377] 'read index received' (duration: 219.948205ms)","trace[627519377] 'applied index is now lower than readState.Index' (duration: 166.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:52.945089Z","caller":"traceutil/trace.go:171","msg":"trace[194120850] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"220.549575ms","start":"2026-02-18T10:06:52.724533Z","end":"2026-02-18T10:06:52.945083Z","steps":["trace[194120850] 'process raft request' (duration: 220.350461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:52.945258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.368782ms","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-18T10:06:52.94528Z","caller":"traceutil/trace.go:171","msg":"trace[711078480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"220.410823ms","start":"2026-02-18T10:06:52.724861Z","end":"2026-02-18T10:06:52.945272Z","steps":["trace[711078480] 'agreement among raft nodes before linearized reading' (duration: 220.316101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:57.590768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886248943,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T10:06:57.619266Z","caller":"traceutil/trace.go:171","msg":"trace[1534544790] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"529.378944ms","start":"2026-02-18T10:06:57.089867Z","end":"2026-02-18T10:06:57.619245Z","steps":["trace[1534544790] 'read index received' (duration: 529.220241ms)","trace[1534544790] 'applied index is now lower than readState.Index' (duration: 157.933µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:06:57.619532Z","caller":"traceutil/trace.go:171","msg":"trace[1645483060] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"606.015779ms","start":"2026-02-18T10:06:57.013506Z","end":"2026-02-18T10:06:57.619521Z","steps":["trace[1645483060] 'process raft request' (duration: 605.633992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:57.619673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:06:57.013491Z","time spent":"606.115051ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:06:57.619865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.994574ms","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-18T10:06:57.619904Z","caller":"traceutil/trace.go:171","msg":"trace[1503233844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"530.067586ms","start":"2026-02-18T10:06:57.089826Z","end":"2026-02-18T10:06:57.619894Z","steps":["trace[1503233844] 'agreement among raft nodes before linearized reading' (duration: 530.001335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:57.619938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:06:57.089807Z","time spent":"530.123926ms","remote":"127.0.0.1:52074","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-18T10:06:57.620107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.430115ms","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-18T10:06:57.620144Z","caller":"traceutil/trace.go:171","msg":"trace[1021904110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"492.496936ms","start":"2026-02-18T10:06:57.127637Z","end":"2026-02-18T10:06:57.620134Z","steps":["trace[1021904110] 'agreement among raft nodes before linearized reading' (duration: 492.427915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:06:57.620175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:06:57.127622Z","time spent":"492.546646ms","remote":"127.0.0.1:52074","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-18T10:07:01.034568Z","caller":"traceutil/trace.go:171","msg":"trace[972570982] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"478.867175ms","start":"2026-02-18T10:07:00.555676Z","end":"2026-02-18T10:07:01.034543Z","steps":["trace[972570982] 'process raft request' (duration: 478.646081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:01.035035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:00.555655Z","time spent":"478.998047ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:07:03.267975Z","caller":"traceutil/trace.go:171","msg":"trace[1612065209] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"225.730104ms","start":"2026-02-18T10:07:03.042219Z","end":"2026-02-18T10:07:03.267949Z","steps":["trace[1612065209] 'process raft request' (duration: 225.585252ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:05.620065Z","caller":"traceutil/trace.go:171","msg":"trace[1002624287] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"139.448702ms","start":"2026-02-18T10:07:05.480585Z","end":"2026-02-18T10:07:05.620033Z","steps":["trace[1002624287] 'process raft request' (duration: 135.990535ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:05.628898Z","caller":"traceutil/trace.go:171","msg":"trace[490906235] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:577; }","duration":"132.994776ms","start":"2026-02-18T10:07:05.495872Z","end":"2026-02-18T10:07:05.628867Z","steps":["trace[490906235] 'read index received' (duration: 120.666552ms)","trace[490906235] 'applied index is now lower than readState.Index' (duration: 12.327434ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:05.628933Z","caller":"traceutil/trace.go:171","msg":"trace[400672124] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"133.945591ms","start":"2026-02-18T10:07:05.494966Z","end":"2026-02-18T10:07:05.628911Z","steps":["trace[400672124] 'process raft request' (duration: 133.699117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:05.629026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.124938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:05.629061Z","caller":"traceutil/trace.go:171","msg":"trace[1747421296] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:561; }","duration":"133.181529ms","start":"2026-02-18T10:07:05.495869Z","end":"2026-02-18T10:07:05.62905Z","steps":["trace[1747421296] 'agreement among raft nodes before linearized reading' (duration: 133.090467ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:05.629447Z","caller":"traceutil/trace.go:171","msg":"trace[276627112] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"134.318397ms","start":"2026-02-18T10:07:05.49512Z","end":"2026-02-18T10:07:05.629438Z","steps":["trace[276627112] 'process raft request' (duration: 133.711227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:06.726459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249150,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:07.226576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249150,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:07.72748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249150,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:07.993916Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.965943648s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T10:07:07.994633Z","caller":"traceutil/trace.go:171","msg":"trace[517102950] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.96671192s","start":"2026-02-18T10:07:06.0279Z","end":"2026-02-18T10:07:07.994611Z","steps":["trace[517102950] 'process raft request' (duration: 1.966542668s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:07.994842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.027884Z","time spent":"1.966860722s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T10:07:08.032852Z","caller":"traceutil/trace.go:171","msg":"trace[1564111668] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:598; }","duration":"1.807391637s","start":"2026-02-18T10:07:06.225445Z","end":"2026-02-18T10:07:08.032837Z","steps":["trace[1564111668] 'read index received' (duration: 1.768904508s)","trace[1564111668] 'applied index is now lower than readState.Index' (duration: 38.486099ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:08.033045Z","caller":"traceutil/trace.go:171","msg":"trace[161049522] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.849713592s","start":"2026-02-18T10:07:06.183325Z","end":"2026-02-18T10:07:08.033039Z","steps":["trace[161049522] 'process raft request' (duration: 1.849406247s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.033119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.183309Z","time spent":"1.849755392s","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:07:08.033253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807805243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:08.033284Z","caller":"traceutil/trace.go:171","msg":"trace[2054002395] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:581; }","duration":"1.807855764s","start":"2026-02-18T10:07:06.225419Z","end":"2026-02-18T10:07:08.033275Z","steps":["trace[2054002395] 'agreement among raft nodes before linearized reading' (duration: 1.807812603s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.033304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.225406Z","time spent":"1.807893384s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-18T10:07:08.033237Z","caller":"traceutil/trace.go:171","msg":"trace[498430858] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.720524197s","start":"2026-02-18T10:07:06.312692Z","end":"2026-02-18T10:07:08.033217Z","steps":["trace[498430858] 'process raft request' (duration: 1.72011529s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.033581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.312674Z","time spent":"1.720815142s","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4364,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:07:08.060824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634771233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:08.061036Z","caller":"traceutil/trace.go:171","msg":"trace[1810362427] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:582; }","duration":"1.635017948s","start":"2026-02-18T10:07:06.426004Z","end":"2026-02-18T10:07:08.061022Z","steps":["trace[1810362427] 'agreement among raft nodes before linearized reading' (duration: 1.634694943s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.061125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.425995Z","time spent":"1.635119019s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T10:07:08.062108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.268079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-18T10:07:08.062208Z","caller":"traceutil/trace.go:171","msg":"trace[427141076] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"236.409811ms","start":"2026-02-18T10:07:07.825789Z","end":"2026-02-18T10:07:08.062199Z","steps":["trace[427141076] 'agreement among raft nodes before linearized reading' (duration: 236.072126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.062519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.523708ms","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-18T10:07:08.062662Z","caller":"traceutil/trace.go:171","msg":"trace[1530912309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"290.733082ms","start":"2026-02-18T10:07:07.77192Z","end":"2026-02-18T10:07:08.062653Z","steps":["trace[1530912309] 'agreement among raft nodes before linearized reading' (duration: 290.56448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.062945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.25785ms","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-18T10:07:08.063084Z","caller":"traceutil/trace.go:171","msg":"trace[2076065242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"291.474074ms","start":"2026-02-18T10:07:07.771602Z","end":"2026-02-18T10:07:08.063076Z","steps":["trace[2076065242] 'agreement among raft nodes before linearized reading' (duration: 291.316702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.063324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.531747ms","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-18T10:07:08.063401Z","caller":"traceutil/trace.go:171","msg":"trace[2002253425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"307.635979ms","start":"2026-02-18T10:07:07.755757Z","end":"2026-02-18T10:07:08.063393Z","steps":["trace[2002253425] 'agreement among raft nodes before linearized reading' (duration: 307.508777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.063504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:07.75574Z","time spent":"307.754951ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T10:07:08.06377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.135451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-18T10:07:08.063855Z","caller":"traceutil/trace.go:171","msg":"trace[1891669614] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"437.255763ms","start":"2026-02-18T10:07:07.62659Z","end":"2026-02-18T10:07:08.063846Z","steps":["trace[1891669614] 'agreement among raft nodes before linearized reading' (duration: 437.147221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.063936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:07.626572Z","time spent":"437.355444ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T10:07:08.064185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.223831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-18T10:07:08.064248Z","caller":"traceutil/trace.go:171","msg":"trace[2135778386] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"637.325412ms","start":"2026-02-18T10:07:07.426917Z","end":"2026-02-18T10:07:08.064242Z","steps":["trace[2135778386] 'agreement among raft nodes before linearized reading' (duration: 637.214721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.064307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:07.426898Z","time spent":"637.401374ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-18T10:07:08.064506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.195707ms","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-18T10:07:08.064562Z","caller":"traceutil/trace.go:171","msg":"trace[223661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"757.294829ms","start":"2026-02-18T10:07:07.307261Z","end":"2026-02-18T10:07:08.064555Z","steps":["trace[223661] 'agreement among raft nodes before linearized reading' (duration: 757.208667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.064615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:07.307243Z","time spent":"757.366539ms","remote":"127.0.0.1:52074","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-18T10:07:08.064785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.221035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-18T10:07:08.064851Z","caller":"traceutil/trace.go:171","msg":"trace[1824833087] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"838.324066ms","start":"2026-02-18T10:07:07.226522Z","end":"2026-02-18T10:07:08.064846Z","steps":["trace[1824833087] 'agreement among raft nodes before linearized reading' (duration: 838.241035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.064906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:07.226502Z","time spent":"838.398178ms","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-18T10:07:08.065122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038720521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-18T10:07:08.065184Z","caller":"traceutil/trace.go:171","msg":"trace[1402116173] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"1.038833843s","start":"2026-02-18T10:07:07.026344Z","end":"2026-02-18T10:07:08.065178Z","steps":["trace[1402116173] 'agreement among raft nodes before linearized reading' (duration: 1.038720601s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.065239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:07.026328Z","time spent":"1.038904674s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-18T10:07:08.065455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136278884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:08.065539Z","caller":"traceutil/trace.go:171","msg":"trace[1144059771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"1.136437617s","start":"2026-02-18T10:07:06.929096Z","end":"2026-02-18T10:07:08.065534Z","steps":["trace[1144059771] 'agreement among raft nodes before linearized reading' (duration: 1.136341405s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.065607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.929079Z","time spent":"1.136523148s","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T10:07:08.065829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23940001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-18T10:07:08.065894Z","caller":"traceutil/trace.go:171","msg":"trace[40060210] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"1.239501651s","start":"2026-02-18T10:07:06.826386Z","end":"2026-02-18T10:07:08.065888Z","steps":["trace[40060210] 'agreement among raft nodes before linearized reading' (duration: 1.2394071s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.065952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.826371Z","time spent":"1.239575333s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-18T10:07:08.066187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43883565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-18T10:07:08.066267Z","caller":"traceutil/trace.go:171","msg":"trace[643010984] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"1.438955882s","start":"2026-02-18T10:07:06.627305Z","end":"2026-02-18T10:07:08.066261Z","steps":["trace[643010984] 'agreement among raft nodes before linearized reading' (duration: 1.43882201s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.066315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.62729Z","time spent":"1.439017473s","remote":"127.0.0.1:51850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-18T10:07:08.066536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558126042s","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-18T10:07:08.066614Z","caller":"traceutil/trace.go:171","msg":"trace[1114920305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.558240884s","start":"2026-02-18T10:07:06.508366Z","end":"2026-02-18T10:07:08.066607Z","steps":["trace[1114920305] 'agreement among raft nodes before linearized reading' (duration: 1.558150433s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:08.066703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:06.508354Z","time spent":"1.558342156s","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T10:07:09.800406Z","caller":"traceutil/trace.go:171","msg":"trace[1648571551] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"224.887828ms","start":"2026-02-18T10:07:09.575504Z","end":"2026-02-18T10:07:09.800392Z","steps":["trace[1648571551] 'process raft request' (duration: 224.677125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:16.425419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249258,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T10:07:16.499222Z","caller":"traceutil/trace.go:171","msg":"trace[110594559] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"574.142619ms","start":"2026-02-18T10:07:15.925065Z","end":"2026-02-18T10:07:16.499208Z","steps":["trace[110594559] 'read index received' (duration: 574.008056ms)","trace[110594559] 'applied index is now lower than readState.Index' (duration: 133.993µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:16.499434Z","caller":"traceutil/trace.go:171","msg":"trace[447705417] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"722.291901ms","start":"2026-02-18T10:07:15.777136Z","end":"2026-02-18T10:07:16.499428Z","steps":["trace[447705417] 'process raft request' (duration: 721.991126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:16.499495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:15.777117Z","time spent":"722.332271ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:07:16.499628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.572888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T10:07:16.499707Z","caller":"traceutil/trace.go:171","msg":"trace[450973768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"239.681719ms","start":"2026-02-18T10:07:16.260006Z","end":"2026-02-18T10:07:16.499688Z","steps":["trace[450973768] 'agreement among raft nodes before linearized reading' (duration: 239.457736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:16.499743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.981454ms","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-18T10:07:16.499766Z","caller":"traceutil/trace.go:171","msg":"trace[779905674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"223.015114ms","start":"2026-02-18T10:07:16.276745Z","end":"2026-02-18T10:07:16.49976Z","steps":["trace[779905674] 'agreement among raft nodes before linearized reading' (duration: 222.934983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:16.499864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.800759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:16.499878Z","caller":"traceutil/trace.go:171","msg":"trace[1544806251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"574.814449ms","start":"2026-02-18T10:07:15.925059Z","end":"2026-02-18T10:07:16.499874Z","steps":["trace[1544806251] 'agreement among raft nodes before linearized reading' (duration: 574.792679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:16.499873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.897968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-18T10:07:16.499895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:15.925006Z","time spent":"574.8838ms","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T10:07:16.499901Z","caller":"traceutil/trace.go:171","msg":"trace[370906893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"223.947189ms","start":"2026-02-18T10:07:16.275945Z","end":"2026-02-18T10:07:16.499893Z","steps":["trace[370906893] 'agreement among raft nodes before linearized reading' (duration: 223.867298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:16.499988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.634788ms","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-18T10:07:16.500004Z","caller":"traceutil/trace.go:171","msg":"trace[409355440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"222.648988ms","start":"2026-02-18T10:07:16.277349Z","end":"2026-02-18T10:07:16.499998Z","steps":["trace[409355440] 'agreement among raft nodes before linearized reading' (duration: 222.604727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:16.500149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.300061ms","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-18T10:07:16.500163Z","caller":"traceutil/trace.go:171","msg":"trace[422406983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"232.327162ms","start":"2026-02-18T10:07:16.267832Z","end":"2026-02-18T10:07:16.500159Z","steps":["trace[422406983] 'agreement among raft nodes before linearized reading' (duration: 232.284481ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:16.633199Z","caller":"traceutil/trace.go:171","msg":"trace[1191484444] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"116.259597ms","start":"2026-02-18T10:07:16.516921Z","end":"2026-02-18T10:07:16.633181Z","steps":["trace[1191484444] 'process raft request' (duration: 115.8161ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:16.633554Z","caller":"traceutil/trace.go:171","msg":"trace[2050310032] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"116.555872ms","start":"2026-02-18T10:07:16.51699Z","end":"2026-02-18T10:07:16.633546Z","steps":["trace[2050310032] 'process raft request' (duration: 115.845131ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:16.633839Z","caller":"traceutil/trace.go:171","msg":"trace[1408192680] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"114.812465ms","start":"2026-02-18T10:07:16.519022Z","end":"2026-02-18T10:07:16.633834Z","steps":["trace[1408192680] 'process raft request' (duration: 114.472409ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:16.633815Z","caller":"traceutil/trace.go:171","msg":"trace[1525184214] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"114.223205ms","start":"2026-02-18T10:07:16.519584Z","end":"2026-02-18T10:07:16.633807Z","steps":["trace[1525184214] 'process raft request' (duration: 113.943681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:19.08451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:19.584961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:20.085624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:20.586461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:20.929034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000804553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T10:07:20.929125Z","caller":"traceutil/trace.go:171","msg":"trace[1637478037] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000929445s","start":"2026-02-18T10:07:18.928172Z","end":"2026-02-18T10:07:20.929101Z","steps":["trace[1637478037] 'agreement among raft nodes before linearized reading' (duration: 2.000823493s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:20.929192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:18.928159Z","time spent":"2.001021466s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 10:07:20 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T10:07:21.087333Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:21.587565Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909820929886249267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:07:21.92416Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.746635522s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T10:07:22.935648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000286896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T10:07:22.935718Z","caller":"traceutil/trace.go:171","msg":"trace[1911770403] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000402268s","start":"2026-02-18T10:07:20.935301Z","end":"2026-02-18T10:07:22.935704Z","steps":["trace[1911770403] 'agreement among raft nodes before linearized reading' (duration: 2.000298816s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:22.935775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:20.935296Z","time spent":"2.000470229s","remote":"127.0.0.1:51796","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-18T10:07:23.509603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.582079198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:23.509667Z","caller":"traceutil/trace.go:171","msg":"trace[1931691571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"6.582139129s","start":"2026-02-18T10:07:16.927505Z","end":"2026-02-18T10:07:23.509644Z","steps":["trace[1931691571] 'range keys from in-memory index tree' (duration: 6.582017967s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.509696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:16.927463Z","time spent":"6.582224091s","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T10:07:23.509763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.150825033s","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-18T10:07:23.509816Z","caller":"traceutil/trace.go:171","msg":"trace[1050104688] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:621; }","duration":"6.150877454s","start":"2026-02-18T10:07:17.358931Z","end":"2026-02-18T10:07:23.509808Z","steps":["trace[1050104688] 'range keys from in-memory index tree' (duration: 6.150819103s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.51064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586231863s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T10:07:23.510701Z","caller":"traceutil/trace.go:171","msg":"trace[1470266799] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"5.333251161s","start":"2026-02-18T10:07:18.177439Z","end":"2026-02-18T10:07:23.510691Z","steps":["trace[1470266799] 'process raft request' (duration: 3.746908226s)","trace[1470266799] 'compare' (duration: 1.585044994s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:23.510732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:18.177424Z","time spent":"5.333294781s","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:07:23.534146Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.6098505s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T10:07:23.583799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000155223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T10:07:23.58386Z","caller":"traceutil/trace.go:171","msg":"trace[536200595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000249524s","start":"2026-02-18T10:07:18.583595Z","end":"2026-02-18T10:07:23.583845Z","steps":["trace[536200595] 'agreement among raft nodes before linearized reading' (duration: 5.000173973s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.583894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:18.583583Z","time spent":"5.000303585s","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/18 10:07:23 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-18T10:07:23.613743Z","caller":"traceutil/trace.go:171","msg":"trace[148563481] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"2.680032331s","start":"2026-02-18T10:07:20.933703Z","end":"2026-02-18T10:07:23.613735Z","steps":["trace[148563481] 'process raft request' (duration: 2.679815098s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.613802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:20.933689Z","time spent":"2.680076062s","remote":"127.0.0.1:51876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T10:07:23.613696Z","caller":"traceutil/trace.go:171","msg":"trace[1940001416] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:643; }","duration":"5.030061148s","start":"2026-02-18T10:07:18.583621Z","end":"2026-02-18T10:07:23.613682Z","steps":["trace[1940001416] 'read index received' (duration: 3.340734192s)","trace[1940001416] 'applied index is now lower than readState.Index' (duration: 1.689326396s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:23.620887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.982111819s","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-18T10:07:23.620923Z","caller":"traceutil/trace.go:171","msg":"trace[1188242304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"4.982190301s","start":"2026-02-18T10:07:18.638724Z","end":"2026-02-18T10:07:23.620914Z","steps":["trace[1188242304] 'agreement among raft nodes before linearized reading' (duration: 4.982048919s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.620943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:18.638707Z","time spent":"4.982231521s","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T10:07:23.621154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.276872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:23.621173Z","caller":"traceutil/trace.go:171","msg":"trace[2122656602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"896.311873ms","start":"2026-02-18T10:07:22.724854Z","end":"2026-02-18T10:07:23.621166Z","steps":["trace[2122656602] 'agreement among raft nodes before linearized reading' (duration: 896.279172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.621186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:22.724841Z","time spent":"896.341883ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T10:07:23.621717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.978299669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T10:07:23.621787Z","caller":"traceutil/trace.go:171","msg":"trace[496021433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"4.97839558s","start":"2026-02-18T10:07:18.643374Z","end":"2026-02-18T10:07:23.621769Z","steps":["trace[496021433] 'agreement among raft nodes before linearized reading' (duration: 4.978151286s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.62183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:18.643367Z","time spent":"4.978454551s","remote":"127.0.0.1:52074","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-18T10:07:23.621992Z","caller":"traceutil/trace.go:171","msg":"trace[1531925579] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"869.355422ms","start":"2026-02-18T10:07:22.752627Z","end":"2026-02-18T10:07:23.621983Z","steps":["trace[1531925579] 'process raft request' (duration: 868.089263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.622001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.983171006s","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-18T10:07:23.622035Z","caller":"traceutil/trace.go:171","msg":"trace[123231026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"4.983215926s","start":"2026-02-18T10:07:18.63881Z","end":"2026-02-18T10:07:23.622026Z","steps":["trace[123231026] 'agreement among raft nodes before linearized reading' (duration: 4.983132685s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.622059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:22.752612Z","time spent":"869.400613ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:07:23.622065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:18.638806Z","time spent":"4.983248376s","remote":"127.0.0.1:52074","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-18T10:07:23.622118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.91904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:23.622134Z","caller":"traceutil/trace.go:171","msg":"trace[737319333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"685.952302ms","start":"2026-02-18T10:07:22.936175Z","end":"2026-02-18T10:07:23.622128Z","steps":["trace[737319333] 'agreement among raft nodes before linearized reading' (duration: 685.926961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.622146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:22.936165Z","time spent":"685.978772ms","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T10:07:23.622214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.983407199s","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-18T10:07:23.622242Z","caller":"traceutil/trace.go:171","msg":"trace[604337765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:624; }","duration":"4.98344939s","start":"2026-02-18T10:07:18.638785Z","end":"2026-02-18T10:07:23.622234Z","steps":["trace[604337765] 'agreement among raft nodes before linearized reading' (duration: 4.983372059s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.622268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:18.63878Z","time spent":"4.983482001s","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T10:07:23.96192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.526328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T10:07:23.962036Z","caller":"traceutil/trace.go:171","msg":"trace[434214310] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"168.981348ms","start":"2026-02-18T10:07:23.793035Z","end":"2026-02-18T10:07:23.962016Z","steps":["trace[434214310] 'read index received' (duration: 42.233176ms)","trace[434214310] 'applied index is now lower than readState.Index' (duration: 126.746531ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:23.962165Z","caller":"traceutil/trace.go:171","msg":"trace[1842157859] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"169.11815ms","start":"2026-02-18T10:07:23.793006Z","end":"2026-02-18T10:07:23.962124Z","steps":["trace[1842157859] 'process raft request' (duration: 42.296718ms)","trace[1842157859] 'compare' (duration: 126.406936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:23.962745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.696449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-47qbq\" ","response":"range_response_count:1 size:16814"} {"level":"info","ts":"2026-02-18T10:07:23.962797Z","caller":"traceutil/trace.go:171","msg":"trace[524542924] range","detail":"{range_begin:/registry/pods/kube-system/cilium-47qbq; range_end:; response_count:1; response_revision:630; }","duration":"169.781131ms","start":"2026-02-18T10:07:23.793004Z","end":"2026-02-18T10:07:23.962785Z","steps":["trace[524542924] 'agreement among raft nodes before linearized reading' (duration: 169.594738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:23.963034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.012612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T10:07:23.963071Z","caller":"traceutil/trace.go:171","msg":"trace[1740265521] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:630; }","duration":"112.084693ms","start":"2026-02-18T10:07:23.850977Z","end":"2026-02-18T10:07:23.963062Z","steps":["trace[1740265521] 'agreement among raft nodes before linearized reading' (duration: 111.994631ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:24.080075Z","caller":"traceutil/trace.go:171","msg":"trace[1138381100] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"104.759225ms","start":"2026-02-18T10:07:23.975294Z","end":"2026-02-18T10:07:24.080053Z","steps":["trace[1138381100] 'process raft request' (duration: 92.423814ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:24.612523Z","caller":"traceutil/trace.go:171","msg":"trace[152588278] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"298.136463ms","start":"2026-02-18T10:07:24.31437Z","end":"2026-02-18T10:07:24.612507Z","steps":["trace[152588278] 'process raft request' (duration: 279.995781ms)","trace[152588278] 'compare' (duration: 17.837347ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:25.548745Z","caller":"traceutil/trace.go:171","msg":"trace[905074540] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"732.523194ms","start":"2026-02-18T10:07:24.816198Z","end":"2026-02-18T10:07:25.548722Z","steps":["trace[905074540] 'process raft request' (duration: 732.378941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:25.548886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:24.816182Z","time spent":"732.627935ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17059,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:07:25.765245Z","caller":"traceutil/trace.go:171","msg":"trace[2140339098] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"176.627921ms","start":"2026-02-18T10:07:25.5886Z","end":"2026-02-18T10:07:25.765228Z","steps":["trace[2140339098] 'process raft request' (duration: 143.724793ms)","trace[2140339098] 'compare' (duration: 32.505152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:26.247053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.328007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T10:07:26.247207Z","caller":"traceutil/trace.go:171","msg":"trace[793871573] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"444.084356ms","start":"2026-02-18T10:07:25.803113Z","end":"2026-02-18T10:07:26.247197Z","steps":["trace[793871573] 'read index received' (duration: 82.686238ms)","trace[793871573] 'applied index is now lower than readState.Index' (duration: 361.397428ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:26.247307Z","caller":"traceutil/trace.go:171","msg":"trace[651961812] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"478.528418ms","start":"2026-02-18T10:07:25.768772Z","end":"2026-02-18T10:07:26.247301Z","steps":["trace[651961812] 'process raft request' (duration: 116.899907ms)","trace[651961812] 'compare' (duration: 361.076313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:26.247339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:25.768759Z","time spent":"478.561959ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:07:26.247423Z","caller":"traceutil/trace.go:171","msg":"trace[890623783] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"477.434191ms","start":"2026-02-18T10:07:25.769984Z","end":"2026-02-18T10:07:26.247418Z","steps":["trace[890623783] 'process raft request' (duration: 477.127967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:26.247448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:25.769978Z","time spent":"477.456231ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:07:26.247501Z","caller":"traceutil/trace.go:171","msg":"trace[148883149] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"475.602193ms","start":"2026-02-18T10:07:25.771895Z","end":"2026-02-18T10:07:26.247497Z","steps":["trace[148883149] 'process raft request' (duration: 475.250378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:26.247523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:25.771889Z","time spent":"475.621224ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:07:26.247573Z","caller":"traceutil/trace.go:171","msg":"trace[2114936656] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"474.331184ms","start":"2026-02-18T10:07:25.773237Z","end":"2026-02-18T10:07:26.247569Z","steps":["trace[2114936656] 'process raft request' (duration: 473.922897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:26.247594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:25.773231Z","time spent":"474.351834ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:07:26.247643Z","caller":"traceutil/trace.go:171","msg":"trace[1658835778] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"442.260338ms","start":"2026-02-18T10:07:25.805379Z","end":"2026-02-18T10:07:26.247639Z","steps":["trace[1658835778] 'process raft request' (duration: 441.798251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:26.247664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:25.805363Z","time spent":"442.289618ms","remote":"127.0.0.1:51876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T10:07:26.2478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.694245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-47qbq\" ","response":"range_response_count:1 size:17074"} {"level":"info","ts":"2026-02-18T10:07:26.247814Z","caller":"traceutil/trace.go:171","msg":"trace[172559919] range","detail":"{range_begin:/registry/pods/kube-system/cilium-47qbq; range_end:; response_count:1; response_revision:641; }","duration":"444.736165ms","start":"2026-02-18T10:07:25.803074Z","end":"2026-02-18T10:07:26.24781Z","steps":["trace[172559919] 'agreement among raft nodes before linearized reading' (duration: 444.665914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:26.247827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:25.803059Z","time spent":"444.765856ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17098,"request content":"key:\"/registry/pods/kube-system/cilium-47qbq\" "} {"level":"warn","ts":"2026-02-18T10:07:26.247902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.096554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:26.247915Z","caller":"traceutil/trace.go:171","msg":"trace[117296738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"319.133964ms","start":"2026-02-18T10:07:25.928776Z","end":"2026-02-18T10:07:26.247909Z","steps":["trace[117296738] 'agreement among raft nodes before linearized reading' (duration: 319.115033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:26.247927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:25.92876Z","time spent":"319.164504ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T10:07:26.53066Z","caller":"traceutil/trace.go:171","msg":"trace[1514800275] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"266.978377ms","start":"2026-02-18T10:07:26.263654Z","end":"2026-02-18T10:07:26.530617Z","steps":["trace[1514800275] 'process raft request' (duration: 218.943336ms)","trace[1514800275] 'compare' (duration: 47.408751ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:27.555831Z","caller":"traceutil/trace.go:171","msg":"trace[1761712667] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"100.722658ms","start":"2026-02-18T10:07:27.455092Z","end":"2026-02-18T10:07:27.555815Z","steps":["trace[1761712667] 'process raft request' (duration: 100.623137ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:27.994664Z","caller":"traceutil/trace.go:171","msg":"trace[1804014248] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"311.369607ms","start":"2026-02-18T10:07:27.683287Z","end":"2026-02-18T10:07:27.994656Z","steps":["trace[1804014248] 'process raft request' (duration: 311.193754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:27.994738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:27.683257Z","time spent":"311.436848ms","remote":"127.0.0.1:51876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T10:07:27.994618Z","caller":"traceutil/trace.go:171","msg":"trace[314478885] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"224.877787ms","start":"2026-02-18T10:07:27.769723Z","end":"2026-02-18T10:07:27.994601Z","steps":["trace[314478885] 'read index received' (duration: 224.694524ms)","trace[314478885] 'applied index is now lower than readState.Index' (duration: 182.463µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:27.995101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.879176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-47qbq\" ","response":"range_response_count:1 size:17167"} {"level":"info","ts":"2026-02-18T10:07:27.995118Z","caller":"traceutil/trace.go:171","msg":"trace[1484526803] range","detail":"{range_begin:/registry/pods/kube-system/cilium-47qbq; range_end:; response_count:1; response_revision:644; }","duration":"181.923337ms","start":"2026-02-18T10:07:27.813189Z","end":"2026-02-18T10:07:27.995113Z","steps":["trace[1484526803] 'agreement among raft nodes before linearized reading' (duration: 181.823185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:27.99539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.66978ms","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-18T10:07:27.995406Z","caller":"traceutil/trace.go:171","msg":"trace[141043365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"225.71576ms","start":"2026-02-18T10:07:27.769686Z","end":"2026-02-18T10:07:27.995401Z","steps":["trace[141043365] 'agreement among raft nodes before linearized reading' (duration: 225.266723ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:28.323144Z","caller":"traceutil/trace.go:171","msg":"trace[1964844129] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"321.53779ms","start":"2026-02-18T10:07:28.001578Z","end":"2026-02-18T10:07:28.323116Z","steps":["trace[1964844129] 'process raft request' (duration: 321.305976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:28.323312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:28.001532Z","time spent":"321.691472ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:07:28.461653Z","caller":"traceutil/trace.go:171","msg":"trace[1935354277] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"205.705984ms","start":"2026-02-18T10:07:28.255932Z","end":"2026-02-18T10:07:28.461638Z","steps":["trace[1935354277] 'read index received' (duration: 66.904445ms)","trace[1935354277] 'applied index is now lower than readState.Index' (duration: 138.800949ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:28.461818Z","caller":"traceutil/trace.go:171","msg":"trace[1357607315] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"453.31616ms","start":"2026-02-18T10:07:28.008492Z","end":"2026-02-18T10:07:28.461808Z","steps":["trace[1357607315] 'process raft request' (duration: 453.071437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:28.461882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:28.008478Z","time spent":"453.359661ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17314,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:07:28.462016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.082829ms","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-18T10:07:28.462036Z","caller":"traceutil/trace.go:171","msg":"trace[800441344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:646; }","duration":"206.131641ms","start":"2026-02-18T10:07:28.2559Z","end":"2026-02-18T10:07:28.462032Z","steps":["trace[800441344] 'agreement among raft nodes before linearized reading' (duration: 206.06242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:28.462138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.985658ms","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-18T10:07:28.46215Z","caller":"traceutil/trace.go:171","msg":"trace[197201801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"206.010538ms","start":"2026-02-18T10:07:28.256136Z","end":"2026-02-18T10:07:28.462147Z","steps":["trace[197201801] 'agreement among raft nodes before linearized reading' (duration: 205.972958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:28.462311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.608481ms","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-18T10:07:28.462324Z","caller":"traceutil/trace.go:171","msg":"trace[94521835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"201.637431ms","start":"2026-02-18T10:07:28.260683Z","end":"2026-02-18T10:07:28.46232Z","steps":["trace[94521835] 'agreement among raft nodes before linearized reading' (duration: 201.598311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:28.462396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.725053ms","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-18T10:07:28.462407Z","caller":"traceutil/trace.go:171","msg":"trace[530346071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"201.750183ms","start":"2026-02-18T10:07:28.260654Z","end":"2026-02-18T10:07:28.462404Z","steps":["trace[530346071] 'agreement among raft nodes before linearized reading' (duration: 201.718783ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:28.689347Z","caller":"traceutil/trace.go:171","msg":"trace[220188979] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"216.535389ms","start":"2026-02-18T10:07:28.472796Z","end":"2026-02-18T10:07:28.689331Z","steps":["trace[220188979] 'process raft request' (duration: 163.470226ms)","trace[220188979] 'compare' (duration: 52.952871ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:28.689502Z","caller":"traceutil/trace.go:171","msg":"trace[897860080] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"216.619961ms","start":"2026-02-18T10:07:28.472877Z","end":"2026-02-18T10:07:28.689497Z","steps":["trace[897860080] 'process raft request' (duration: 216.418368ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:28.689585Z","caller":"traceutil/trace.go:171","msg":"trace[43976279] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"214.451197ms","start":"2026-02-18T10:07:28.47513Z","end":"2026-02-18T10:07:28.689581Z","steps":["trace[43976279] 'process raft request' (duration: 214.329245ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:29.300467Z","caller":"traceutil/trace.go:171","msg":"trace[1268578652] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"371.327951ms","start":"2026-02-18T10:07:28.929108Z","end":"2026-02-18T10:07:29.300436Z","steps":["trace[1268578652] 'read index received' (duration: 327.265148ms)","trace[1268578652] 'applied index is now lower than readState.Index' (duration: 44.061763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:29.300593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.453943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:29.300625Z","caller":"traceutil/trace.go:171","msg":"trace[659854682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"371.568025ms","start":"2026-02-18T10:07:28.929047Z","end":"2026-02-18T10:07:29.300615Z","steps":["trace[659854682] 'agreement among raft nodes before linearized reading' (duration: 371.485914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:29.30066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:28.929029Z","time spent":"371.623286ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T10:07:29.300678Z","caller":"traceutil/trace.go:171","msg":"trace[536514547] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"421.906277ms","start":"2026-02-18T10:07:28.87875Z","end":"2026-02-18T10:07:29.300656Z","steps":["trace[536514547] 'process raft request' (duration: 377.61089ms)","trace[536514547] 'compare' (duration: 43.949101ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:29.300787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:28.878733Z","time spent":"421.995738ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17396,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:07:29.480962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.498306ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T10:07:29.481113Z","caller":"traceutil/trace.go:171","msg":"trace[1775545995] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"175.766607ms","start":"2026-02-18T10:07:29.305335Z","end":"2026-02-18T10:07:29.481101Z","steps":["trace[1775545995] 'read index received' (duration: 43.059309ms)","trace[1775545995] 'applied index is now lower than readState.Index' (duration: 132.703778ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:29.4812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.856979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:29.481229Z","caller":"traceutil/trace.go:171","msg":"trace[816128009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"175.917029ms","start":"2026-02-18T10:07:29.305301Z","end":"2026-02-18T10:07:29.481218Z","steps":["trace[816128009] 'agreement among raft nodes before linearized reading' (duration: 175.830678ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:30.824832Z","caller":"traceutil/trace.go:171","msg":"trace[1634450825] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"275.623553ms","start":"2026-02-18T10:07:30.549184Z","end":"2026-02-18T10:07:30.824808Z","steps":["trace[1634450825] 'read index received' (duration: 225.901145ms)","trace[1634450825] 'applied index is now lower than readState.Index' (duration: 49.721408ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:30.824946Z","caller":"traceutil/trace.go:171","msg":"trace[2094956622] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"421.406655ms","start":"2026-02-18T10:07:30.403529Z","end":"2026-02-18T10:07:30.824935Z","steps":["trace[2094956622] 'process raft request' (duration: 371.619246ms)","trace[2094956622] 'compare' (duration: 49.487965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:07:30.825026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:30.403507Z","time spent":"421.466677ms","remote":"127.0.0.1:51876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T10:07:30.825324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.903346ms","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-18T10:07:30.82537Z","caller":"traceutil/trace.go:171","msg":"trace[1676407046] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:655; }","duration":"130.985278ms","start":"2026-02-18T10:07:30.694374Z","end":"2026-02-18T10:07:30.825359Z","steps":["trace[1676407046] 'agreement among raft nodes before linearized reading' (duration: 130.812715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:30.825529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.343044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T10:07:30.825552Z","caller":"traceutil/trace.go:171","msg":"trace[1329409904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"276.390124ms","start":"2026-02-18T10:07:30.549156Z","end":"2026-02-18T10:07:30.825546Z","steps":["trace[1329409904] 'agreement among raft nodes before linearized reading' (duration: 276.339144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:30.825661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.327617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T10:07:30.825676Z","caller":"traceutil/trace.go:171","msg":"trace[121519144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"130.353617ms","start":"2026-02-18T10:07:30.695319Z","end":"2026-02-18T10:07:30.825673Z","steps":["trace[121519144] 'agreement among raft nodes before linearized reading' (duration: 130.310367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:30.825741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.270481ms","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-18T10:07:30.825754Z","caller":"traceutil/trace.go:171","msg":"trace[1314331119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"131.324023ms","start":"2026-02-18T10:07:30.694427Z","end":"2026-02-18T10:07:30.825751Z","steps":["trace[1314331119] 'agreement among raft nodes before linearized reading' (duration: 131.285612ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:31.057841Z","caller":"traceutil/trace.go:171","msg":"trace[1429562143] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"227.973744ms","start":"2026-02-18T10:07:30.829849Z","end":"2026-02-18T10:07:31.057823Z","steps":["trace[1429562143] 'process raft request' (duration: 141.585549ms)","trace[1429562143] 'compare' (duration: 86.198892ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:31.093109Z","caller":"traceutil/trace.go:171","msg":"trace[842898167] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"260.312815ms","start":"2026-02-18T10:07:30.83278Z","end":"2026-02-18T10:07:31.093093Z","steps":["trace[842898167] 'process raft request' (duration: 260.191763ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:31.09351Z","caller":"traceutil/trace.go:171","msg":"trace[234924037] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"260.62952ms","start":"2026-02-18T10:07:30.832872Z","end":"2026-02-18T10:07:31.093502Z","steps":["trace[234924037] 'process raft request' (duration: 260.194624ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:31.093495Z","caller":"traceutil/trace.go:171","msg":"trace[124361368] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:680; }","duration":"259.386261ms","start":"2026-02-18T10:07:30.834049Z","end":"2026-02-18T10:07:31.093435Z","steps":["trace[124361368] 'read index received' (duration: 137.395705ms)","trace[124361368] 'applied index is now lower than readState.Index' (duration: 121.989446ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:31.093607Z","caller":"traceutil/trace.go:171","msg":"trace[605940226] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"259.601855ms","start":"2026-02-18T10:07:30.833999Z","end":"2026-02-18T10:07:31.093601Z","steps":["trace[605940226] 'process raft request' (duration: 259.356981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:31.093896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.902357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:07:31.093929Z","caller":"traceutil/trace.go:171","msg":"trace[282629095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"167.964428ms","start":"2026-02-18T10:07:30.92595Z","end":"2026-02-18T10:07:31.093915Z","steps":["trace[282629095] 'agreement among raft nodes before linearized reading' (duration: 167.915107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:31.094065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.97142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-47qbq\" ","response":"range_response_count:1 size:17411"} {"level":"info","ts":"2026-02-18T10:07:31.094197Z","caller":"traceutil/trace.go:171","msg":"trace[621553199] range","detail":"{range_begin:/registry/pods/kube-system/cilium-47qbq; range_end:; response_count:1; response_revision:659; }","duration":"260.128322ms","start":"2026-02-18T10:07:30.834026Z","end":"2026-02-18T10:07:31.094154Z","steps":["trace[621553199] 'agreement among raft nodes before linearized reading' (duration: 259.807877ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:31.219987Z","caller":"traceutil/trace.go:171","msg":"trace[683682587] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"112.670612ms","start":"2026-02-18T10:07:31.10729Z","end":"2026-02-18T10:07:31.219961Z","steps":["trace[683682587] 'process raft request' (duration: 99.935049ms)","trace[683682587] 'compare' (duration: 12.555161ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:31.964419Z","caller":"traceutil/trace.go:171","msg":"trace[994605570] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"105.384273ms","start":"2026-02-18T10:07:31.859017Z","end":"2026-02-18T10:07:31.964402Z","steps":["trace[994605570] 'process raft request' (duration: 87.323798ms)","trace[994605570] 'compare' (duration: 17.74868ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:07:32.707451Z","caller":"traceutil/trace.go:171","msg":"trace[263925277] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"286.917272ms","start":"2026-02-18T10:07:32.42051Z","end":"2026-02-18T10:07:32.707427Z","steps":["trace[263925277] 'process raft request' (duration: 286.747739ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:32.981782Z","caller":"traceutil/trace.go:171","msg":"trace[403335354] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"113.700305ms","start":"2026-02-18T10:07:32.868062Z","end":"2026-02-18T10:07:32.981762Z","steps":["trace[403335354] 'process raft request' (duration: 113.596133ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:33.854358Z","caller":"traceutil/trace.go:171","msg":"trace[121930251] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"311.909218ms","start":"2026-02-18T10:07:33.542429Z","end":"2026-02-18T10:07:33.854338Z","steps":["trace[121930251] 'process raft request' (duration: 311.453751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:07:33.854481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:07:33.542396Z","time spent":"312.02031ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:07:33.85451Z","caller":"traceutil/trace.go:171","msg":"trace[1414598819] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"112.582433ms","start":"2026-02-18T10:07:33.741914Z","end":"2026-02-18T10:07:33.854497Z","steps":["trace[1414598819] 'process raft request' (duration: 112.274848ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:35.000365Z","caller":"traceutil/trace.go:171","msg":"trace[1977041785] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"111.33848ms","start":"2026-02-18T10:07:34.889006Z","end":"2026-02-18T10:07:35.000344Z","steps":["trace[1977041785] 'process raft request' (duration: 101.472241ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:07:40.497667Z","caller":"traceutil/trace.go:171","msg":"trace[950572023] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"121.577307ms","start":"2026-02-18T10:07:40.376073Z","end":"2026-02-18T10:07:40.497651Z","steps":["trace[950572023] 'process raft request' (duration: 121.481906ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:08:01.432444Z","caller":"traceutil/trace.go:171","msg":"trace[741507763] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"103.400362ms","start":"2026-02-18T10:08:01.329025Z","end":"2026-02-18T10:08:01.432425Z","steps":["trace[741507763] 'read index received' (duration: 103.25448ms)","trace[741507763] 'applied index is now lower than readState.Index' (duration: 145.012µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:08:01.432679Z","caller":"traceutil/trace.go:171","msg":"trace[631757848] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"123.946015ms","start":"2026-02-18T10:08:01.308722Z","end":"2026-02-18T10:08:01.432668Z","steps":["trace[631757848] 'process raft request' (duration: 123.60556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:08:01.432894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.854639ms","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-18T10:08:01.432928Z","caller":"traceutil/trace.go:171","msg":"trace[616796550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:841; }","duration":"103.898139ms","start":"2026-02-18T10:08:01.329021Z","end":"2026-02-18T10:08:01.432919Z","steps":["trace[616796550] 'agreement among raft nodes before linearized reading' (duration: 103.788608ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:08:01.558948Z","caller":"traceutil/trace.go:171","msg":"trace[1828564745] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"101.673957ms","start":"2026-02-18T10:08:01.457251Z","end":"2026-02-18T10:08:01.558925Z","steps":["trace[1828564745] 'read index received' (duration: 64.207024ms)","trace[1828564745] 'applied index is now lower than readState.Index' (duration: 37.466003ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:08:01.560015Z","caller":"traceutil/trace.go:171","msg":"trace[705762900] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"119.346779ms","start":"2026-02-18T10:08:01.440648Z","end":"2026-02-18T10:08:01.559994Z","steps":["trace[705762900] 'process raft request' (duration: 98.904617ms)","trace[705762900] 'compare' (duration: 19.288565ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:08:01.560127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.856464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:08:01.560155Z","caller":"traceutil/trace.go:171","msg":"trace[1615055496] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:843; }","duration":"102.924586ms","start":"2026-02-18T10:08:01.457222Z","end":"2026-02-18T10:08:01.560146Z","steps":["trace[1615055496] 'agreement among raft nodes before linearized reading' (duration: 102.868155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:08:27.755793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.319064ms","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-18T10:08:27.755856Z","caller":"traceutil/trace.go:171","msg":"trace[984680186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:916; }","duration":"116.438625ms","start":"2026-02-18T10:08:27.639405Z","end":"2026-02-18T10:08:27.755844Z","steps":["trace[984680186] 'range keys from in-memory index tree' (duration: 116.169122ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:08:49.794258Z","caller":"traceutil/trace.go:171","msg":"trace[1114887790] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"112.547901ms","start":"2026-02-18T10:08:49.681684Z","end":"2026-02-18T10:08:49.794232Z","steps":["trace[1114887790] 'process raft request' (duration: 112.119563ms)"],"step_count":1}