{"level":"warn","ts":"2026-02-21T02:31:52.077735Z","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-21T02:31:52.077854Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-21T02:31:52.077928Z","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-21T02:31:52.077936Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-02-21T02:31:52.077954Z","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-21T02:31:52.078604Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-02-21T02:31:52.078683Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-21T02:31:52.082002Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.111628ms"} {"level":"info","ts":"2026-02-21T02:31:52.088868Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-02-21T02:31:52.088952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-02-21T02:31:52.088976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-02-21T02:31:52.088986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-21T02:31:52.088998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-02-21T02:31:52.089037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-02-21T02:31:52.097617Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-21T02:31:52.098886Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-21T02:31:52.099756Z","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-21T02:31:52.104429Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-21T02:31:52.10483Z","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-21T02:31:52.104896Z","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-21T02:31:52.104913Z","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-21T02:31:52.1049Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-21T02:31:52.105482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-02-21T02:31:52.105638Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-02-21T02:31:52.107923Z","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-21T02:31:52.108019Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-02-21T02:31:52.108068Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-02-21T02:31:52.108106Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-21T02:31:52.108149Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-21T02:31:52.891218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-02-21T02:31:52.891276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-02-21T02:31:52.891303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-02-21T02:31:52.891317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-02-21T02:31:52.891324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-02-21T02:31:52.891344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-02-21T02:31:52.891354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-02-21T02:31:52.89737Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-02-21T02:31:52.897432Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T02:31:52.897506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-21T02:31:52.897831Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-21T02:31:52.899482Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-21T02:31:52.899519Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-21T02:31:52.90243Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-02-21T02:31:52.904302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-21T02:31:52.923294Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T02:31:52.923409Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T02:31:52.92345Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T02:32:10.804054Z","caller":"traceutil/trace.go:171","msg":"trace[541186980] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"158.245059ms","start":"2026-02-21T02:32:10.645777Z","end":"2026-02-21T02:32:10.804022Z","steps":["trace[541186980] 'process raft request' (duration: 158.081634ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:11.033971Z","caller":"traceutil/trace.go:171","msg":"trace[295078340] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"218.118805ms","start":"2026-02-21T02:32:10.815836Z","end":"2026-02-21T02:32:11.033954Z","steps":["trace[295078340] 'read index received' (duration: 214.406109ms)","trace[295078340] 'applied index is now lower than readState.Index' (duration: 3.708026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:32:11.034077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.230438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-21T02:32:11.03411Z","caller":"traceutil/trace.go:171","msg":"trace[1277158811] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:410; }","duration":"218.30275ms","start":"2026-02-21T02:32:10.815801Z","end":"2026-02-21T02:32:11.034103Z","steps":["trace[1277158811] 'agreement among raft nodes before linearized reading' (duration: 218.214928ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:11.034214Z","caller":"traceutil/trace.go:171","msg":"trace[1996229211] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"226.139653ms","start":"2026-02-21T02:32:10.808015Z","end":"2026-02-21T02:32:11.034155Z","steps":["trace[1996229211] 'process raft request' (duration: 222.238212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:16.662148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.728007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:32:16.662301Z","caller":"traceutil/trace.go:171","msg":"trace[164830] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"261.957263ms","start":"2026-02-21T02:32:16.400299Z","end":"2026-02-21T02:32:16.662257Z","steps":["trace[164830] 'process raft request' (duration: 105.559137ms)","trace[164830] 'compare' (duration: 155.575483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:32:19.399184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.434408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:32:19.399258Z","caller":"traceutil/trace.go:171","msg":"trace[2059433993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"301.555451ms","start":"2026-02-21T02:32:19.097685Z","end":"2026-02-21T02:32:19.39924Z","steps":["trace[2059433993] 'range keys from in-memory index tree' (duration: 301.347226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:19.399283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:19.097663Z","time spent":"301.615354ms","remote":"127.0.0.1:55350","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-21T02:32:19.399418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.549549ms","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-21T02:32:19.399452Z","caller":"traceutil/trace.go:171","msg":"trace[1341017292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"561.625711ms","start":"2026-02-21T02:32:18.837815Z","end":"2026-02-21T02:32:19.399441Z","steps":["trace[1341017292] 'range keys from in-memory index tree' (duration: 561.392295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:19.399454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.82946ms","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-21T02:32:19.399472Z","caller":"traceutil/trace.go:171","msg":"trace[1138043080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"333.846011ms","start":"2026-02-21T02:32:19.06562Z","end":"2026-02-21T02:32:19.399466Z","steps":["trace[1138043080] 'range keys from in-memory index tree' (duration: 333.745738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:19.399472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:18.837784Z","time spent":"561.682633ms","remote":"127.0.0.1:55674","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-21T02:32:19.399486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:19.065572Z","time spent":"333.909963ms","remote":"127.0.0.1:55674","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-21T02:32:22.671122Z","caller":"traceutil/trace.go:171","msg":"trace[300351992] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"119.530646ms","start":"2026-02-21T02:32:22.551554Z","end":"2026-02-21T02:32:22.671084Z","steps":["trace[300351992] 'read index received' (duration: 119.257308ms)","trace[300351992] 'applied index is now lower than readState.Index' (duration: 272.088µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:32:22.671289Z","caller":"traceutil/trace.go:171","msg":"trace[1933834056] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"164.452775ms","start":"2026-02-21T02:32:22.506784Z","end":"2026-02-21T02:32:22.671237Z","steps":["trace[1933834056] 'process raft request' (duration: 164.104875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:22.671392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.798163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-21T02:32:22.671636Z","caller":"traceutil/trace.go:171","msg":"trace[1826772909] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:471; }","duration":"120.077221ms","start":"2026-02-21T02:32:22.551529Z","end":"2026-02-21T02:32:22.671606Z","steps":["trace[1826772909] 'agreement among raft nodes before linearized reading' (duration: 119.747601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:23.197884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116545071423006,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:32:23.581594Z","caller":"traceutil/trace.go:171","msg":"trace[1486402951] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"884.321226ms","start":"2026-02-21T02:32:22.697244Z","end":"2026-02-21T02:32:23.581566Z","steps":["trace[1486402951] 'read index received' (duration: 884.087429ms)","trace[1486402951] 'applied index is now lower than readState.Index' (duration: 232.777µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:32:23.581692Z","caller":"traceutil/trace.go:171","msg":"trace[1282862910] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"901.952198ms","start":"2026-02-21T02:32:22.679701Z","end":"2026-02-21T02:32:23.581653Z","steps":["trace[1282862910] 'process raft request' (duration: 901.66521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:23.581767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.336026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:32:23.581803Z","caller":"traceutil/trace.go:171","msg":"trace[711549659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"473.385397ms","start":"2026-02-21T02:32:23.108405Z","end":"2026-02-21T02:32:23.581791Z","steps":["trace[711549659] 'agreement among raft nodes before linearized reading' (duration: 473.314955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:23.581834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:23.108364Z","time spent":"473.4626ms","remote":"127.0.0.1:55350","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-21T02:32:23.581859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.588303ms","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-21T02:32:23.581891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:22.679668Z","time spent":"902.125563ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:32:23.582006Z","caller":"traceutil/trace.go:171","msg":"trace[1119095395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"884.767638ms","start":"2026-02-21T02:32:22.69716Z","end":"2026-02-21T02:32:23.581928Z","steps":["trace[1119095395] 'agreement among raft nodes before linearized reading' (duration: 884.4969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:23.582067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:22.697135Z","time spent":"884.920723ms","remote":"127.0.0.1:55674","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-21T02:32:23.582111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.954157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-21T02:32:23.582075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.274594ms","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-21T02:32:23.582144Z","caller":"traceutil/trace.go:171","msg":"trace[712325637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:472; }","duration":"167.016119ms","start":"2026-02-21T02:32:23.415118Z","end":"2026-02-21T02:32:23.582134Z","steps":["trace[712325637] 'agreement among raft nodes before linearized reading' (duration: 166.925936ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:23.582209Z","caller":"traceutil/trace.go:171","msg":"trace[1060272240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"162.414678ms","start":"2026-02-21T02:32:23.419749Z","end":"2026-02-21T02:32:23.582164Z","steps":["trace[1060272240] 'agreement among raft nodes before linearized reading' (duration: 162.15713ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:30.518596Z","caller":"traceutil/trace.go:171","msg":"trace[90954771] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"478.576635ms","start":"2026-02-21T02:32:30.039953Z","end":"2026-02-21T02:32:30.51853Z","steps":["trace[90954771] 'process raft request' (duration: 478.328088ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:30.518612Z","caller":"traceutil/trace.go:171","msg":"trace[2034641063] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:513; }","duration":"418.127743ms","start":"2026-02-21T02:32:30.100409Z","end":"2026-02-21T02:32:30.518537Z","steps":["trace[2034641063] 'read index received' (duration: 418.114073ms)","trace[2034641063] 'applied index is now lower than readState.Index' (duration: 11.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:32:30.518796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.346639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T02:32:30.518843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:30.039906Z","time spent":"478.826053ms","remote":"127.0.0.1:55570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:32:30.518868Z","caller":"traceutil/trace.go:171","msg":"trace[1136928560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"418.481843ms","start":"2026-02-21T02:32:30.100369Z","end":"2026-02-21T02:32:30.518851Z","steps":["trace[1136928560] 'agreement among raft nodes before linearized reading' (duration: 418.325269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:30.518922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:30.100344Z","time spent":"418.567286ms","remote":"127.0.0.1:55350","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-21T02:32:31.051731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.070474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-21T02:32:31.051811Z","caller":"traceutil/trace.go:171","msg":"trace[671246671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"337.207618ms","start":"2026-02-21T02:32:30.714582Z","end":"2026-02-21T02:32:31.05179Z","steps":["trace[671246671] 'range keys from in-memory index tree' (duration: 336.962021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:31.051851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:30.714561Z","time spent":"337.28118ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T02:32:31.589772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.412174ms","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-21T02:32:31.589983Z","caller":"traceutil/trace.go:171","msg":"trace[131654556] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:495; }","duration":"284.62983ms","start":"2026-02-21T02:32:31.305324Z","end":"2026-02-21T02:32:31.589954Z","steps":["trace[131654556] 'range keys from in-memory index tree' (duration: 284.390003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:32.115065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.091161ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-21T02:32:32.115211Z","caller":"traceutil/trace.go:171","msg":"trace[1958861195] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"456.99385ms","start":"2026-02-21T02:32:31.658195Z","end":"2026-02-21T02:32:32.115189Z","steps":["trace[1958861195] 'read index received' (duration: 48.532µs)","trace[1958861195] 'applied index is now lower than readState.Index' (duration: 456.943308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:32:32.115384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.168035ms","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-21T02:32:32.116065Z","caller":"traceutil/trace.go:171","msg":"trace[859419712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"457.842954ms","start":"2026-02-21T02:32:31.658189Z","end":"2026-02-21T02:32:32.116032Z","steps":["trace[859419712] 'agreement among raft nodes before linearized reading' (duration: 457.022311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:32.116147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:31.658145Z","time spent":"457.978348ms","remote":"127.0.0.1:55674","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-21T02:32:32.115467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.264087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-21T02:32:32.115549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.197866ms","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-21T02:32:32.116255Z","caller":"traceutil/trace.go:171","msg":"trace[1341412654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"458.04744ms","start":"2026-02-21T02:32:31.658165Z","end":"2026-02-21T02:32:32.116213Z","steps":["trace[1341412654] 'agreement among raft nodes before linearized reading' (duration: 457.144644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:32.116333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:31.658145Z","time spent":"458.169423ms","remote":"127.0.0.1:55674","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-21T02:32:32.116361Z","caller":"traceutil/trace.go:171","msg":"trace[73391295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"458.067101ms","start":"2026-02-21T02:32:31.658243Z","end":"2026-02-21T02:32:32.11631Z","steps":["trace[73391295] 'agreement among raft nodes before linearized reading' (duration: 457.108144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:32.116463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:31.658209Z","time spent":"458.218564ms","remote":"127.0.0.1:55674","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-21T02:32:32.477338Z","caller":"traceutil/trace.go:171","msg":"trace[1373817414] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"354.184391ms","start":"2026-02-21T02:32:32.12313Z","end":"2026-02-21T02:32:32.477314Z","steps":["trace[1373817414] 'process raft request' (duration: 353.966275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:32.477483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:32.123099Z","time spent":"354.309545ms","remote":"127.0.0.1:55674","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-21T02:32:32.501065Z","caller":"traceutil/trace.go:171","msg":"trace[1801987568] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"377.724772ms","start":"2026-02-21T02:32:32.123321Z","end":"2026-02-21T02:32:32.501046Z","steps":["trace[1801987568] 'process raft request' (duration: 377.691221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:32.501181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:32.123282Z","time spent":"377.840585ms","remote":"127.0.0.1:55674","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-21T02:32:32.50147Z","caller":"traceutil/trace.go:171","msg":"trace[1614949039] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"378.169005ms","start":"2026-02-21T02:32:32.123296Z","end":"2026-02-21T02:32:32.501465Z","steps":["trace[1614949039] 'process raft request' (duration: 377.604868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:32.501507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:32.123272Z","time spent":"378.217466ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:32:34.34265Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.22777396s","expected-duration":"1s"} {"level":"info","ts":"2026-02-21T02:32:34.343075Z","caller":"traceutil/trace.go:171","msg":"trace[1179837950] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"242.738316ms","start":"2026-02-21T02:32:34.100309Z","end":"2026-02-21T02:32:34.343047Z","steps":["trace[1179837950] 'read index received' (duration: 242.500789ms)","trace[1179837950] 'applied index is now lower than readState.Index' (duration: 236.607µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:32:34.343168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.843549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:32:34.343201Z","caller":"traceutil/trace.go:171","msg":"trace[1709078362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"242.930641ms","start":"2026-02-21T02:32:34.100261Z","end":"2026-02-21T02:32:34.343192Z","steps":["trace[1709078362] 'agreement among raft nodes before linearized reading' (duration: 242.852679ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:34.343272Z","caller":"traceutil/trace.go:171","msg":"trace[6431253] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.228507471s","start":"2026-02-21T02:32:33.114729Z","end":"2026-02-21T02:32:34.343237Z","steps":["trace[6431253] 'process raft request' (duration: 1.228141841s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:34.343486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:33.114695Z","time spent":"1.228708097s","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:32:36.379815Z","caller":"traceutil/trace.go:171","msg":"trace[883679770] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"267.694457ms","start":"2026-02-21T02:32:36.11208Z","end":"2026-02-21T02:32:36.379775Z","steps":["trace[883679770] 'process raft request' (duration: 267.432529ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:39.467819Z","caller":"traceutil/trace.go:171","msg":"trace[1524291327] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"117.678062ms","start":"2026-02-21T02:32:39.350118Z","end":"2026-02-21T02:32:39.467796Z","steps":["trace[1524291327] 'process raft request' (duration: 116.696644ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:45.957397Z","caller":"traceutil/trace.go:171","msg":"trace[600081919] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"121.175192ms","start":"2026-02-21T02:32:45.836195Z","end":"2026-02-21T02:32:45.95737Z","steps":["trace[600081919] 'read index received' (duration: 121.057469ms)","trace[600081919] 'applied index is now lower than readState.Index' (duration: 117.023µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:32:45.957578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.369209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-21T02:32:45.957601Z","caller":"traceutil/trace.go:171","msg":"trace[1947488308] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:626; }","duration":"121.43828ms","start":"2026-02-21T02:32:45.836157Z","end":"2026-02-21T02:32:45.957596Z","steps":["trace[1947488308] 'agreement among raft nodes before linearized reading' (duration: 121.307896ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:45.95778Z","caller":"traceutil/trace.go:171","msg":"trace[1882580046] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"137.133117ms","start":"2026-02-21T02:32:45.820641Z","end":"2026-02-21T02:32:45.957774Z","steps":["trace[1882580046] 'process raft request' (duration: 136.611792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:46.244348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.909185ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-21T02:32:46.244459Z","caller":"traceutil/trace.go:171","msg":"trace[992523373] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"276.279281ms","start":"2026-02-21T02:32:45.968164Z","end":"2026-02-21T02:32:46.244443Z","steps":["trace[992523373] 'read index received' (duration: 29.280204ms)","trace[992523373] 'applied index is now lower than readState.Index' (duration: 246.997797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:32:46.244606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.431465ms","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-21T02:32:46.244644Z","caller":"traceutil/trace.go:171","msg":"trace[461704438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:626; }","duration":"276.508318ms","start":"2026-02-21T02:32:45.968126Z","end":"2026-02-21T02:32:46.244635Z","steps":["trace[461704438] 'agreement among raft nodes before linearized reading' (duration: 276.358753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:52.767228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.935848ms","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-21T02:32:52.767271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.159433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-21T02:32:52.767309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.247821ms","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-21T02:32:52.767335Z","caller":"traceutil/trace.go:171","msg":"trace[1563403498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"179.097623ms","start":"2026-02-21T02:32:52.588205Z","end":"2026-02-21T02:32:52.767303Z","steps":["trace[1563403498] 'range keys from in-memory index tree' (duration: 178.67582ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:52.767375Z","caller":"traceutil/trace.go:171","msg":"trace[1943566112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"315.326078ms","start":"2026-02-21T02:32:52.452024Z","end":"2026-02-21T02:32:52.76735Z","steps":["trace[1943566112] 'range keys from in-memory index tree' (duration: 314.951157ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:52.767384Z","caller":"traceutil/trace.go:171","msg":"trace[753249907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"174.337503ms","start":"2026-02-21T02:32:52.593025Z","end":"2026-02-21T02:32:52.767363Z","steps":["trace[753249907] 'range keys from in-memory index tree' (duration: 174.115897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:52.767422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:52.451997Z","time spent":"315.415871ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T02:32:52.76742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.715092ms","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-21T02:32:52.767511Z","caller":"traceutil/trace.go:171","msg":"trace[594158936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"207.853996ms","start":"2026-02-21T02:32:52.55963Z","end":"2026-02-21T02:32:52.767484Z","steps":["trace[594158936] 'range keys from in-memory index tree' (duration: 207.489815ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:52.991188Z","caller":"traceutil/trace.go:171","msg":"trace[222289274] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"219.270621ms","start":"2026-02-21T02:32:52.771877Z","end":"2026-02-21T02:32:52.991148Z","steps":["trace[222289274] 'process raft request' (duration: 219.023534ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:53.115666Z","caller":"traceutil/trace.go:171","msg":"trace[304050450] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"342.144145ms","start":"2026-02-21T02:32:52.773473Z","end":"2026-02-21T02:32:53.115617Z","steps":["trace[304050450] 'process raft request' (duration: 342.014092ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:53.115709Z","caller":"traceutil/trace.go:171","msg":"trace[1248873054] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"342.131195ms","start":"2026-02-21T02:32:52.773548Z","end":"2026-02-21T02:32:53.115679Z","steps":["trace[1248873054] 'process raft request' (duration: 341.97267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:53.115847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:52.773459Z","time spent":"342.28883ms","remote":"127.0.0.1:55674","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-21T02:32:53.115705Z","caller":"traceutil/trace.go:171","msg":"trace[365508318] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"342.607229ms","start":"2026-02-21T02:32:52.773072Z","end":"2026-02-21T02:32:53.115679Z","steps":["trace[365508318] 'process raft request' (duration: 342.234688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:53.11609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:52.773539Z","time spent":"342.32072ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:32:53.116121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:52.773035Z","time spent":"343.028761ms","remote":"127.0.0.1:55674","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-21T02:32:55.484859Z","caller":"traceutil/trace.go:171","msg":"trace[703713851] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"487.700695ms","start":"2026-02-21T02:32:54.99714Z","end":"2026-02-21T02:32:55.48484Z","steps":["trace[703713851] 'process raft request' (duration: 487.581852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:55.484988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:54.997086Z","time spent":"487.831579ms","remote":"127.0.0.1:55674","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-21T02:32:55.723411Z","caller":"traceutil/trace.go:171","msg":"trace[1535124050] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"626.409764ms","start":"2026-02-21T02:32:55.096979Z","end":"2026-02-21T02:32:55.723389Z","steps":["trace[1535124050] 'read index received' (duration: 387.931799ms)","trace[1535124050] 'applied index is now lower than readState.Index' (duration: 238.477165ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:32:55.723507Z","caller":"traceutil/trace.go:171","msg":"trace[448424427] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"694.704747ms","start":"2026-02-21T02:32:55.028773Z","end":"2026-02-21T02:32:55.723477Z","steps":["trace[448424427] 'process raft request' (duration: 622.368215ms)","trace[448424427] 'compare' (duration: 72.161797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:32:55.723593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.596539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T02:32:55.723624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:55.028754Z","time spent":"694.80964ms","remote":"127.0.0.1:55442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-21T02:32:55.723645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.869982ms","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-21T02:32:55.723648Z","caller":"traceutil/trace.go:171","msg":"trace[421173400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"626.692242ms","start":"2026-02-21T02:32:55.09694Z","end":"2026-02-21T02:32:55.723633Z","steps":["trace[421173400] 'agreement among raft nodes before linearized reading' (duration: 626.553428ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:55.723672Z","caller":"traceutil/trace.go:171","msg":"trace[1257065988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"603.923894ms","start":"2026-02-21T02:32:55.119741Z","end":"2026-02-21T02:32:55.723665Z","steps":["trace[1257065988] 'agreement among raft nodes before linearized reading' (duration: 603.76277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:55.723682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:55.096929Z","time spent":"626.746774ms","remote":"127.0.0.1:55350","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-21T02:32:55.723697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:55.119733Z","time spent":"603.955875ms","remote":"127.0.0.1:55674","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-21T02:32:55.72389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.861722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-21T02:32:55.723911Z","caller":"traceutil/trace.go:171","msg":"trace[1788108174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"603.913233ms","start":"2026-02-21T02:32:55.119992Z","end":"2026-02-21T02:32:55.723905Z","steps":["trace[1788108174] 'agreement among raft nodes before linearized reading' (duration: 603.854302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:55.723927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:55.119975Z","time spent":"603.948245ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T02:32:55.724093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.222223ms","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-21T02:32:55.724145Z","caller":"traceutil/trace.go:171","msg":"trace[949179200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"604.310805ms","start":"2026-02-21T02:32:55.119819Z","end":"2026-02-21T02:32:55.72413Z","steps":["trace[949179200] 'agreement among raft nodes before linearized reading' (duration: 604.1457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:32:55.724187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:32:55.119796Z","time spent":"604.383207ms","remote":"127.0.0.1:55674","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-21T02:32:55.911884Z","caller":"traceutil/trace.go:171","msg":"trace[48866700] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"184.395199ms","start":"2026-02-21T02:32:55.727471Z","end":"2026-02-21T02:32:55.911866Z","steps":["trace[48866700] 'process raft request' (duration: 176.562499ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:32:55.911925Z","caller":"traceutil/trace.go:171","msg":"trace[350524365] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"183.293847ms","start":"2026-02-21T02:32:55.728599Z","end":"2026-02-21T02:32:55.911893Z","steps":["trace[350524365] 'process raft request' (duration: 182.970467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:17.660524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:16.961025Z","time spent":"699.488547ms","remote":"127.0.0.1:59228","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-21T02:33:17.661287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.58483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:33:17.661664Z","caller":"traceutil/trace.go:171","msg":"trace[725647024] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"963.185261ms","start":"2026-02-21T02:33:16.698276Z","end":"2026-02-21T02:33:17.661461Z","steps":["trace[725647024] 'read index received' (duration: 279.2236ms)","trace[725647024] 'applied index is now lower than readState.Index' (duration: 683.960381ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:17.661773Z","caller":"traceutil/trace.go:171","msg":"trace[204473738] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.098094288s","start":"2026-02-21T02:33:16.563668Z","end":"2026-02-21T02:33:17.661762Z","steps":["trace[204473738] 'process raft request' (duration: 413.910221ms)","trace[204473738] 'compare' (duration: 683.451476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:33:17.661837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:16.563652Z","time spent":"1.09815429s","remote":"127.0.0.1:55674","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-21T02:33:17.662143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.198443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T02:33:17.66228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.016535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:33:17.662328Z","caller":"traceutil/trace.go:171","msg":"trace[838454152] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:757; }","duration":"964.112689ms","start":"2026-02-21T02:33:16.698203Z","end":"2026-02-21T02:33:17.662316Z","steps":["trace[838454152] 'agreement among raft nodes before linearized reading' (duration: 964.031836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:17.66236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:16.697762Z","time spent":"964.589913ms","remote":"127.0.0.1:55718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-21T02:33:17.662266Z","caller":"traceutil/trace.go:171","msg":"trace[1645373445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"212.40303ms","start":"2026-02-21T02:33:17.44984Z","end":"2026-02-21T02:33:17.662243Z","steps":["trace[1645373445] 'agreement among raft nodes before linearized reading' (duration: 212.219104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:18.004103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.747805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-21T02:33:18.004758Z","caller":"traceutil/trace.go:171","msg":"trace[2011893466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"131.467416ms","start":"2026-02-21T02:33:17.87326Z","end":"2026-02-21T02:33:18.004728Z","steps":["trace[2011893466] 'range keys from in-memory index tree' (duration: 130.488568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:18.286955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.024372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:33:18.287259Z","caller":"traceutil/trace.go:171","msg":"trace[21422056] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:790; }","duration":"171.960794ms","start":"2026-02-21T02:33:18.115274Z","end":"2026-02-21T02:33:18.287235Z","steps":["trace[21422056] 'read index received' (duration: 68.951972ms)","trace[21422056] 'applied index is now lower than readState.Index' (duration: 103.007682ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:18.287249Z","caller":"traceutil/trace.go:171","msg":"trace[1866330868] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"207.809916ms","start":"2026-02-21T02:33:18.079396Z","end":"2026-02-21T02:33:18.287206Z","steps":["trace[1866330868] 'process raft request' (duration: 104.873467ms)","trace[1866330868] 'compare' (duration: 101.829426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:33:18.287541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.231551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:33:18.287653Z","caller":"traceutil/trace.go:171","msg":"trace[483852384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"172.407267ms","start":"2026-02-21T02:33:18.115216Z","end":"2026-02-21T02:33:18.287623Z","steps":["trace[483852384] 'agreement among raft nodes before linearized reading' (duration: 172.143279ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:18.287903Z","caller":"traceutil/trace.go:171","msg":"trace[354875520] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"203.372686ms","start":"2026-02-21T02:33:18.084515Z","end":"2026-02-21T02:33:18.287887Z","steps":["trace[354875520] 'process raft request' (duration: 202.581713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:18.287446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.747589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-21T02:33:18.288083Z","caller":"traceutil/trace.go:171","msg":"trace[1740905850] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:761; }","duration":"109.383638ms","start":"2026-02-21T02:33:18.178682Z","end":"2026-02-21T02:33:18.288065Z","steps":["trace[1740905850] 'agreement among raft nodes before linearized reading' (duration: 108.686208ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:19.039231Z","caller":"traceutil/trace.go:171","msg":"trace[636433060] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"131.162537ms","start":"2026-02-21T02:33:18.908036Z","end":"2026-02-21T02:33:19.039198Z","steps":["trace[636433060] 'process raft request' (duration: 130.944751ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:19.948855Z","caller":"traceutil/trace.go:171","msg":"trace[2111330091] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"184.4184ms","start":"2026-02-21T02:33:19.764396Z","end":"2026-02-21T02:33:19.948814Z","steps":["trace[2111330091] 'process raft request' (duration: 138.152483ms)","trace[2111330091] 'compare' (duration: 45.625108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:33:20.377036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.26577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-21T02:33:20.377141Z","caller":"traceutil/trace.go:171","msg":"trace[1537348631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"297.437875ms","start":"2026-02-21T02:33:20.079679Z","end":"2026-02-21T02:33:20.377117Z","steps":["trace[1537348631] 'range keys from in-memory index tree' (duration: 297.029623ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:20.649787Z","caller":"traceutil/trace.go:171","msg":"trace[1398925032] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"250.295372ms","start":"2026-02-21T02:33:20.399457Z","end":"2026-02-21T02:33:20.649753Z","steps":["trace[1398925032] 'process raft request' (duration: 249.799367ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:20.838514Z","caller":"traceutil/trace.go:171","msg":"trace[1240835062] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:795; }","duration":"291.923923ms","start":"2026-02-21T02:33:20.546561Z","end":"2026-02-21T02:33:20.838485Z","steps":["trace[1240835062] 'read index received' (duration: 102.778275ms)","trace[1240835062] 'applied index is now lower than readState.Index' (duration: 189.144028ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:20.83942Z","caller":"traceutil/trace.go:171","msg":"trace[742732161] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"438.840732ms","start":"2026-02-21T02:33:20.400551Z","end":"2026-02-21T02:33:20.839392Z","steps":["trace[742732161] 'process raft request' (duration: 437.866284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:20.839583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:20.400521Z","time spent":"438.969266ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:33:20.839774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.223131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-21T02:33:20.839821Z","caller":"traceutil/trace.go:171","msg":"trace[357223822] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:767; }","duration":"293.263112ms","start":"2026-02-21T02:33:20.546537Z","end":"2026-02-21T02:33:20.8398Z","steps":["trace[357223822] 'agreement among raft nodes before linearized reading' (duration: 293.19694ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:20.848981Z","caller":"traceutil/trace.go:171","msg":"trace[808885038] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"448.466814ms","start":"2026-02-21T02:33:20.400461Z","end":"2026-02-21T02:33:20.848928Z","steps":["trace[808885038] 'process raft request' (duration: 421.72492ms)","trace[808885038] 'compare' (duration: 16.067861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:33:20.849165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:20.400435Z","time spent":"448.631639ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:33:31.063123Z","caller":"traceutil/trace.go:171","msg":"trace[662320626] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"163.527657ms","start":"2026-02-21T02:33:30.89957Z","end":"2026-02-21T02:33:31.063098Z","steps":["trace[662320626] 'process raft request' (duration: 163.368022ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:31.349124Z","caller":"traceutil/trace.go:171","msg":"trace[1559701998] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"447.371343ms","start":"2026-02-21T02:33:30.901717Z","end":"2026-02-21T02:33:31.349088Z","steps":["trace[1559701998] 'process raft request' (duration: 349.391599ms)","trace[1559701998] 'compare' (duration: 97.813999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:33:31.349411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:30.901684Z","time spent":"447.581279ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:33:31.824047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.306879ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-21T02:33:33.56803Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116545071423863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-21T02:33:34.013827Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.242067822s","expected-duration":"1s"} {"level":"info","ts":"2026-02-21T02:33:34.014318Z","caller":"traceutil/trace.go:171","msg":"trace[506285249] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"1.24267717s","start":"2026-02-21T02:33:32.771608Z","end":"2026-02-21T02:33:34.014285Z","steps":["trace[506285249] 'process raft request' (duration: 1.242487004s)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:34.014292Z","caller":"traceutil/trace.go:171","msg":"trace[1861973697] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"946.596065ms","start":"2026-02-21T02:33:33.067668Z","end":"2026-02-21T02:33:34.014264Z","steps":["trace[1861973697] 'read index received' (duration: 946.340868ms)","trace[1861973697] 'applied index is now lower than readState.Index' (duration: 254.077µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:33:34.014451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:32.771576Z","time spent":"1.242792653s","remote":"127.0.0.1:55674","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-21T02:33:34.01458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.848803ms","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-21T02:33:34.014559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.863854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-21T02:33:34.014615Z","caller":"traceutil/trace.go:171","msg":"trace[48676499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:849; }","duration":"660.910116ms","start":"2026-02-21T02:33:33.353695Z","end":"2026-02-21T02:33:34.014605Z","steps":["trace[48676499] 'agreement among raft nodes before linearized reading' (duration: 660.729721ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:34.014642Z","caller":"traceutil/trace.go:171","msg":"trace[375929936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:849; }","duration":"947.016578ms","start":"2026-02-21T02:33:33.067605Z","end":"2026-02-21T02:33:34.014622Z","steps":["trace[375929936] 'agreement among raft nodes before linearized reading' (duration: 946.7498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:34.014657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:33.353583Z","time spent":"661.06168ms","remote":"127.0.0.1:55674","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-21T02:33:34.014677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:33.06758Z","time spent":"947.09094ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T02:33:34.01473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.632516ms","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-21T02:33:34.014791Z","caller":"traceutil/trace.go:171","msg":"trace[792669583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:849; }","duration":"108.701768ms","start":"2026-02-21T02:33:33.906073Z","end":"2026-02-21T02:33:34.014775Z","steps":["trace[792669583] 'agreement among raft nodes before linearized reading' (duration: 108.455151ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:38.253682Z","caller":"traceutil/trace.go:171","msg":"trace[680762144] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"109.305076ms","start":"2026-02-21T02:33:38.144354Z","end":"2026-02-21T02:33:38.253659Z","steps":["trace[680762144] 'process raft request' (duration: 109.277085ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:38.253702Z","caller":"traceutil/trace.go:171","msg":"trace[1047984381] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"109.887713ms","start":"2026-02-21T02:33:38.143789Z","end":"2026-02-21T02:33:38.253677Z","steps":["trace[1047984381] 'process raft request' (duration: 98.432687ms)","trace[1047984381] 'compare' (duration: 11.21992ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:38.253701Z","caller":"traceutil/trace.go:171","msg":"trace[1123801748] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"109.618195ms","start":"2026-02-21T02:33:38.144053Z","end":"2026-02-21T02:33:38.253671Z","steps":["trace[1123801748] 'process raft request' (duration: 109.510602ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:38.253805Z","caller":"traceutil/trace.go:171","msg":"trace[2005024241] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"109.666367ms","start":"2026-02-21T02:33:38.144099Z","end":"2026-02-21T02:33:38.253766Z","steps":["trace[2005024241] 'process raft request' (duration: 109.506902ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:48.827894Z","caller":"traceutil/trace.go:171","msg":"trace[2056395308] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"478.655421ms","start":"2026-02-21T02:33:48.349204Z","end":"2026-02-21T02:33:48.82786Z","steps":["trace[2056395308] 'read index received' (duration: 478.451745ms)","trace[2056395308] 'applied index is now lower than readState.Index' (duration: 202.596µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:48.82827Z","caller":"traceutil/trace.go:171","msg":"trace[587104275] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"491.175098ms","start":"2026-02-21T02:33:48.337083Z","end":"2026-02-21T02:33:48.828258Z","steps":["trace[587104275] 'process raft request' (duration: 490.614021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:48.828425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:48.337063Z","time spent":"491.273141ms","remote":"127.0.0.1:55674","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-21T02:33:48.828817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.63578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:33:48.828867Z","caller":"traceutil/trace.go:171","msg":"trace[339663996] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:890; }","duration":"479.737492ms","start":"2026-02-21T02:33:48.349118Z","end":"2026-02-21T02:33:48.828855Z","steps":["trace[339663996] 'agreement among raft nodes before linearized reading' (duration: 479.65647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:48.828899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:48.349094Z","time spent":"479.796884ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-21T02:33:48.829279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.994965ms","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-21T02:33:48.829366Z","caller":"traceutil/trace.go:171","msg":"trace[1316237224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"471.11409ms","start":"2026-02-21T02:33:48.358231Z","end":"2026-02-21T02:33:48.829345Z","steps":["trace[1316237224] 'agreement among raft nodes before linearized reading' (duration: 470.79009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:48.829392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.305357ms","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-21T02:33:48.829423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:48.358224Z","time spent":"471.183241ms","remote":"127.0.0.1:55674","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-21T02:33:48.82944Z","caller":"traceutil/trace.go:171","msg":"trace[446949542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"469.369038ms","start":"2026-02-21T02:33:48.360056Z","end":"2026-02-21T02:33:48.829426Z","steps":["trace[446949542] 'agreement among raft nodes before linearized reading' (duration: 469.217663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:48.829469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:48.360049Z","time spent":"469.41394ms","remote":"127.0.0.1:55674","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-21T02:33:48.829597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.311906ms","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-21T02:33:48.829627Z","caller":"traceutil/trace.go:171","msg":"trace[1816422910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"470.390069ms","start":"2026-02-21T02:33:48.35923Z","end":"2026-02-21T02:33:48.82962Z","steps":["trace[1816422910] 'agreement among raft nodes before linearized reading' (duration: 470.311406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:48.829664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:48.359205Z","time spent":"470.441239ms","remote":"127.0.0.1:55674","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-21T02:33:49.018382Z","caller":"traceutil/trace.go:171","msg":"trace[1413807336] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"184.711647ms","start":"2026-02-21T02:33:48.833644Z","end":"2026-02-21T02:33:49.018355Z","steps":["trace[1413807336] 'process raft request' (duration: 184.563023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:49.182905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.744958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:33:49.183215Z","caller":"traceutil/trace.go:171","msg":"trace[493879525] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"348.169542ms","start":"2026-02-21T02:33:48.835014Z","end":"2026-02-21T02:33:49.183184Z","steps":["trace[493879525] 'process raft request' (duration: 206.056864ms)","trace[493879525] 'compare' (duration: 141.589943ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:49.18333Z","caller":"traceutil/trace.go:171","msg":"trace[1627433160] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"346.477243ms","start":"2026-02-21T02:33:48.836787Z","end":"2026-02-21T02:33:49.183264Z","steps":["trace[1627433160] 'process raft request' (duration: 346.261276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:49.18338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:48.835003Z","time spent":"348.280816ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:33:49.183494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:48.836777Z","time spent":"346.664188ms","remote":"127.0.0.1:55674","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-21T02:33:50.470889Z","caller":"traceutil/trace.go:171","msg":"trace[1477630594] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"277.237781ms","start":"2026-02-21T02:33:50.193624Z","end":"2026-02-21T02:33:50.470861Z","steps":["trace[1477630594] 'process raft request' (duration: 277.108058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:51.355191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.119026ms","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-21T02:33:51.355283Z","caller":"traceutil/trace.go:171","msg":"trace[441263879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"168.27664ms","start":"2026-02-21T02:33:51.186985Z","end":"2026-02-21T02:33:51.355262Z","steps":["trace[441263879] 'range keys from in-memory index tree' (duration: 167.91873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:51.355598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.515062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-21T02:33:51.35567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.276271ms","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-21T02:33:51.355704Z","caller":"traceutil/trace.go:171","msg":"trace[582678000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"333.353442ms","start":"2026-02-21T02:33:51.022339Z","end":"2026-02-21T02:33:51.355693Z","steps":["trace[582678000] 'range keys from in-memory index tree' (duration: 333.153456ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:51.355697Z","caller":"traceutil/trace.go:171","msg":"trace[88020033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"521.673096ms","start":"2026-02-21T02:33:50.833999Z","end":"2026-02-21T02:33:51.355672Z","steps":["trace[88020033] 'range keys from in-memory index tree' (duration: 521.243244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:51.355732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:51.022316Z","time spent":"333.408054ms","remote":"127.0.0.1:55674","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-21T02:33:51.355746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:50.833962Z","time spent":"521.770529ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T02:33:51.355817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.904001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:33:51.355867Z","caller":"traceutil/trace.go:171","msg":"trace[1403873196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"256.001924ms","start":"2026-02-21T02:33:51.099847Z","end":"2026-02-21T02:33:51.355849Z","steps":["trace[1403873196] 'range keys from in-memory index tree' (duration: 255.794027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:51.355196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.241934ms","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-21T02:33:51.35601Z","caller":"traceutil/trace.go:171","msg":"trace[2094439463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"167.116978ms","start":"2026-02-21T02:33:51.188862Z","end":"2026-02-21T02:33:51.355979Z","steps":["trace[2094439463] 'range keys from in-memory index tree' (duration: 166.048569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:51.52017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.574408ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-21T02:33:51.520614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.293015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:33:51.520717Z","caller":"traceutil/trace.go:171","msg":"trace[2020867467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"162.450539ms","start":"2026-02-21T02:33:51.358241Z","end":"2026-02-21T02:33:51.520691Z","steps":["trace[2020867467] 'agreement among raft nodes before linearized reading' (duration: 162.286135ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:51.520942Z","caller":"traceutil/trace.go:171","msg":"trace[236246255] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"162.079729ms","start":"2026-02-21T02:33:51.358308Z","end":"2026-02-21T02:33:51.520388Z","steps":["trace[236246255] 'read index received' (duration: 62.871µs)","trace[236246255] 'applied index is now lower than readState.Index' (duration: 162.014718ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:51.542538Z","caller":"traceutil/trace.go:171","msg":"trace[922150011] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"183.089167ms","start":"2026-02-21T02:33:51.359435Z","end":"2026-02-21T02:33:51.542524Z","steps":["trace[922150011] 'process raft request' (duration: 182.951563ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:51.575916Z","caller":"traceutil/trace.go:171","msg":"trace[1662330752] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"214.288196ms","start":"2026-02-21T02:33:51.361603Z","end":"2026-02-21T02:33:51.575891Z","steps":["trace[1662330752] 'process raft request' (duration: 214.143392ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:51.57593Z","caller":"traceutil/trace.go:171","msg":"trace[172173181] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"214.975524ms","start":"2026-02-21T02:33:51.360891Z","end":"2026-02-21T02:33:51.575866Z","steps":["trace[172173181] 'process raft request' (duration: 214.682326ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:51.575915Z","caller":"traceutil/trace.go:171","msg":"trace[829904539] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"213.927926ms","start":"2026-02-21T02:33:51.361956Z","end":"2026-02-21T02:33:51.575884Z","steps":["trace[829904539] 'process raft request' (duration: 213.865804ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:52.94618Z","caller":"traceutil/trace.go:171","msg":"trace[1096630336] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"465.538309ms","start":"2026-02-21T02:33:52.48061Z","end":"2026-02-21T02:33:52.946149Z","steps":["trace[1096630336] 'process raft request' (duration: 465.296702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:52.946367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:52.480571Z","time spent":"465.704944ms","remote":"127.0.0.1:55674","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-21T02:33:53.868603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.985859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:33:53.868715Z","caller":"traceutil/trace.go:171","msg":"trace[718585345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"768.164325ms","start":"2026-02-21T02:33:53.100526Z","end":"2026-02-21T02:33:53.86869Z","steps":["trace[718585345] 'range keys from in-memory index tree' (duration: 767.873367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:53.868779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:53.100497Z","time spent":"768.268858ms","remote":"127.0.0.1:55350","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-21T02:33:53.869177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.836038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-21T02:33:53.86938Z","caller":"traceutil/trace.go:171","msg":"trace[1871096180] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:901; }","duration":"325.091004ms","start":"2026-02-21T02:33:53.544275Z","end":"2026-02-21T02:33:53.869366Z","steps":["trace[1871096180] 'count revisions from in-memory index tree' (duration: 324.729264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:53.869431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:53.544253Z","time spent":"325.157497ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-21T02:33:53.869688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.849203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-21T02:33:53.869725Z","caller":"traceutil/trace.go:171","msg":"trace[713510261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"322.915335ms","start":"2026-02-21T02:33:53.546801Z","end":"2026-02-21T02:33:53.869716Z","steps":["trace[713510261] 'range keys from in-memory index tree' (duration: 322.625756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:53.869753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:53.546787Z","time spent":"322.959056ms","remote":"127.0.0.1:55674","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-21T02:33:53.869925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.939322ms","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-21T02:33:53.869958Z","caller":"traceutil/trace.go:171","msg":"trace[357225448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"288.019934ms","start":"2026-02-21T02:33:53.58193Z","end":"2026-02-21T02:33:53.86995Z","steps":["trace[357225448] 'range keys from in-memory index tree' (duration: 287.84858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:53.870108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.276541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-21T02:33:53.87014Z","caller":"traceutil/trace.go:171","msg":"trace[897010427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:901; }","duration":"288.361234ms","start":"2026-02-21T02:33:53.581771Z","end":"2026-02-21T02:33:53.870132Z","steps":["trace[897010427] 'range keys from in-memory index tree' (duration: 287.924352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:53.87029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.176469ms","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-21T02:33:53.870321Z","caller":"traceutil/trace.go:171","msg":"trace[1413640334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"288.22898ms","start":"2026-02-21T02:33:53.582085Z","end":"2026-02-21T02:33:53.870314Z","steps":["trace[1413640334] 'range keys from in-memory index tree' (duration: 288.085506ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:54.177771Z","caller":"traceutil/trace.go:171","msg":"trace[941329206] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"303.218633ms","start":"2026-02-21T02:33:53.874519Z","end":"2026-02-21T02:33:54.177737Z","steps":["trace[941329206] 'process raft request' (duration: 302.960786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:54.178077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:53.874502Z","time spent":"303.358777ms","remote":"127.0.0.1:55674","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-21T02:33:54.184552Z","caller":"traceutil/trace.go:171","msg":"trace[396857043] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"309.532567ms","start":"2026-02-21T02:33:53.875007Z","end":"2026-02-21T02:33:54.18454Z","steps":["trace[396857043] 'process raft request' (duration: 309.469015ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:54.184585Z","caller":"traceutil/trace.go:171","msg":"trace[1062612967] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"309.559238ms","start":"2026-02-21T02:33:53.874981Z","end":"2026-02-21T02:33:54.184541Z","steps":["trace[1062612967] 'process raft request' (duration: 309.467255ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:54.184577Z","caller":"traceutil/trace.go:171","msg":"trace[1026885369] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"309.66234ms","start":"2026-02-21T02:33:53.874874Z","end":"2026-02-21T02:33:54.184537Z","steps":["trace[1026885369] 'process raft request' (duration: 309.515645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:54.184728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:53.874976Z","time spent":"309.675411ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:33:54.184618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:53.874996Z","time spent":"309.588188ms","remote":"127.0.0.1:55674","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-21T02:33:54.184791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:53.874852Z","time spent":"309.827724ms","remote":"127.0.0.1:55674","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-21T02:33:55.127949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.475332ms","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-21T02:33:55.12803Z","caller":"traceutil/trace.go:171","msg":"trace[2055745256] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:906; }","duration":"101.614206ms","start":"2026-02-21T02:33:55.026397Z","end":"2026-02-21T02:33:55.128011Z","steps":["trace[2055745256] 'count revisions from in-memory index tree' (duration: 101.39664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:56.373872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.197467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:33:56.373985Z","caller":"traceutil/trace.go:171","msg":"trace[1426802899] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"191.764598ms","start":"2026-02-21T02:33:56.182203Z","end":"2026-02-21T02:33:56.373967Z","steps":["trace[1426802899] 'read index received' (duration: 6.315605ms)","trace[1426802899] 'applied index is now lower than readState.Index' (duration: 185.448223ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:56.374033Z","caller":"traceutil/trace.go:171","msg":"trace[1371096432] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"408.409485ms","start":"2026-02-21T02:33:55.965581Z","end":"2026-02-21T02:33:56.37399Z","steps":["trace[1371096432] 'process raft request' (duration: 223.002463ms)","trace[1371096432] 'compare' (duration: 184.904738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:33:56.374204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:55.965563Z","time spent":"408.547199ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:33:56.374226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.443903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-21T02:33:56.374265Z","caller":"traceutil/trace.go:171","msg":"trace[617835117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"185.493105ms","start":"2026-02-21T02:33:56.188749Z","end":"2026-02-21T02:33:56.374242Z","steps":["trace[617835117] 'agreement among raft nodes before linearized reading' (duration: 185.31226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:56.374255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.497317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-21T02:33:56.374269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.445553ms","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-21T02:33:56.374299Z","caller":"traceutil/trace.go:171","msg":"trace[1752709327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"184.558339ms","start":"2026-02-21T02:33:56.189728Z","end":"2026-02-21T02:33:56.374286Z","steps":["trace[1752709327] 'agreement among raft nodes before linearized reading' (duration: 184.347333ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:56.374306Z","caller":"traceutil/trace.go:171","msg":"trace[1824487789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"185.498214ms","start":"2026-02-21T02:33:56.188797Z","end":"2026-02-21T02:33:56.374295Z","steps":["trace[1824487789] 'agreement among raft nodes before linearized reading' (duration: 185.287878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:56.374676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.469377ms","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-21T02:33:56.374726Z","caller":"traceutil/trace.go:171","msg":"trace[893011616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:907; }","duration":"192.572451ms","start":"2026-02-21T02:33:56.182143Z","end":"2026-02-21T02:33:56.374716Z","steps":["trace[893011616] 'agreement among raft nodes before linearized reading' (duration: 191.911812ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:33:56.69751Z","caller":"traceutil/trace.go:171","msg":"trace[1466529501] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:948; }","duration":"317.975752ms","start":"2026-02-21T02:33:56.379514Z","end":"2026-02-21T02:33:56.69749Z","steps":["trace[1466529501] 'read index received' (duration: 68.795345ms)","trace[1466529501] 'applied index is now lower than readState.Index' (duration: 249.179697ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:56.697736Z","caller":"traceutil/trace.go:171","msg":"trace[1221706738] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"319.198886ms","start":"2026-02-21T02:33:56.378494Z","end":"2026-02-21T02:33:56.697693Z","steps":["trace[1221706738] 'process raft request' (duration: 318.825745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:56.698184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:56.378484Z","time spent":"319.564125ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:33:56.697905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.356962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-21T02:33:56.698402Z","caller":"traceutil/trace.go:171","msg":"trace[1041467060] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:911; }","duration":"318.900497ms","start":"2026-02-21T02:33:56.379492Z","end":"2026-02-21T02:33:56.698392Z","steps":["trace[1041467060] 'agreement among raft nodes before linearized reading' (duration: 318.28978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:56.698461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:56.379483Z","time spent":"318.967229ms","remote":"127.0.0.1:55686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-21T02:33:56.697957Z","caller":"traceutil/trace.go:171","msg":"trace[2131637984] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"319.402751ms","start":"2026-02-21T02:33:56.378546Z","end":"2026-02-21T02:33:56.697949Z","steps":["trace[2131637984] 'process raft request' (duration: 318.888546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:56.69867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:56.378541Z","time spent":"320.103821ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:33:56.697991Z","caller":"traceutil/trace.go:171","msg":"trace[1865935942] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"318.404983ms","start":"2026-02-21T02:33:56.379565Z","end":"2026-02-21T02:33:56.69797Z","steps":["trace[1865935942] 'process raft request' (duration: 317.898669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:56.698989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:56.37955Z","time spent":"319.407162ms","remote":"127.0.0.1:55674","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-21T02:33:57.008974Z","caller":"traceutil/trace.go:171","msg":"trace[774703066] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"310.953917ms","start":"2026-02-21T02:33:56.69799Z","end":"2026-02-21T02:33:57.008944Z","steps":["trace[774703066] 'read index received' (duration: 232.352521ms)","trace[774703066] 'applied index is now lower than readState.Index' (duration: 78.599906ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:33:57.009252Z","caller":"traceutil/trace.go:171","msg":"trace[534039345] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"493.671115ms","start":"2026-02-21T02:33:56.515564Z","end":"2026-02-21T02:33:57.009235Z","steps":["trace[534039345] 'process raft request' (duration: 414.809032ms)","trace[534039345] 'compare' (duration: 78.446861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:33:57.00979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.034894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4742"} {"level":"info","ts":"2026-02-21T02:33:57.009852Z","caller":"traceutil/trace.go:171","msg":"trace[1018386484] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:912; }","duration":"365.139467ms","start":"2026-02-21T02:33:56.644696Z","end":"2026-02-21T02:33:57.009835Z","steps":["trace[1018386484] 'agreement among raft nodes before linearized reading' (duration: 364.947561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:33:57.009885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:56.644675Z","time spent":"365.203069ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4765,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-21T02:33:57.009433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:33:56.515525Z","time spent":"493.788908ms","remote":"127.0.0.1:55442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-21T02:34:02.9697Z","caller":"traceutil/trace.go:171","msg":"trace[2000682698] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"150.261719ms","start":"2026-02-21T02:34:02.819408Z","end":"2026-02-21T02:34:02.96967Z","steps":["trace[2000682698] 'read index received' (duration: 150.037753ms)","trace[2000682698] 'applied index is now lower than readState.Index' (duration: 223.136µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:34:02.970104Z","caller":"traceutil/trace.go:171","msg":"trace[190071891] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"231.489038ms","start":"2026-02-21T02:34:02.738563Z","end":"2026-02-21T02:34:02.970052Z","steps":["trace[190071891] 'process raft request' (duration: 230.959023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:34:02.970208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.772583ms","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-21T02:34:02.970294Z","caller":"traceutil/trace.go:171","msg":"trace[1052813660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"150.940938ms","start":"2026-02-21T02:34:02.819332Z","end":"2026-02-21T02:34:02.970273Z","steps":["trace[1052813660] 'agreement among raft nodes before linearized reading' (duration: 150.619049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:34:02.970331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.882366ms","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-21T02:34:02.970427Z","caller":"traceutil/trace.go:171","msg":"trace[290390342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"151.0485ms","start":"2026-02-21T02:34:02.819357Z","end":"2026-02-21T02:34:02.970406Z","steps":["trace[290390342] 'agreement among raft nodes before linearized reading' (duration: 150.764763ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:34:03.297347Z","caller":"traceutil/trace.go:171","msg":"trace[1482804529] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"321.752106ms","start":"2026-02-21T02:34:02.975562Z","end":"2026-02-21T02:34:03.297314Z","steps":["trace[1482804529] 'process raft request' (duration: 270.96165ms)","trace[1482804529] 'compare' (duration: 50.635652ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:34:03.29754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:34:02.975531Z","time spent":"321.90137ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:34:03.625661Z","caller":"traceutil/trace.go:171","msg":"trace[409878399] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"491.923736ms","start":"2026-02-21T02:34:03.133712Z","end":"2026-02-21T02:34:03.625635Z","steps":["trace[409878399] 'read index received' (duration: 112.741181ms)","trace[409878399] 'applied index is now lower than readState.Index' (duration: 379.181575ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:34:03.62583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.09159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:34:03.625787Z","caller":"traceutil/trace.go:171","msg":"trace[783701280] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"649.999782ms","start":"2026-02-21T02:34:02.975743Z","end":"2026-02-21T02:34:03.625742Z","steps":["trace[783701280] 'process raft request' (duration: 623.629682ms)","trace[783701280] 'compare' (duration: 26.135313ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:34:03.625855Z","caller":"traceutil/trace.go:171","msg":"trace[930795832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"492.185633ms","start":"2026-02-21T02:34:03.133662Z","end":"2026-02-21T02:34:03.625847Z","steps":["trace[930795832] 'agreement among raft nodes before linearized reading' (duration: 492.114641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:34:03.62588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:34:03.133638Z","time spent":"492.237644ms","remote":"127.0.0.1:55350","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-21T02:34:03.625994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:34:02.975732Z","time spent":"650.152226ms","remote":"127.0.0.1:55674","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-21T02:34:03.626069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.257495ms","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-21T02:34:03.626159Z","caller":"traceutil/trace.go:171","msg":"trace[2023195616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"492.394829ms","start":"2026-02-21T02:34:03.133743Z","end":"2026-02-21T02:34:03.626137Z","steps":["trace[2023195616] 'agreement among raft nodes before linearized reading' (duration: 492.112761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:34:03.626206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:34:03.133727Z","time spent":"492.470411ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-21T02:34:06.734741Z","caller":"traceutil/trace.go:171","msg":"trace[1101220032] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"174.080429ms","start":"2026-02-21T02:34:06.560632Z","end":"2026-02-21T02:34:06.734713Z","steps":["trace[1101220032] 'process raft request' (duration: 173.895514ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:34:09.96242Z","caller":"traceutil/trace.go:171","msg":"trace[1654833888] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"120.769412ms","start":"2026-02-21T02:34:09.84162Z","end":"2026-02-21T02:34:09.96239Z","steps":["trace[1654833888] 'process raft request' (duration: 120.609738ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:34:36.028109Z","caller":"traceutil/trace.go:171","msg":"trace[937052930] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"133.953148ms","start":"2026-02-21T02:34:35.894133Z","end":"2026-02-21T02:34:36.028086Z","steps":["trace[937052930] 'read index received' (duration: 133.752682ms)","trace[937052930] 'applied index is now lower than readState.Index' (duration: 199.726µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:34:36.028163Z","caller":"traceutil/trace.go:171","msg":"trace[1567071101] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"156.747259ms","start":"2026-02-21T02:34:35.871379Z","end":"2026-02-21T02:34:36.028126Z","steps":["trace[1567071101] 'process raft request' (duration: 156.559084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:34:36.02824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.084261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:34:36.028267Z","caller":"traceutil/trace.go:171","msg":"trace[399392304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1115; }","duration":"134.165134ms","start":"2026-02-21T02:34:35.894095Z","end":"2026-02-21T02:34:36.02826Z","steps":["trace[399392304] 'agreement among raft nodes before linearized reading' (duration: 134.074841ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:34:56.097287Z","caller":"traceutil/trace.go:171","msg":"trace[403731011] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1247; }","duration":"111.489336ms","start":"2026-02-21T02:34:55.985762Z","end":"2026-02-21T02:34:56.097251Z","steps":["trace[403731011] 'read index received' (duration: 55.582199ms)","trace[403731011] 'applied index is now lower than readState.Index' (duration: 55.905677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:34:56.097646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.854606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-21T02:34:56.097699Z","caller":"traceutil/trace.go:171","msg":"trace[132715002] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1196; }","duration":"111.953609ms","start":"2026-02-21T02:34:55.985728Z","end":"2026-02-21T02:34:56.097682Z","steps":["trace[132715002] 'agreement among raft nodes before linearized reading' (duration: 111.687062ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:34:56.098033Z","caller":"traceutil/trace.go:171","msg":"trace[985535461] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"136.305583ms","start":"2026-02-21T02:34:55.961711Z","end":"2026-02-21T02:34:56.098017Z","steps":["trace[985535461] 'process raft request' (duration: 97.026896ms)","trace[985535461] 'compare' (duration: 38.335081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:37:06.942188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.808785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:37:06.942338Z","caller":"traceutil/trace.go:171","msg":"trace[529917605] linearizableReadLoop","detail":"{readStateIndex:1834; appliedIndex:1833; }","duration":"275.233022ms","start":"2026-02-21T02:37:06.667078Z","end":"2026-02-21T02:37:06.942311Z","steps":["trace[529917605] 'read index received' (duration: 160.122529ms)","trace[529917605] 'applied index is now lower than readState.Index' (duration: 115.109233ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:37:06.942413Z","caller":"traceutil/trace.go:171","msg":"trace[642390236] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"286.541312ms","start":"2026-02-21T02:37:06.655812Z","end":"2026-02-21T02:37:06.942353Z","steps":["trace[642390236] 'process raft request' (duration: 171.446119ms)","trace[642390236] 'compare' (duration: 114.64606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:37:06.942543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.461778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-21T02:37:06.942582Z","caller":"traceutil/trace.go:171","msg":"trace[1962720671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1754; }","duration":"275.52914ms","start":"2026-02-21T02:37:06.667039Z","end":"2026-02-21T02:37:06.942568Z","steps":["trace[1962720671] 'agreement among raft nodes before linearized reading' (duration: 275.377806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:06.942704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.233836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-21T02:37:06.942819Z","caller":"traceutil/trace.go:171","msg":"trace[1681891607] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1754; }","duration":"248.400801ms","start":"2026-02-21T02:37:06.694387Z","end":"2026-02-21T02:37:06.942787Z","steps":["trace[1681891607] 'agreement among raft nodes before linearized reading' (duration: 248.052941ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:12.61053Z","caller":"traceutil/trace.go:171","msg":"trace[2016890985] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"133.006144ms","start":"2026-02-21T02:37:12.477459Z","end":"2026-02-21T02:37:12.610465Z","steps":["trace[2016890985] 'process raft request' (duration: 132.681505ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:15.342972Z","caller":"traceutil/trace.go:171","msg":"trace[1623282946] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"361.722414ms","start":"2026-02-21T02:37:14.981215Z","end":"2026-02-21T02:37:15.342938Z","steps":["trace[1623282946] 'read index received' (duration: 361.451927ms)","trace[1623282946] 'applied index is now lower than readState.Index' (duration: 269.217µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:37:15.343096Z","caller":"traceutil/trace.go:171","msg":"trace[721370139] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"419.072794ms","start":"2026-02-21T02:37:14.923979Z","end":"2026-02-21T02:37:15.343052Z","steps":["trace[721370139] 'process raft request' (duration: 418.775676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:15.343313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:14.923942Z","time spent":"419.27132ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:37:15.343316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.125445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-21T02:37:15.343378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.904978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:37:15.343399Z","caller":"traceutil/trace.go:171","msg":"trace[1706847538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1791; }","duration":"362.224158ms","start":"2026-02-21T02:37:14.981133Z","end":"2026-02-21T02:37:15.343358Z","steps":["trace[1706847538] 'agreement among raft nodes before linearized reading' (duration: 361.963211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:15.343312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.21599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-21T02:37:15.343434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:14.981106Z","time spent":"362.319431ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-21T02:37:15.343497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.798639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-21T02:37:15.343495Z","caller":"traceutil/trace.go:171","msg":"trace[1889471767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1791; }","duration":"361.479608ms","start":"2026-02-21T02:37:14.981979Z","end":"2026-02-21T02:37:15.343459Z","steps":["trace[1889471767] 'agreement among raft nodes before linearized reading' (duration: 361.142658ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:15.34353Z","caller":"traceutil/trace.go:171","msg":"trace[785534557] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1791; }","duration":"360.849079ms","start":"2026-02-21T02:37:14.98267Z","end":"2026-02-21T02:37:15.343519Z","steps":["trace[785534557] 'agreement among raft nodes before linearized reading' (duration: 360.769887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:15.343519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.445396ms","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-21T02:37:15.343421Z","caller":"traceutil/trace.go:171","msg":"trace[520460556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1791; }","duration":"242.983211ms","start":"2026-02-21T02:37:15.100426Z","end":"2026-02-21T02:37:15.343409Z","steps":["trace[520460556] 'agreement among raft nodes before linearized reading' (duration: 242.92822ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:15.343591Z","caller":"traceutil/trace.go:171","msg":"trace[138029902] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1791; }","duration":"356.534839ms","start":"2026-02-21T02:37:14.987038Z","end":"2026-02-21T02:37:15.343573Z","steps":["trace[138029902] 'agreement among raft nodes before linearized reading' (duration: 356.398065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:15.34362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:14.981946Z","time spent":"361.628942ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-21T02:37:15.343634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:14.982658Z","time spent":"360.955003ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-21T02:37:15.343636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:14.987026Z","time spent":"356.59808ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "}