{"level":"warn","ts":"2026-02-17T16:12:51.469906Z","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-17T16:12:51.470011Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.27: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.27:2380","--initial-cluster=instance=https://199.19.213.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.27: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-17T16:12:51.470073Z","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-17T16:12:51.470082Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.27:2380"]} {"level":"info","ts":"2026-02-17T16:12:51.4701Z","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-17T16:12:51.470603Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"]} {"level":"info","ts":"2026-02-17T16:12:51.470665Z","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.27:2380"],"listen-peer-urls":["https://199.19.213.27:2380"],"advertise-client-urls":["https://199.19.213.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.27: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-17T16:12:51.476969Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.095954ms"} {"level":"info","ts":"2026-02-17T16:12:51.487481Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0494904b1be7692","cluster-id":"7c10a1b21fbd6dff"} {"level":"info","ts":"2026-02-17T16:12:51.487556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T16:12:51.487574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became follower at term 0"} {"level":"info","ts":"2026-02-17T16:12:51.487582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0494904b1be7692 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T16:12:51.487588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became follower at term 1"} {"level":"info","ts":"2026-02-17T16:12:51.487617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=(15008607517699634834)"} {"level":"warn","ts":"2026-02-17T16:12:51.507862Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T16:12:51.52292Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T16:12:51.525512Z","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-17T16:12:51.533891Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0494904b1be7692","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T16:12:51.535653Z","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-17T16:12:51.547011Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0494904b1be7692","initial-advertise-peer-urls":["https://199.19.213.27:2380"],"listen-peer-urls":["https://199.19.213.27:2380"],"advertise-client-urls":["https://199.19.213.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T16:12:51.547048Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T16:12:51.535752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0494904b1be7692","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T16:12:51.535907Z","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-17T16:12:51.548151Z","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-17T16:12:51.548222Z","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-17T16:12:51.536105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=(15008607517699634834)"} {"level":"info","ts":"2026-02-17T16:12:51.548387Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7c10a1b21fbd6dff","local-member-id":"d0494904b1be7692","added-peer-id":"d0494904b1be7692","added-peer-peer-urls":["https://199.19.213.27:2380"]} {"level":"info","ts":"2026-02-17T16:12:51.536222Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.27:2380"} {"level":"info","ts":"2026-02-17T16:12:51.548465Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.27:2380"} {"level":"info","ts":"2026-02-17T16:12:52.288682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T16:12:52.288749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T16:12:52.288774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 received MsgPreVoteResp from d0494904b1be7692 at term 1"} {"level":"info","ts":"2026-02-17T16:12:52.288784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became candidate at term 2"} {"level":"info","ts":"2026-02-17T16:12:52.288789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 received MsgVoteResp from d0494904b1be7692 at term 2"} {"level":"info","ts":"2026-02-17T16:12:52.288797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became leader at term 2"} {"level":"info","ts":"2026-02-17T16:12:52.288804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0494904b1be7692 elected leader d0494904b1be7692 at term 2"} {"level":"info","ts":"2026-02-17T16:12:52.290926Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:12:52.29311Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0494904b1be7692","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.27:2379]}","request-path":"/0/members/d0494904b1be7692/attributes","cluster-id":"7c10a1b21fbd6dff","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T16:12:52.293595Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:12:52.293898Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:12:52.295072Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7c10a1b21fbd6dff","local-member-id":"d0494904b1be7692","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:12:52.29512Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:12:52.295133Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:12:52.299706Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T16:12:52.312777Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T16:12:52.312793Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T16:12:52.322086Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.27:2379"} {"level":"info","ts":"2026-02-17T16:12:55.036605Z","caller":"traceutil/trace.go:171","msg":"trace[110651894] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"173.830052ms","start":"2026-02-17T16:12:54.862753Z","end":"2026-02-17T16:12:55.036583Z","steps":["trace[110651894] 'read index received' (duration: 173.628177ms)","trace[110651894] 'applied index is now lower than readState.Index' (duration: 201.045µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:55.03717Z","caller":"traceutil/trace.go:171","msg":"trace[1261449737] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"176.376402ms","start":"2026-02-17T16:12:54.86078Z","end":"2026-02-17T16:12:55.037156Z","steps":["trace[1261449737] 'process raft request' (duration: 175.654474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:55.037427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.640731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:12:55.040293Z","caller":"traceutil/trace.go:171","msg":"trace[732452648] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"177.53924ms","start":"2026-02-17T16:12:54.862731Z","end":"2026-02-17T16:12:55.04027Z","steps":["trace[732452648] 'agreement among raft nodes before linearized reading' (duration: 174.531429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:55.77251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.495077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-17T16:12:55.772612Z","caller":"traceutil/trace.go:171","msg":"trace[1913384551] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"724.140349ms","start":"2026-02-17T16:12:55.048455Z","end":"2026-02-17T16:12:55.772596Z","steps":["trace[1913384551] 'read index received' (duration: 416.976746ms)","trace[1913384551] 'applied index is now lower than readState.Index' (duration: 307.162513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:55.772718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.252543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:12:55.772746Z","caller":"traceutil/trace.go:171","msg":"trace[1087901717] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:73; }","duration":"724.282443ms","start":"2026-02-17T16:12:55.048454Z","end":"2026-02-17T16:12:55.772736Z","steps":["trace[1087901717] 'agreement among raft nodes before linearized reading' (duration: 724.184111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:55.77278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:55.048438Z","time spent":"724.333134ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-02-17T16:12:55.773035Z","caller":"traceutil/trace.go:171","msg":"trace[125042200] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"725.169665ms","start":"2026-02-17T16:12:55.047845Z","end":"2026-02-17T16:12:55.773015Z","steps":["trace[125042200] 'process raft request' (duration: 417.638334ms)","trace[125042200] 'compare' (duration: 306.366123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:55.773091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:55.047838Z","time spent":"725.222876ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:13:07.664183Z","caller":"traceutil/trace.go:171","msg":"trace[275507441] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"212.261433ms","start":"2026-02-17T16:13:07.451907Z","end":"2026-02-17T16:13:07.664168Z","steps":["trace[275507441] 'process raft request' (duration: 211.950386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:08.604499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.706278ms","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-17T16:13:08.604563Z","caller":"traceutil/trace.go:171","msg":"trace[1620952656] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:366; }","duration":"178.7793ms","start":"2026-02-17T16:13:08.425771Z","end":"2026-02-17T16:13:08.60455Z","steps":["trace[1620952656] 'range keys from in-memory index tree' (duration: 178.599725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:08.604662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.79029ms","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-17T16:13:08.604752Z","caller":"traceutil/trace.go:171","msg":"trace[824315048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:366; }","duration":"143.944374ms","start":"2026-02-17T16:13:08.460792Z","end":"2026-02-17T16:13:08.604736Z","steps":["trace[824315048] 'range keys from in-memory index tree' (duration: 143.667646ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:22.156113Z","caller":"traceutil/trace.go:171","msg":"trace[1854963218] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"170.230517ms","start":"2026-02-17T16:13:21.985863Z","end":"2026-02-17T16:13:22.156094Z","steps":["trace[1854963218] 'read index received' (duration: 170.013482ms)","trace[1854963218] 'applied index is now lower than readState.Index' (duration: 216.125µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:22.156417Z","caller":"traceutil/trace.go:171","msg":"trace[901047569] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"365.7998ms","start":"2026-02-17T16:13:21.790606Z","end":"2026-02-17T16:13:22.156406Z","steps":["trace[901047569] 'process raft request' (duration: 365.3238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:22.156713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:21.790586Z","time spent":"365.870263ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:22.156931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.075417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:13:22.156964Z","caller":"traceutil/trace.go:171","msg":"trace[828726908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"171.140118ms","start":"2026-02-17T16:13:21.985814Z","end":"2026-02-17T16:13:22.156954Z","steps":["trace[828726908] 'agreement among raft nodes before linearized reading' (duration: 171.076326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:29.660014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.369036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:29.660086Z","caller":"traceutil/trace.go:171","msg":"trace[243582624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"210.495219ms","start":"2026-02-17T16:13:29.449577Z","end":"2026-02-17T16:13:29.660072Z","steps":["trace[243582624] 'range keys from in-memory index tree' (duration: 210.277745ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:30.374153Z","caller":"traceutil/trace.go:171","msg":"trace[2115649827] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"107.560362ms","start":"2026-02-17T16:13:30.266573Z","end":"2026-02-17T16:13:30.374133Z","steps":["trace[2115649827] 'process raft request' (duration: 50.38992ms)","trace[2115649827] 'compare' (duration: 57.099961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:30.838195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.216579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-17T16:13:30.838214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.381885ms","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-17T16:13:30.838283Z","caller":"traceutil/trace.go:171","msg":"trace[871938168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"102.487559ms","start":"2026-02-17T16:13:30.735776Z","end":"2026-02-17T16:13:30.838264Z","steps":["trace[871938168] 'range keys from in-memory index tree' (duration: 102.294603ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:30.838267Z","caller":"traceutil/trace.go:171","msg":"trace[971425760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"328.329162ms","start":"2026-02-17T16:13:30.50992Z","end":"2026-02-17T16:13:30.838249Z","steps":["trace[971425760] 'range keys from in-memory index tree' (duration: 328.093066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:30.838427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:30.509903Z","time spent":"328.511066ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T16:13:34.741169Z","caller":"traceutil/trace.go:171","msg":"trace[855310514] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"341.454505ms","start":"2026-02-17T16:13:34.399691Z","end":"2026-02-17T16:13:34.741145Z","steps":["trace[855310514] 'read index received' (duration: 341.287242ms)","trace[855310514] 'applied index is now lower than readState.Index' (duration: 164.353µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:34.74129Z","caller":"traceutil/trace.go:171","msg":"trace[95044684] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"460.195918ms","start":"2026-02-17T16:13:34.281081Z","end":"2026-02-17T16:13:34.741277Z","steps":["trace[95044684] 'process raft request' (duration: 459.921282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:34.741455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:34.281055Z","time spent":"460.321321ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:34.741511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.253302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:34.741577Z","caller":"traceutil/trace.go:171","msg":"trace[722212567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"292.372525ms","start":"2026-02-17T16:13:34.449187Z","end":"2026-02-17T16:13:34.741559Z","steps":["trace[722212567] 'agreement among raft nodes before linearized reading' (duration: 292.258613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:34.741843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.146581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:13:34.741872Z","caller":"traceutil/trace.go:171","msg":"trace[1064553520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"342.204702ms","start":"2026-02-17T16:13:34.399659Z","end":"2026-02-17T16:13:34.741863Z","steps":["trace[1064553520] 'agreement among raft nodes before linearized reading' (duration: 342.09163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:34.741899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:34.399643Z","time spent":"342.249633ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T16:13:34.89729Z","caller":"traceutil/trace.go:171","msg":"trace[1875345196] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"150.33048ms","start":"2026-02-17T16:13:34.746936Z","end":"2026-02-17T16:13:34.897267Z","steps":["trace[1875345196] 'process raft request' (duration: 149.500211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:36.918196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.496772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T16:13:36.918255Z","caller":"traceutil/trace.go:171","msg":"trace[613489525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"170.640026ms","start":"2026-02-17T16:13:36.747604Z","end":"2026-02-17T16:13:36.918244Z","steps":["trace[613489525] 'range keys from in-memory index tree' (duration: 170.34587ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:39.67789Z","caller":"traceutil/trace.go:171","msg":"trace[1763506727] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"122.092498ms","start":"2026-02-17T16:13:39.555774Z","end":"2026-02-17T16:13:39.677867Z","steps":["trace[1763506727] 'process raft request' (duration: 121.855163ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:39.732287Z","caller":"traceutil/trace.go:171","msg":"trace[1922831647] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"145.152003ms","start":"2026-02-17T16:13:39.587115Z","end":"2026-02-17T16:13:39.732267Z","steps":["trace[1922831647] 'read index received' (duration: 90.722703ms)","trace[1922831647] 'applied index is now lower than readState.Index' (duration: 54.42859ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:39.732369Z","caller":"traceutil/trace.go:171","msg":"trace[1304303540] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"170.947313ms","start":"2026-02-17T16:13:39.561414Z","end":"2026-02-17T16:13:39.732361Z","steps":["trace[1304303540] 'process raft request' (duration: 170.734189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:39.732589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.248363ms","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-17T16:13:39.732669Z","caller":"traceutil/trace.go:171","msg":"trace[462705491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:568; }","duration":"119.359786ms","start":"2026-02-17T16:13:39.61327Z","end":"2026-02-17T16:13:39.73263Z","steps":["trace[462705491] 'agreement among raft nodes before linearized reading' (duration: 119.237603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:39.732705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.587324ms","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-17T16:13:39.732731Z","caller":"traceutil/trace.go:171","msg":"trace[1797505944] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"145.665116ms","start":"2026-02-17T16:13:39.587056Z","end":"2026-02-17T16:13:39.732721Z","steps":["trace[1797505944] 'agreement among raft nodes before linearized reading' (duration: 145.627025ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:40.134515Z","caller":"traceutil/trace.go:171","msg":"trace[383976146] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"109.046814ms","start":"2026-02-17T16:13:40.025446Z","end":"2026-02-17T16:13:40.134493Z","steps":["trace[383976146] 'process raft request' (duration: 108.775668ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:40.135144Z","caller":"traceutil/trace.go:171","msg":"trace[865886363] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:589; }","duration":"101.524446ms","start":"2026-02-17T16:13:40.033608Z","end":"2026-02-17T16:13:40.135133Z","steps":["trace[865886363] 'read index received' (duration: 101.497565ms)","trace[865886363] 'applied index is now lower than readState.Index' (duration: 26.201µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:40.135362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.73294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-17T16:13:40.135485Z","caller":"traceutil/trace.go:171","msg":"trace[1669962274] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"101.862373ms","start":"2026-02-17T16:13:40.033581Z","end":"2026-02-17T16:13:40.135443Z","steps":["trace[1669962274] 'agreement among raft nodes before linearized reading' (duration: 101.645279ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:43.243407Z","caller":"traceutil/trace.go:171","msg":"trace[478909117] transaction","detail":"{read_only:false; number_of_response:0; response_revision:606; }","duration":"134.147368ms","start":"2026-02-17T16:13:43.109242Z","end":"2026-02-17T16:13:43.24339Z","steps":["trace[478909117] 'process raft request' (duration: 96.243957ms)","trace[478909117] 'compare' (duration: 37.85733ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:45.374298Z","caller":"traceutil/trace.go:171","msg":"trace[915098105] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"374.69371ms","start":"2026-02-17T16:13:44.999589Z","end":"2026-02-17T16:13:45.374282Z","steps":["trace[915098105] 'process raft request' (duration: 374.555007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:45.377276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:44.999579Z","time spent":"377.35455ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:45.588027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.123341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:13:45.588134Z","caller":"traceutil/trace.go:171","msg":"trace[224217203] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"578.180004ms","start":"2026-02-17T16:13:45.009944Z","end":"2026-02-17T16:13:45.588124Z","steps":["trace[224217203] 'read index received' (duration: 364.157565ms)","trace[224217203] 'applied index is now lower than readState.Index' (duration: 214.021949ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:45.588262Z","caller":"traceutil/trace.go:171","msg":"trace[184259724] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"588.538075ms","start":"2026-02-17T16:13:44.999717Z","end":"2026-02-17T16:13:45.588255Z","steps":["trace[184259724] 'process raft request' (duration: 453.147849ms)","trace[184259724] 'compare' (duration: 135.048478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:45.588293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:44.999712Z","time spent":"588.564355ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:45.58839Z","caller":"traceutil/trace.go:171","msg":"trace[1231250318] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"372.43043ms","start":"2026-02-17T16:13:45.215955Z","end":"2026-02-17T16:13:45.588385Z","steps":["trace[1231250318] 'process raft request' (duration: 372.132143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:45.588414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:45.215937Z","time spent":"372.463271ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:45.588516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.572162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:13:45.588529Z","caller":"traceutil/trace.go:171","msg":"trace[1892607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"578.640454ms","start":"2026-02-17T16:13:45.009884Z","end":"2026-02-17T16:13:45.588525Z","steps":["trace[1892607] 'agreement among raft nodes before linearized reading' (duration: 578.580252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:45.588542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:45.009871Z","time spent":"578.668924ms","remote":"127.0.0.1:57596","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-17T16:13:45.588637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.177803ms","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-17T16:13:45.588652Z","caller":"traceutil/trace.go:171","msg":"trace[606098885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"479.217684ms","start":"2026-02-17T16:13:45.109428Z","end":"2026-02-17T16:13:45.588646Z","steps":["trace[606098885] 'agreement among raft nodes before linearized reading' (duration: 479.178704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:45.588663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:45.109404Z","time spent":"479.256905ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T16:13:57.578827Z","caller":"traceutil/trace.go:171","msg":"trace[654251726] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"130.570066ms","start":"2026-02-17T16:13:57.44824Z","end":"2026-02-17T16:13:57.57881Z","steps":["trace[654251726] 'process raft request' (duration: 130.476164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:58.958869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281383076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T16:13:58.958923Z","caller":"traceutil/trace.go:171","msg":"trace[879566654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:699; }","duration":"1.281472838s","start":"2026-02-17T16:13:57.677436Z","end":"2026-02-17T16:13:58.958909Z","steps":["trace[879566654] 'range keys from in-memory index tree' (duration: 1.281270623s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:58.958903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.951968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-17T16:13:58.958952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:57.677423Z","time spent":"1.281518729s","remote":"127.0.0.1:57596","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":"info","ts":"2026-02-17T16:13:58.958981Z","caller":"traceutil/trace.go:171","msg":"trace[222548949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"828.06165ms","start":"2026-02-17T16:13:58.130901Z","end":"2026-02-17T16:13:58.958963Z","steps":["trace[222548949] 'range keys from in-memory index tree' (duration: 827.828145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:58.959024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:58.130888Z","time spent":"828.122851ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T16:13:58.959158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217153597s","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-17T16:13:58.959183Z","caller":"traceutil/trace.go:171","msg":"trace[578961830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:699; }","duration":"1.217236449s","start":"2026-02-17T16:13:57.741934Z","end":"2026-02-17T16:13:58.95917Z","steps":["trace[578961830] 'range keys from in-memory index tree' (duration: 1.217066255s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:58.959197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:57.741918Z","time spent":"1.21727536s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:13:58.959283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177338143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:13:58.959296Z","caller":"traceutil/trace.go:171","msg":"trace[332630816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:699; }","duration":"1.177390475s","start":"2026-02-17T16:13:57.781901Z","end":"2026-02-17T16:13:58.959291Z","steps":["trace[332630816] 'range keys from in-memory index tree' (duration: 1.177268622s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:58.959309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:57.781884Z","time spent":"1.177422236s","remote":"127.0.0.1:57596","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-17T16:13:58.959717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.08291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:58.959753Z","caller":"traceutil/trace.go:171","msg":"trace[876764099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"513.319366ms","start":"2026-02-17T16:13:58.446422Z","end":"2026-02-17T16:13:58.959742Z","steps":["trace[876764099] 'range keys from in-memory index tree' (duration: 513.034429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:58.959779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:58.446398Z","time spent":"513.374407ms","remote":"127.0.0.1:57384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:13:59.799988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544063432360043760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:14:00.064327Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.095205231s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:14:00.064687Z","caller":"traceutil/trace.go:171","msg":"trace[600263871] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"1.095623861s","start":"2026-02-17T16:13:58.969044Z","end":"2026-02-17T16:14:00.064668Z","steps":["trace[600263871] 'process raft request' (duration: 1.095499898s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.064806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:58.969015Z","time spent":"1.095713232s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:00.26961Z","caller":"traceutil/trace.go:171","msg":"trace[1546479695] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:721; }","duration":"969.664162ms","start":"2026-02-17T16:13:59.299916Z","end":"2026-02-17T16:14:00.26958Z","steps":["trace[1546479695] 'read index received' (duration: 764.701551ms)","trace[1546479695] 'applied index is now lower than readState.Index' (duration: 204.961921ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:00.26983Z","caller":"traceutil/trace.go:171","msg":"trace[1474669004] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"1.300648333s","start":"2026-02-17T16:13:58.969173Z","end":"2026-02-17T16:14:00.269822Z","steps":["trace[1474669004] 'process raft request' (duration: 1.300211462s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.269909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:58.969162Z","time spent":"1.300689423s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:00.270038Z","caller":"traceutil/trace.go:171","msg":"trace[910161936] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"1.300816016s","start":"2026-02-17T16:13:58.969215Z","end":"2026-02-17T16:14:00.270031Z","steps":["trace[910161936] 'process raft request' (duration: 1.300276814s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.270074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:58.969212Z","time spent":"1.300841917s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:00.27017Z","caller":"traceutil/trace.go:171","msg":"trace[57921275] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"1.30018497s","start":"2026-02-17T16:13:58.969979Z","end":"2026-02-17T16:14:00.270164Z","steps":["trace[57921275] 'process raft request' (duration: 1.299544536s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.270206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:58.969971Z","time spent":"1.300214251s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:14:00.270354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.43355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:14:00.270378Z","caller":"traceutil/trace.go:171","msg":"trace[401906592] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:703; }","duration":"970.47698ms","start":"2026-02-17T16:13:59.299893Z","end":"2026-02-17T16:14:00.27037Z","steps":["trace[401906592] 'agreement among raft nodes before linearized reading' (duration: 970.403868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.270403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:59.29988Z","time spent":"970.514402ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-17T16:14:00.486269Z","caller":"traceutil/trace.go:171","msg":"trace[997684275] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:725; }","duration":"216.598269ms","start":"2026-02-17T16:14:00.269653Z","end":"2026-02-17T16:14:00.486251Z","steps":["trace[997684275] 'read index received' (duration: 216.436775ms)","trace[997684275] 'applied index is now lower than readState.Index' (duration: 160.824µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:00.486352Z","caller":"traceutil/trace.go:171","msg":"trace[842873845] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"263.966527ms","start":"2026-02-17T16:14:00.222378Z","end":"2026-02-17T16:14:00.486345Z","steps":["trace[842873845] 'process raft request' (duration: 263.841004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.486495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.218625ms","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-17T16:14:00.486517Z","caller":"traceutil/trace.go:171","msg":"trace[1782737764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"904.278317ms","start":"2026-02-17T16:13:59.582229Z","end":"2026-02-17T16:14:00.486508Z","steps":["trace[1782737764] 'agreement among raft nodes before linearized reading' (duration: 904.192755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.486534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:59.582205Z","time spent":"904.324727ms","remote":"127.0.0.1:57596","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-17T16:14:00.486558Z","caller":"traceutil/trace.go:171","msg":"trace[1464464122] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"303.663377ms","start":"2026-02-17T16:14:00.182887Z","end":"2026-02-17T16:14:00.48655Z","steps":["trace[1464464122] 'process raft request' (duration: 303.218867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.486609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:00.182868Z","time spent":"303.706337ms","remote":"127.0.0.1:57522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4768,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:14:00.486643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.81976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:14:00.486662Z","caller":"traceutil/trace.go:171","msg":"trace[1190445867] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:705; }","duration":"999.860681ms","start":"2026-02-17T16:13:59.486795Z","end":"2026-02-17T16:14:00.486655Z","steps":["trace[1190445867] 'agreement among raft nodes before linearized reading' (duration: 999.829791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:00.486678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:59.486779Z","time spent":"999.894572ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:14:02.009307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.921595ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T16:14:08.487772Z","caller":"traceutil/trace.go:171","msg":"trace[920297162] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"290.171704ms","start":"2026-02-17T16:14:08.197583Z","end":"2026-02-17T16:14:08.487754Z","steps":["trace[920297162] 'process raft request' (duration: 289.962869ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:08.621114Z","caller":"traceutil/trace.go:171","msg":"trace[841927764] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"128.251843ms","start":"2026-02-17T16:14:08.492843Z","end":"2026-02-17T16:14:08.621095Z","steps":["trace[841927764] 'process raft request' (duration: 126.643175ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:08.621196Z","caller":"traceutil/trace.go:171","msg":"trace[573004198] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"128.269014ms","start":"2026-02-17T16:14:08.492915Z","end":"2026-02-17T16:14:08.621184Z","steps":["trace[573004198] 'process raft request' (duration: 128.071669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:15.138423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.627269ms","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-17T16:14:15.138515Z","caller":"traceutil/trace.go:171","msg":"trace[1156474646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:749; }","duration":"436.750061ms","start":"2026-02-17T16:14:14.701745Z","end":"2026-02-17T16:14:15.138495Z","steps":["trace[1156474646] 'range keys from in-memory index tree' (duration: 436.486726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:15.138554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:14.701733Z","time spent":"436.812453ms","remote":"127.0.0.1:57596","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-17T16:14:16.509279Z","caller":"traceutil/trace.go:171","msg":"trace[1554354110] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"208.950742ms","start":"2026-02-17T16:14:16.300308Z","end":"2026-02-17T16:14:16.509259Z","steps":["trace[1554354110] 'read index received' (duration: 208.746177ms)","trace[1554354110] 'applied index is now lower than readState.Index' (duration: 203.745µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:16.509408Z","caller":"traceutil/trace.go:171","msg":"trace[1242029570] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"336.119189ms","start":"2026-02-17T16:14:16.173279Z","end":"2026-02-17T16:14:16.509398Z","steps":["trace[1242029570] 'process raft request' (duration: 335.838233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:16.509509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:16.173236Z","time spent":"336.198761ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:14:16.509767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.454354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.27\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T16:14:16.509799Z","caller":"traceutil/trace.go:171","msg":"trace[1205289855] range","detail":"{range_begin:/registry/masterleases/199.19.213.27; range_end:; response_count:1; response_revision:751; }","duration":"209.486995ms","start":"2026-02-17T16:14:16.300302Z","end":"2026-02-17T16:14:16.509789Z","steps":["trace[1205289855] 'agreement among raft nodes before linearized reading' (duration: 209.313221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:16.509895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.948818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:14:16.509965Z","caller":"traceutil/trace.go:171","msg":"trace[1066650776] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:751; }","duration":"107.065881ms","start":"2026-02-17T16:14:16.402883Z","end":"2026-02-17T16:14:16.509948Z","steps":["trace[1066650776] 'agreement among raft nodes before linearized reading' (duration: 106.952558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:17.656022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.734469ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-17T16:14:17.65622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:16.51263Z","time spent":"1.143587712s","remote":"127.0.0.1:57396","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-17T16:14:17.65608Z","caller":"traceutil/trace.go:171","msg":"trace[920405123] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"1.110450543s","start":"2026-02-17T16:14:16.54562Z","end":"2026-02-17T16:14:17.656071Z","steps":["trace[920405123] 'read index received' (duration: 286.606342ms)","trace[920405123] 'applied index is now lower than readState.Index' (duration: 823.841551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:17.656565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110965615s","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-17T16:14:17.656583Z","caller":"traceutil/trace.go:171","msg":"trace[417619414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.111026657s","start":"2026-02-17T16:14:16.545551Z","end":"2026-02-17T16:14:17.656577Z","steps":["trace[417619414] 'agreement among raft nodes before linearized reading' (duration: 1.110879714s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:17.656598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:16.545527Z","time spent":"1.111066937s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T16:14:17.6567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.115668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T16:14:17.656712Z","caller":"traceutil/trace.go:171","msg":"trace[721503966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"994.157189ms","start":"2026-02-17T16:14:16.662551Z","end":"2026-02-17T16:14:17.656708Z","steps":["trace[721503966] 'agreement among raft nodes before linearized reading' (duration: 994.117338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:17.656725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:16.662533Z","time spent":"994.18835ms","remote":"127.0.0.1:57596","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-17T16:14:17.656801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.085585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:14:17.656814Z","caller":"traceutil/trace.go:171","msg":"trace[1712920957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"209.125515ms","start":"2026-02-17T16:14:17.447683Z","end":"2026-02-17T16:14:17.656809Z","steps":["trace[1712920957] 'agreement among raft nodes before linearized reading' (duration: 209.104845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:17.656901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.20932ms","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-17T16:14:17.656912Z","caller":"traceutil/trace.go:171","msg":"trace[977583155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"509.248882ms","start":"2026-02-17T16:14:17.14766Z","end":"2026-02-17T16:14:17.656909Z","steps":["trace[977583155] 'agreement among raft nodes before linearized reading' (duration: 509.213751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:17.656923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:17.147645Z","time spent":"509.275832ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:14:17.656985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.141227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:14:17.656997Z","caller":"traceutil/trace.go:171","msg":"trace[2121859607] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:751; }","duration":"978.154057ms","start":"2026-02-17T16:14:16.678839Z","end":"2026-02-17T16:14:17.656993Z","steps":["trace[2121859607] 'agreement among raft nodes before linearized reading' (duration: 978.129507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:17.657009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:16.67881Z","time spent":"978.196219ms","remote":"127.0.0.1:57662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:14:17.657118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.748126ms","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-17T16:14:17.65713Z","caller":"traceutil/trace.go:171","msg":"trace[533016161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"989.773547ms","start":"2026-02-17T16:14:16.667353Z","end":"2026-02-17T16:14:17.657127Z","steps":["trace[533016161] 'agreement among raft nodes before linearized reading' (duration: 989.732657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:17.657142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:16.667347Z","time spent":"989.792508ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:14:17.65721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.588559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:14:17.657222Z","caller":"traceutil/trace.go:171","msg":"trace[1646665826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"991.626811ms","start":"2026-02-17T16:14:16.665592Z","end":"2026-02-17T16:14:17.657219Z","steps":["trace[1646665826] 'agreement among raft nodes before linearized reading' (duration: 991.59357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:17.657236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:16.66558Z","time spent":"991.652141ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T16:14:18.398026Z","caller":"traceutil/trace.go:171","msg":"trace[839511808] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"740.122661ms","start":"2026-02-17T16:14:17.657876Z","end":"2026-02-17T16:14:18.397999Z","steps":["trace[839511808] 'process raft request' (duration: 669.558926ms)","trace[839511808] 'compare' (duration: 70.444442ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:18.398145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:17.657864Z","time spent":"740.223404ms","remote":"127.0.0.1:57396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:18.772449Z","caller":"traceutil/trace.go:171","msg":"trace[909477241] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.109485381s","start":"2026-02-17T16:14:17.662949Z","end":"2026-02-17T16:14:18.772435Z","steps":["trace[909477241] 'process raft request' (duration: 1.109147583s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:18.772532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:17.662935Z","time spent":"1.109553323s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:18.772699Z","caller":"traceutil/trace.go:171","msg":"trace[1767894717] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.109696097s","start":"2026-02-17T16:14:17.662999Z","end":"2026-02-17T16:14:18.772695Z","steps":["trace[1767894717] 'process raft request' (duration: 1.109182294s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:18.77273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:17.662996Z","time spent":"1.109720657s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:18.773871Z","caller":"traceutil/trace.go:171","msg":"trace[757808210] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"1.108715194s","start":"2026-02-17T16:14:17.664213Z","end":"2026-02-17T16:14:18.772928Z","steps":["trace[757808210] 'process raft request' (duration: 1.10813474s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:18.773919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:17.664206Z","time spent":"1.109694176s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:18.774112Z","caller":"traceutil/trace.go:171","msg":"trace[2043012141] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.10985881s","start":"2026-02-17T16:14:17.664246Z","end":"2026-02-17T16:14:18.774105Z","steps":["trace[2043012141] 'process raft request' (duration: 1.108122329s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:18.774141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:17.664243Z","time spent":"1.10988209s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:18.774239Z","caller":"traceutil/trace.go:171","msg":"trace[168192625] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:780; }","duration":"371.916388ms","start":"2026-02-17T16:14:18.402304Z","end":"2026-02-17T16:14:18.77422Z","steps":["trace[168192625] 'read index received' (duration: 369.744468ms)","trace[168192625] 'applied index is now lower than readState.Index' (duration: 2.17094ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:18.774299Z","caller":"traceutil/trace.go:171","msg":"trace[2107175831] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.108912378s","start":"2026-02-17T16:14:17.665381Z","end":"2026-02-17T16:14:18.774294Z","steps":["trace[2107175831] 'process raft request' (duration: 1.108706233s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:18.774325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:17.665372Z","time spent":"1.108940499s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:14:18.774361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.042102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-17T16:14:18.774384Z","caller":"traceutil/trace.go:171","msg":"trace[1962884207] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:757; }","duration":"372.107213ms","start":"2026-02-17T16:14:18.40227Z","end":"2026-02-17T16:14:18.774377Z","steps":["trace[1962884207] 'agreement among raft nodes before linearized reading' (duration: 372.047372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:18.774411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:18.402255Z","time spent":"372.148135ms","remote":"127.0.0.1:57512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-17T16:14:18.774415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.550491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:14:18.774433Z","caller":"traceutil/trace.go:171","msg":"trace[1718162730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"324.568421ms","start":"2026-02-17T16:14:18.449859Z","end":"2026-02-17T16:14:18.774428Z","steps":["trace[1718162730] 'agreement among raft nodes before linearized reading' (duration: 324.541311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:18.774447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:18.449811Z","time spent":"324.633023ms","remote":"127.0.0.1:57384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:14:23.217887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.769495ms","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-17T16:14:23.218035Z","caller":"traceutil/trace.go:171","msg":"trace[1026224031] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:764; }","duration":"286.942179ms","start":"2026-02-17T16:14:22.931078Z","end":"2026-02-17T16:14:23.21802Z","steps":["trace[1026224031] 'range keys from in-memory index tree' (duration: 286.754965ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:23.218224Z","caller":"traceutil/trace.go:171","msg":"trace[1517909312] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"394.109772ms","start":"2026-02-17T16:14:22.824107Z","end":"2026-02-17T16:14:23.218217Z","steps":["trace[1517909312] 'process raft request' (duration: 393.089669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:23.218299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:22.8241Z","time spent":"394.153223ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:23.223129Z","caller":"traceutil/trace.go:171","msg":"trace[518343914] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"397.917612ms","start":"2026-02-17T16:14:22.825182Z","end":"2026-02-17T16:14:23.2231Z","steps":["trace[518343914] 'process raft request' (duration: 397.473732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:23.224213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:22.825176Z","time spent":"398.741481ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:23.225396Z","caller":"traceutil/trace.go:171","msg":"trace[1020761574] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"398.2753ms","start":"2026-02-17T16:14:22.827109Z","end":"2026-02-17T16:14:23.225385Z","steps":["trace[1020761574] 'process raft request' (duration: 398.205358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:23.225569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:22.827103Z","time spent":"398.434003ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:23.225812Z","caller":"traceutil/trace.go:171","msg":"trace[218580241] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"399.777514ms","start":"2026-02-17T16:14:22.826022Z","end":"2026-02-17T16:14:23.225799Z","steps":["trace[218580241] 'process raft request' (duration: 396.770085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:23.225907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:22.826016Z","time spent":"399.861197ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:25.371518Z","caller":"traceutil/trace.go:171","msg":"trace[738452852] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"119.95253ms","start":"2026-02-17T16:14:25.251533Z","end":"2026-02-17T16:14:25.371486Z","steps":["trace[738452852] 'process raft request' (duration: 119.907219ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:25.372133Z","caller":"traceutil/trace.go:171","msg":"trace[2053219633] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"122.948859ms","start":"2026-02-17T16:14:25.24917Z","end":"2026-02-17T16:14:25.372118Z","steps":["trace[2053219633] 'process raft request' (duration: 41.00167ms)","trace[2053219633] 'compare' (duration: 81.032998ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:25.372276Z","caller":"traceutil/trace.go:171","msg":"trace[808407954] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"122.409086ms","start":"2026-02-17T16:14:25.249858Z","end":"2026-02-17T16:14:25.372267Z","steps":["trace[808407954] 'process raft request' (duration: 121.534346ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:27.499935Z","caller":"traceutil/trace.go:171","msg":"trace[523191554] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"117.176666ms","start":"2026-02-17T16:14:27.382717Z","end":"2026-02-17T16:14:27.499893Z","steps":["trace[523191554] 'process raft request' (duration: 84.677513ms)","trace[523191554] 'compare' (duration: 32.020302ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:27.502737Z","caller":"traceutil/trace.go:171","msg":"trace[183995012] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"116.945901ms","start":"2026-02-17T16:14:27.385779Z","end":"2026-02-17T16:14:27.502725Z","steps":["trace[183995012] 'process raft request' (duration: 113.852239ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:27.506115Z","caller":"traceutil/trace.go:171","msg":"trace[1520367737] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"120.234877ms","start":"2026-02-17T16:14:27.385864Z","end":"2026-02-17T16:14:27.506099Z","steps":["trace[1520367737] 'process raft request' (duration: 113.825799ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:33.033873Z","caller":"traceutil/trace.go:171","msg":"trace[95513387] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"140.501356ms","start":"2026-02-17T16:14:32.893356Z","end":"2026-02-17T16:14:33.033858Z","steps":["trace[95513387] 'process raft request' (duration: 140.405123ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:37.188126Z","caller":"traceutil/trace.go:171","msg":"trace[1717430290] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"118.589108ms","start":"2026-02-17T16:14:37.06952Z","end":"2026-02-17T16:14:37.18811Z","steps":["trace[1717430290] 'process raft request' (duration: 118.494816ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:43.77472Z","caller":"traceutil/trace.go:171","msg":"trace[221877613] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"225.471954ms","start":"2026-02-17T16:14:43.549237Z","end":"2026-02-17T16:14:43.774709Z","steps":["trace[221877613] 'process raft request' (duration: 225.30692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:44.190444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:14:44.190514Z","caller":"traceutil/trace.go:171","msg":"trace[526848508] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"339.047757ms","start":"2026-02-17T16:14:43.851456Z","end":"2026-02-17T16:14:44.190504Z","steps":["trace[526848508] 'read index received' (duration: 13.076824ms)","trace[526848508] 'applied index is now lower than readState.Index' (duration: 325.970333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:44.190601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.14399ms","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-17T16:14:44.190613Z","caller":"traceutil/trace.go:171","msg":"trace[1640731365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"339.1803ms","start":"2026-02-17T16:14:43.851429Z","end":"2026-02-17T16:14:44.190609Z","steps":["trace[1640731365] 'agreement among raft nodes before linearized reading' (duration: 339.099588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:44.190629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:43.851416Z","time spent":"339.20916ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T16:14:44.190785Z","caller":"traceutil/trace.go:171","msg":"trace[972791072] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"403.943771ms","start":"2026-02-17T16:14:43.786835Z","end":"2026-02-17T16:14:44.190779Z","steps":["trace[972791072] 'process raft request' (duration: 77.745822ms)","trace[972791072] 'compare' (duration: 325.664316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:44.190813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:43.78682Z","time spent":"403.978662ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:14:45.34995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.836006ms","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-17T16:14:45.349997Z","caller":"traceutil/trace.go:171","msg":"trace[1274404378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"125.905578ms","start":"2026-02-17T16:14:45.224078Z","end":"2026-02-17T16:14:45.349984Z","steps":["trace[1274404378] 'range keys from in-memory index tree' (duration: 125.728234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:45.349539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.697262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:14:45.350406Z","caller":"traceutil/trace.go:171","msg":"trace[1216983872] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:885; }","duration":"784.614893ms","start":"2026-02-17T16:14:44.565782Z","end":"2026-02-17T16:14:45.350397Z","steps":["trace[1216983872] 'count revisions from in-memory index tree' (duration: 783.583799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:45.350457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:44.565741Z","time spent":"784.705755ms","remote":"127.0.0.1:57522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-02-17T16:14:46.179847Z","caller":"traceutil/trace.go:171","msg":"trace[1929812130] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"383.020137ms","start":"2026-02-17T16:14:45.79681Z","end":"2026-02-17T16:14:46.17983Z","steps":["trace[1929812130] 'read index received' (duration: 382.883264ms)","trace[1929812130] 'applied index is now lower than readState.Index' (duration: 136.353µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:46.179997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.16863ms","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-17T16:14:46.180014Z","caller":"traceutil/trace.go:171","msg":"trace[1721200954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"383.217171ms","start":"2026-02-17T16:14:45.796791Z","end":"2026-02-17T16:14:46.180008Z","steps":["trace[1721200954] 'agreement among raft nodes before linearized reading' (duration: 383.098008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:46.180032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:45.796781Z","time spent":"383.246401ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T16:14:46.180218Z","caller":"traceutil/trace.go:171","msg":"trace[1147182834] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"400.746747ms","start":"2026-02-17T16:14:45.779467Z","end":"2026-02-17T16:14:46.180213Z","steps":["trace[1147182834] 'process raft request' (duration: 400.265787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:46.180274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:45.779458Z","time spent":"400.784418ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:14:53.677232Z","caller":"traceutil/trace.go:171","msg":"trace[2071514298] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"182.593001ms","start":"2026-02-17T16:14:53.494621Z","end":"2026-02-17T16:14:53.677214Z","steps":["trace[2071514298] 'process raft request' (duration: 182.486389ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:54.50504Z","caller":"traceutil/trace.go:171","msg":"trace[185425165] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"134.248686ms","start":"2026-02-17T16:14:54.370778Z","end":"2026-02-17T16:14:54.505027Z","steps":["trace[185425165] 'process raft request' (duration: 124.529345ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:54.505125Z","caller":"traceutil/trace.go:171","msg":"trace[1203818973] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"131.476683ms","start":"2026-02-17T16:14:54.373644Z","end":"2026-02-17T16:14:54.505121Z","steps":["trace[1203818973] 'process raft request' (duration: 131.173356ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:54.505179Z","caller":"traceutil/trace.go:171","msg":"trace[1881781505] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"127.732198ms","start":"2026-02-17T16:14:54.377441Z","end":"2026-02-17T16:14:54.505174Z","steps":["trace[1881781505] 'process raft request' (duration: 127.398721ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:56.784577Z","caller":"traceutil/trace.go:171","msg":"trace[1508918891] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"395.940803ms","start":"2026-02-17T16:14:56.388621Z","end":"2026-02-17T16:14:56.784562Z","steps":["trace[1508918891] 'read index received' (duration: 395.83755ms)","trace[1508918891] 'applied index is now lower than readState.Index' (duration: 102.563µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:56.784643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:56.303192Z","time spent":"481.448442ms","remote":"127.0.0.1:57396","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T16:14:56.784892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.26582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T16:14:56.78491Z","caller":"traceutil/trace.go:171","msg":"trace[1525006804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:914; }","duration":"396.326241ms","start":"2026-02-17T16:14:56.388578Z","end":"2026-02-17T16:14:56.784905Z","steps":["trace[1525006804] 'agreement among raft nodes before linearized reading' (duration: 396.236049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:56.784938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:56.388566Z","time spent":"396.367862ms","remote":"127.0.0.1:57596","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-17T16:14:56.785027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.191764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:14:56.78504Z","caller":"traceutil/trace.go:171","msg":"trace[1304443428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"339.225475ms","start":"2026-02-17T16:14:56.44581Z","end":"2026-02-17T16:14:56.785036Z","steps":["trace[1304443428] 'agreement among raft nodes before linearized reading' (duration: 339.205635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:56.785051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:56.445801Z","time spent":"339.247896ms","remote":"127.0.0.1:57384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:14:56.78517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.301239ms","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-17T16:14:56.785183Z","caller":"traceutil/trace.go:171","msg":"trace[1263328923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"269.34239ms","start":"2026-02-17T16:14:56.515836Z","end":"2026-02-17T16:14:56.785179Z","steps":["trace[1263328923] 'agreement among raft nodes before linearized reading' (duration: 269.303949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:56.785263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.848478ms","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-17T16:14:56.785289Z","caller":"traceutil/trace.go:171","msg":"trace[1079105793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"275.871409ms","start":"2026-02-17T16:14:56.509401Z","end":"2026-02-17T16:14:56.785272Z","steps":["trace[1079105793] 'agreement among raft nodes before linearized reading' (duration: 275.832198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:56.785381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.544704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:14:56.785394Z","caller":"traceutil/trace.go:171","msg":"trace[2415314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"276.580614ms","start":"2026-02-17T16:14:56.508809Z","end":"2026-02-17T16:14:56.78539Z","steps":["trace[2415314] 'agreement among raft nodes before linearized reading' (duration: 276.545843ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:15:00.157072Z","caller":"traceutil/trace.go:171","msg":"trace[551108745] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"393.721751ms","start":"2026-02-17T16:14:59.763334Z","end":"2026-02-17T16:15:00.157056Z","steps":["trace[551108745] 'process raft request' (duration: 393.634509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:00.157164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:14:59.763322Z","time spent":"393.795003ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:15:01.691464Z","caller":"traceutil/trace.go:171","msg":"trace[642977867] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"769.741022ms","start":"2026-02-17T16:15:00.921708Z","end":"2026-02-17T16:15:01.691449Z","steps":["trace[642977867] 'read index received' (duration: 73.358155ms)","trace[642977867] 'applied index is now lower than readState.Index' (duration: 696.382297ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:15:01.691616Z","caller":"traceutil/trace.go:171","msg":"trace[2972677] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"773.18405ms","start":"2026-02-17T16:15:00.918426Z","end":"2026-02-17T16:15:01.69161Z","steps":["trace[2972677] 'process raft request' (duration: 768.110415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:01.691681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:00.918417Z","time spent":"773.213241ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:15:01.691821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.11625ms","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-17T16:15:01.691838Z","caller":"traceutil/trace.go:171","msg":"trace[1224421631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"770.150081ms","start":"2026-02-17T16:15:00.921683Z","end":"2026-02-17T16:15:01.691833Z","steps":["trace[1224421631] 'agreement among raft nodes before linearized reading' (duration: 770.08503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:01.691853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:00.921675Z","time spent":"770.172882ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T16:15:02.25841Z","caller":"traceutil/trace.go:171","msg":"trace[457234720] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"720.71495ms","start":"2026-02-17T16:15:01.537653Z","end":"2026-02-17T16:15:02.258368Z","steps":["trace[457234720] 'process raft request' (duration: 630.056393ms)","trace[457234720] 'compare' (duration: 90.023702ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:15:02.258588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:01.537635Z","time spent":"720.856243ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:15:02.258614Z","caller":"traceutil/trace.go:171","msg":"trace[1159011562] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"567.076815ms","start":"2026-02-17T16:15:01.691513Z","end":"2026-02-17T16:15:02.258589Z","steps":["trace[1159011562] 'read index received' (duration: 477.83567ms)","trace[1159011562] 'applied index is now lower than readState.Index' (duration: 89.239405ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:15:02.258759Z","caller":"traceutil/trace.go:171","msg":"trace[1441641472] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"562.355178ms","start":"2026-02-17T16:15:01.696394Z","end":"2026-02-17T16:15:02.258749Z","steps":["trace[1441641472] 'process raft request' (duration: 561.548989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:02.258874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:01.696372Z","time spent":"562.415429ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:15:02.304518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.087166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:15:02.304601Z","caller":"traceutil/trace.go:171","msg":"trace[1628151338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"858.212428ms","start":"2026-02-17T16:15:01.446366Z","end":"2026-02-17T16:15:02.304579Z","steps":["trace[1628151338] 'agreement among raft nodes before linearized reading' (duration: 812.336378ms)","trace[1628151338] 'range keys from in-memory index tree' (duration: 45.763628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:15:02.304639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:01.446348Z","time spent":"858.28059ms","remote":"127.0.0.1:57384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:15:02.304851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036938423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:15:02.304899Z","caller":"traceutil/trace.go:171","msg":"trace[969045422] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:930; }","duration":"1.037020835s","start":"2026-02-17T16:15:01.267869Z","end":"2026-02-17T16:15:02.30489Z","steps":["trace[969045422] 'agreement among raft nodes before linearized reading' (duration: 990.850078ms)","trace[969045422] 'count revisions from in-memory index tree' (duration: 46.109166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:15:02.304922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:01.26785Z","time spent":"1.037064456s","remote":"127.0.0.1:48290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:15:02.305128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368601997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:15:02.305154Z","caller":"traceutil/trace.go:171","msg":"trace[1897326714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"1.368626058s","start":"2026-02-17T16:15:00.936521Z","end":"2026-02-17T16:15:02.305147Z","steps":["trace[1897326714] 'agreement among raft nodes before linearized reading' (duration: 1.322211015s)","trace[1897326714] 'range keys from in-memory index tree' (duration: 46.315831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:15:02.305178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:00.936489Z","time spent":"1.368685338s","remote":"127.0.0.1:57596","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-17T16:15:02.30562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.478565ms","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-17T16:15:02.305648Z","caller":"traceutil/trace.go:171","msg":"trace[1009298709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"143.579147ms","start":"2026-02-17T16:15:02.162062Z","end":"2026-02-17T16:15:02.305641Z","steps":["trace[1009298709] 'agreement among raft nodes before linearized reading' (duration: 143.431123ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:15:02.305886Z","caller":"traceutil/trace.go:171","msg":"trace[2098582837] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"130.856949ms","start":"2026-02-17T16:15:02.175022Z","end":"2026-02-17T16:15:02.305879Z","steps":["trace[2098582837] 'process raft request' (duration: 129.109549ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:15:06.480891Z","caller":"traceutil/trace.go:171","msg":"trace[644932868] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"136.438296ms","start":"2026-02-17T16:15:06.344422Z","end":"2026-02-17T16:15:06.480861Z","steps":["trace[644932868] 'process raft request' (duration: 136.306693ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:15:08.447352Z","caller":"traceutil/trace.go:171","msg":"trace[1465131379] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"100.865918ms","start":"2026-02-17T16:15:08.346469Z","end":"2026-02-17T16:15:08.447335Z","steps":["trace[1465131379] 'process raft request' (duration: 100.50976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:08.676062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.661756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:15:08.676156Z","caller":"traceutil/trace.go:171","msg":"trace[204376725] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"155.146989ms","start":"2026-02-17T16:15:08.520993Z","end":"2026-02-17T16:15:08.67614Z","steps":["trace[204376725] 'read index received' (duration: 4.262358ms)","trace[204376725] 'applied index is now lower than readState.Index' (duration: 150.883211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:15:08.676297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.310254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:15:08.676325Z","caller":"traceutil/trace.go:171","msg":"trace[1934127397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"155.366835ms","start":"2026-02-17T16:15:08.520947Z","end":"2026-02-17T16:15:08.676314Z","steps":["trace[1934127397] 'agreement among raft nodes before linearized reading' (duration: 155.238121ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:15:08.676329Z","caller":"traceutil/trace.go:171","msg":"trace[1715298553] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"179.240316ms","start":"2026-02-17T16:15:08.497061Z","end":"2026-02-17T16:15:08.676301Z","steps":["trace[1715298553] 'process raft request' (duration: 28.243041ms)","trace[1715298553] 'compare' (duration: 150.410471ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:15:09.97077Z","caller":"traceutil/trace.go:171","msg":"trace[900332667] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"166.429286ms","start":"2026-02-17T16:15:09.80429Z","end":"2026-02-17T16:15:09.97072Z","steps":["trace[900332667] 'process raft request' (duration: 95.536158ms)","trace[900332667] 'compare' (duration: 70.460818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:15:14.346301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.488755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-17T16:15:14.346377Z","caller":"traceutil/trace.go:171","msg":"trace[102978101] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1012; }","duration":"229.609369ms","start":"2026-02-17T16:15:14.116751Z","end":"2026-02-17T16:15:14.34636Z","steps":["trace[102978101] 'range keys from in-memory index tree' (duration: 229.425054ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:15:14.346751Z","caller":"traceutil/trace.go:171","msg":"trace[1604178053] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"229.793513ms","start":"2026-02-17T16:15:14.116924Z","end":"2026-02-17T16:15:14.346717Z","steps":["trace[1604178053] 'process raft request' (duration: 192.751032ms)","trace[1604178053] 'compare' (duration: 36.486697ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:15:14.361606Z","caller":"traceutil/trace.go:171","msg":"trace[1589890348] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"244.144989ms","start":"2026-02-17T16:15:14.117448Z","end":"2026-02-17T16:15:14.361593Z","steps":["trace[1589890348] 'process raft request' (duration: 244.045177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:14.633792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.758543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:15:14.634068Z","caller":"traceutil/trace.go:171","msg":"trace[769772785] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"283.246275ms","start":"2026-02-17T16:15:14.35079Z","end":"2026-02-17T16:15:14.634036Z","steps":["trace[769772785] 'process raft request' (duration: 168.178815ms)","trace[769772785] 'compare' (duration: 114.666521ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:15:14.634164Z","caller":"traceutil/trace.go:171","msg":"trace[590933620] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1055; }","duration":"272.334677ms","start":"2026-02-17T16:15:14.361575Z","end":"2026-02-17T16:15:14.63391Z","steps":["trace[590933620] 'read index received' (duration: 157.337598ms)","trace[590933620] 'applied index is now lower than readState.Index' (duration: 114.996389ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:15:14.634254Z","caller":"traceutil/trace.go:171","msg":"trace[992819149] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"278.886057ms","start":"2026-02-17T16:15:14.355357Z","end":"2026-02-17T16:15:14.634243Z","steps":["trace[992819149] 'process raft request' (duration: 278.510168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:14.634531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.359592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:15:14.634559Z","caller":"traceutil/trace.go:171","msg":"trace[1687368501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"184.434754ms","start":"2026-02-17T16:15:14.450116Z","end":"2026-02-17T16:15:14.634551Z","steps":["trace[1687368501] 'agreement among raft nodes before linearized reading' (duration: 184.380022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:14.634724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.982094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-17T16:15:14.634768Z","caller":"traceutil/trace.go:171","msg":"trace[846960768] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1016; }","duration":"281.019806ms","start":"2026-02-17T16:15:14.353729Z","end":"2026-02-17T16:15:14.634749Z","steps":["trace[846960768] 'agreement among raft nodes before linearized reading' (duration: 280.899022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:15.251207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544063432360044508,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:15:15.752236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544063432360044508,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:15:15.82273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08663412s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:15:15.822814Z","caller":"traceutil/trace.go:171","msg":"trace[1522229751] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.183276214s","start":"2026-02-17T16:15:14.639522Z","end":"2026-02-17T16:15:15.822799Z","steps":["trace[1522229751] 'process raft request' (duration: 96.442479ms)","trace[1522229751] 'compare' (duration: 1.082825554s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:15:15.822975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:14.639513Z","time spent":"1.183431927s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:15:15.830121Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.094130771s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:15:15.830405Z","caller":"traceutil/trace.go:171","msg":"trace[195179703] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.187918019s","start":"2026-02-17T16:15:14.642471Z","end":"2026-02-17T16:15:15.830389Z","steps":["trace[195179703] 'process raft request' (duration: 1.187782876s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:15.830613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:14.642462Z","time spent":"1.188020411s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:15:15.830785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080278976s","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-17T16:15:15.83084Z","caller":"traceutil/trace.go:171","msg":"trace[1615776017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"1.080556812s","start":"2026-02-17T16:15:14.75027Z","end":"2026-02-17T16:15:15.830827Z","steps":["trace[1615776017] 'agreement among raft nodes before linearized reading' (duration: 1.080205815s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:15.83092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:14.750254Z","time spent":"1.080617703s","remote":"127.0.0.1:57596","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-17T16:15:15.830438Z","caller":"traceutil/trace.go:171","msg":"trace[1203238416] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1059; }","duration":"1.079923688s","start":"2026-02-17T16:15:14.750492Z","end":"2026-02-17T16:15:15.830416Z","steps":["trace[1203238416] 'read index received' (duration: 1.079917448s)","trace[1203238416] 'applied index is now lower than readState.Index' (duration: 4.97µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:15:15.831575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014368161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:15:15.83173Z","caller":"traceutil/trace.go:171","msg":"trace[5396474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"1.014545365s","start":"2026-02-17T16:15:14.817178Z","end":"2026-02-17T16:15:15.831723Z","steps":["trace[5396474] 'agreement among raft nodes before linearized reading' (duration: 1.01432365s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:15.831817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:14.817165Z","time spent":"1.014645387s","remote":"127.0.0.1:57596","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-17T16:15:15.832024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.271688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-17T16:15:15.832095Z","caller":"traceutil/trace.go:171","msg":"trace[37948526] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1018; }","duration":"239.37528ms","start":"2026-02-17T16:15:15.592714Z","end":"2026-02-17T16:15:15.832089Z","steps":["trace[37948526] 'agreement among raft nodes before linearized reading' (duration: 239.273578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:15.832245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.143686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:15:15.832323Z","caller":"traceutil/trace.go:171","msg":"trace[871982614] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1018; }","duration":"314.247758ms","start":"2026-02-17T16:15:15.518067Z","end":"2026-02-17T16:15:15.832315Z","steps":["trace[871982614] 'agreement among raft nodes before linearized reading' (duration: 314.161547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:15.83239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:15.518052Z","time spent":"314.33106ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:15:15.832547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.865882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:15:15.832599Z","caller":"traceutil/trace.go:171","msg":"trace[1840951094] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1018; }","duration":"399.955844ms","start":"2026-02-17T16:15:15.432636Z","end":"2026-02-17T16:15:15.832592Z","steps":["trace[1840951094] 'agreement among raft nodes before linearized reading' (duration: 399.872572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:15.832664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:15.432618Z","time spent":"400.037305ms","remote":"127.0.0.1:57820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:15:15.832836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.85551ms","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-17T16:15:15.832897Z","caller":"traceutil/trace.go:171","msg":"trace[831333985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"720.017453ms","start":"2026-02-17T16:15:15.112874Z","end":"2026-02-17T16:15:15.832892Z","steps":["trace[831333985] 'agreement among raft nodes before linearized reading' (duration: 719.920281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:15:15.832953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:15:15.112856Z","time spent":"720.074905ms","remote":"127.0.0.1:57596","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-17T16:15:24.850238Z","caller":"traceutil/trace.go:171","msg":"trace[1416385523] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"194.701936ms","start":"2026-02-17T16:15:24.655511Z","end":"2026-02-17T16:15:24.850213Z","steps":["trace[1416385523] 'process raft request' (duration: 194.589034ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:18.686085Z","caller":"traceutil/trace.go:171","msg":"trace[1761267600] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"111.949202ms","start":"2026-02-17T16:16:18.574107Z","end":"2026-02-17T16:16:18.686056Z","steps":["trace[1761267600] 'process raft request' (duration: 93.217555ms)","trace[1761267600] 'compare' (duration: 18.555963ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:20.351017Z","caller":"traceutil/trace.go:171","msg":"trace[1147977122] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"122.776178ms","start":"2026-02-17T16:16:20.228213Z","end":"2026-02-17T16:16:20.35099Z","steps":["trace[1147977122] 'process raft request' (duration: 30.792292ms)","trace[1147977122] 'compare' (duration: 91.470525ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:20.351475Z","caller":"traceutil/trace.go:171","msg":"trace[1167965456] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"120.766962ms","start":"2026-02-17T16:16:20.230683Z","end":"2026-02-17T16:16:20.351449Z","steps":["trace[1167965456] 'process raft request' (duration: 119.976105ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:22.984064Z","caller":"traceutil/trace.go:171","msg":"trace[1683390931] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"112.661787ms","start":"2026-02-17T16:16:22.871365Z","end":"2026-02-17T16:16:22.984026Z","steps":["trace[1683390931] 'process raft request' (duration: 31.455707ms)","trace[1683390931] 'compare' (duration: 80.526295ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:26.659958Z","caller":"traceutil/trace.go:171","msg":"trace[1653567028] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"118.94034ms","start":"2026-02-17T16:16:26.540993Z","end":"2026-02-17T16:16:26.659933Z","steps":["trace[1653567028] 'process raft request' (duration: 42.781685ms)","trace[1653567028] 'compare' (duration: 75.172143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:33.534912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.704561ms","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-17T16:16:33.538508Z","caller":"traceutil/trace.go:171","msg":"trace[1255881004] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1372; }","duration":"608.308354ms","start":"2026-02-17T16:16:32.930181Z","end":"2026-02-17T16:16:33.538489Z","steps":["trace[1255881004] 'range keys from in-memory index tree' (duration: 604.69019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:33.536011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.417908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-17T16:16:33.539043Z","caller":"traceutil/trace.go:171","msg":"trace[1509249614] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1372; }","duration":"128.314525ms","start":"2026-02-17T16:16:33.410546Z","end":"2026-02-17T16:16:33.53886Z","steps":["trace[1509249614] 'range keys from in-memory index tree' (duration: 125.256125ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:34.007589Z","caller":"traceutil/trace.go:171","msg":"trace[592910060] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"191.273159ms","start":"2026-02-17T16:16:33.816295Z","end":"2026-02-17T16:16:34.007568Z","steps":["trace[592910060] 'process raft request' (duration: 163.800833ms)","trace[592910060] 'compare' (duration: 27.18267ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:35.802052Z","caller":"traceutil/trace.go:171","msg":"trace[1676351469] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"136.940112ms","start":"2026-02-17T16:16:35.646587Z","end":"2026-02-17T16:16:35.783527Z","steps":["trace[1676351469] 'process raft request' (duration: 60.707634ms)","trace[1676351469] 'compare' (duration: 76.049864ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:38.711872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.786999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:16:38.7121Z","caller":"traceutil/trace.go:171","msg":"trace[993075804] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1395; }","duration":"109.082436ms","start":"2026-02-17T16:16:38.602996Z","end":"2026-02-17T16:16:38.712079Z","steps":["trace[993075804] 'count revisions from in-memory index tree' (duration: 108.701127ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:38.894976Z","caller":"traceutil/trace.go:171","msg":"trace[568858974] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"130.656248ms","start":"2026-02-17T16:16:38.764288Z","end":"2026-02-17T16:16:38.894944Z","steps":["trace[568858974] 'process raft request' (duration: 61.621304ms)","trace[568858974] 'compare' (duration: 68.675746ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:40.064004Z","caller":"traceutil/trace.go:171","msg":"trace[517263462] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"172.107682ms","start":"2026-02-17T16:16:39.891869Z","end":"2026-02-17T16:16:40.063977Z","steps":["trace[517263462] 'process raft request' (duration: 171.975789ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:42.806711Z","caller":"traceutil/trace.go:171","msg":"trace[1370830985] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"106.894746ms","start":"2026-02-17T16:16:42.699791Z","end":"2026-02-17T16:16:42.806686Z","steps":["trace[1370830985] 'process raft request' (duration: 106.121449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:43.434415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.690232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:16:43.434854Z","caller":"traceutil/trace.go:171","msg":"trace[300659105] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"322.446688ms","start":"2026-02-17T16:16:43.112386Z","end":"2026-02-17T16:16:43.434833Z","steps":["trace[300659105] 'process raft request' (duration: 129.231895ms)","trace[300659105] 'compare' (duration: 192.493387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:43.434933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:16:43.112366Z","time spent":"322.521ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:16:49.148871Z","caller":"traceutil/trace.go:171","msg":"trace[969267271] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"111.352438ms","start":"2026-02-17T16:16:49.037162Z","end":"2026-02-17T16:16:49.148514Z","steps":["trace[969267271] 'process raft request' (duration: 106.099388ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:49.150149Z","caller":"traceutil/trace.go:171","msg":"trace[1044071671] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1502; }","duration":"105.400761ms","start":"2026-02-17T16:16:49.044732Z","end":"2026-02-17T16:16:49.150133Z","steps":["trace[1044071671] 'read index received' (duration: 105.395331ms)","trace[1044071671] 'applied index is now lower than readState.Index' (duration: 4.42µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:49.150514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.75866ms","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-17T16:16:49.150562Z","caller":"traceutil/trace.go:171","msg":"trace[1037866028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1442; }","duration":"105.835723ms","start":"2026-02-17T16:16:49.044717Z","end":"2026-02-17T16:16:49.150552Z","steps":["trace[1037866028] 'agreement among raft nodes before linearized reading' (duration: 105.643698ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:49.325514Z","caller":"traceutil/trace.go:171","msg":"trace[1035298709] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"167.551478ms","start":"2026-02-17T16:16:49.157934Z","end":"2026-02-17T16:16:49.325485Z","steps":["trace[1035298709] 'process raft request' (duration: 153.563749ms)","trace[1035298709] 'compare' (duration: 13.865516ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:49.349696Z","caller":"traceutil/trace.go:171","msg":"trace[1464223343] linearizableReadLoop","detail":"{readStateIndex:1504; appliedIndex:1502; }","duration":"190.551263ms","start":"2026-02-17T16:16:49.15912Z","end":"2026-02-17T16:16:49.349671Z","steps":["trace[1464223343] 'read index received' (duration: 152.391173ms)","trace[1464223343] 'applied index is now lower than readState.Index' (duration: 38.15893ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:16:49.349932Z","caller":"traceutil/trace.go:171","msg":"trace[113505543] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"191.786562ms","start":"2026-02-17T16:16:49.158006Z","end":"2026-02-17T16:16:49.349793Z","steps":["trace[113505543] 'process raft request' (duration: 191.518726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:49.350397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.28544ms","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-17T16:16:49.350441Z","caller":"traceutil/trace.go:171","msg":"trace[257398753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1444; }","duration":"191.379301ms","start":"2026-02-17T16:16:49.159052Z","end":"2026-02-17T16:16:49.350431Z","steps":["trace[257398753] 'agreement among raft nodes before linearized reading' (duration: 191.077684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:49.588238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.351393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:16:49.588326Z","caller":"traceutil/trace.go:171","msg":"trace[1407713922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1446; }","duration":"138.498277ms","start":"2026-02-17T16:16:49.449806Z","end":"2026-02-17T16:16:49.588305Z","steps":["trace[1407713922] 'range keys from in-memory index tree' (duration: 138.210661ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:50.348891Z","caller":"traceutil/trace.go:171","msg":"trace[1781811845] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"125.765986ms","start":"2026-02-17T16:16:50.223098Z","end":"2026-02-17T16:16:50.348864Z","steps":["trace[1781811845] 'process raft request' (duration: 125.47012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:51.077095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.868682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T16:16:51.077565Z","caller":"traceutil/trace.go:171","msg":"trace[1526453424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1451; }","duration":"199.351132ms","start":"2026-02-17T16:16:50.878187Z","end":"2026-02-17T16:16:51.077538Z","steps":["trace[1526453424] 'range keys from in-memory index tree' (duration: 198.663177ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:16:51.271892Z","caller":"traceutil/trace.go:171","msg":"trace[537513908] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"100.746457ms","start":"2026-02-17T16:16:51.171066Z","end":"2026-02-17T16:16:51.271812Z","steps":["trace[537513908] 'process raft request' (duration: 100.569893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:51.642859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.313233ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T16:16:51.643219Z","caller":"traceutil/trace.go:171","msg":"trace[135043411] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1513; }","duration":"250.930469ms","start":"2026-02-17T16:16:51.392263Z","end":"2026-02-17T16:16:51.643194Z","steps":["trace[135043411] 'read index received' (duration: 7.547593ms)","trace[135043411] 'applied index is now lower than readState.Index' (duration: 243.379406ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:16:51.643654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.384309ms","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-17T16:16:51.643805Z","caller":"traceutil/trace.go:171","msg":"trace[1270857294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1454; }","duration":"251.572984ms","start":"2026-02-17T16:16:51.392195Z","end":"2026-02-17T16:16:51.643768Z","steps":["trace[1270857294] 'agreement among raft nodes before linearized reading' (duration: 251.282787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:51.644083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.94501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:16:51.644159Z","caller":"traceutil/trace.go:171","msg":"trace[515827769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1454; }","duration":"237.077773ms","start":"2026-02-17T16:16:51.407064Z","end":"2026-02-17T16:16:51.644142Z","steps":["trace[515827769] 'agreement among raft nodes before linearized reading' (duration: 236.743255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:16:51.644461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.036792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:16:51.644494Z","caller":"traceutil/trace.go:171","msg":"trace[1940669821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1454; }","duration":"194.117794ms","start":"2026-02-17T16:16:51.450367Z","end":"2026-02-17T16:16:51.644485Z","steps":["trace[1940669821] 'agreement among raft nodes before linearized reading' (duration: 194.061852ms)"],"step_count":1}