{"level":"warn","ts":"2026-04-20T20:54:22.503476Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T20:54:22.5036Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-04-20T20:54:22.503708Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T20:54:22.503748Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-04-20T20:54:22.503782Z","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-04-20T20:54:22.504464Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-04-20T20:54:22.504615Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-04-20T20:54:22.50854Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.685759ms"} {"level":"info","ts":"2026-04-20T20:54:22.515511Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-04-20T20:54:22.51563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T20:54:22.515714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-04-20T20:54:22.515744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T20:54:22.515805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-04-20T20:54:22.5159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-04-20T20:54:22.525229Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T20:54:22.526642Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T20:54:22.52956Z","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-04-20T20:54:22.534578Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T20:54:22.538533Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T20:54:22.538858Z","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-04-20T20:54:22.538986Z","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-04-20T20:54:22.539053Z","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-04-20T20:54:22.540025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-04-20T20:54:22.540261Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-04-20T20:54:22.540248Z","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-04-20T20:54:22.540317Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-04-20T20:54:22.540413Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-04-20T20:54:22.540682Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T20:54:22.540758Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T20:54:22.917806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T20:54:22.917886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T20:54:22.917907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-04-20T20:54:22.917922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-04-20T20:54:22.917934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-04-20T20:54:22.917948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-04-20T20:54:22.917969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-04-20T20:54:22.921778Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:54:22.935711Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T20:54:22.935958Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:54:22.936425Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:54:22.936526Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T20:54:22.93659Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T20:54:22.938658Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-04-20T20:54:22.940202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T20:54:22.940518Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:54:22.940617Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:54:22.94065Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:54:28.003335Z","caller":"traceutil/trace.go:171","msg":"trace[2004019468] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"147.02166ms","start":"2026-04-20T20:54:27.856291Z","end":"2026-04-20T20:54:28.003313Z","steps":["trace[2004019468] 'process raft request' (duration: 146.866716ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:28.010772Z","caller":"traceutil/trace.go:171","msg":"trace[890221319] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"138.270054ms","start":"2026-04-20T20:54:27.872473Z","end":"2026-04-20T20:54:28.010743Z","steps":["trace[890221319] 'process raft request' (duration: 138.061728ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:28.010882Z","caller":"traceutil/trace.go:171","msg":"trace[1750265844] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"138.288154ms","start":"2026-04-20T20:54:27.872556Z","end":"2026-04-20T20:54:28.010844Z","steps":["trace[1750265844] 'process raft request' (duration: 138.064908ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:28.010969Z","caller":"traceutil/trace.go:171","msg":"trace[61724157] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:249; }","duration":"119.027514ms","start":"2026-04-20T20:54:27.891922Z","end":"2026-04-20T20:54:28.01095Z","steps":["trace[61724157] 'read index received' (duration: 111.343036ms)","trace[61724157] 'applied index is now lower than readState.Index' (duration: 7.683498ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:28.010975Z","caller":"traceutil/trace.go:171","msg":"trace[1839902330] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"137.160394ms","start":"2026-04-20T20:54:27.873796Z","end":"2026-04-20T20:54:28.010957Z","steps":["trace[1839902330] 'process raft request' (duration: 137.00616ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:28.011079Z","caller":"traceutil/trace.go:171","msg":"trace[23999941] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"137.088182ms","start":"2026-04-20T20:54:27.873979Z","end":"2026-04-20T20:54:28.011067Z","steps":["trace[23999941] 'process raft request' (duration: 136.878447ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:28.011114Z","caller":"traceutil/trace.go:171","msg":"trace[1978298308] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"138.50843ms","start":"2026-04-20T20:54:27.872596Z","end":"2026-04-20T20:54:28.011105Z","steps":["trace[1978298308] 'process raft request' (duration: 138.1216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:28.011261Z","caller":"traceutil/trace.go:171","msg":"trace[648835703] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"138.424908ms","start":"2026-04-20T20:54:27.87282Z","end":"2026-04-20T20:54:28.011245Z","steps":["trace[648835703] 'process raft request' (duration: 137.948565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:28.011465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.525368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-20T20:54:28.011597Z","caller":"traceutil/trace.go:171","msg":"trace[519556616] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:249; }","duration":"119.703273ms","start":"2026-04-20T20:54:27.891874Z","end":"2026-04-20T20:54:28.011577Z","steps":["trace[519556616] 'agreement among raft nodes before linearized reading' (duration: 119.520848ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:33.909487Z","caller":"traceutil/trace.go:171","msg":"trace[239628440] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"104.216655ms","start":"2026-04-20T20:54:33.80522Z","end":"2026-04-20T20:54:33.909437Z","steps":["trace[239628440] 'process raft request' (duration: 103.938927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:34.162183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.122768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:54:34.162424Z","caller":"traceutil/trace.go:171","msg":"trace[1770027271] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"282.363275ms","start":"2026-04-20T20:54:33.880025Z","end":"2026-04-20T20:54:34.162388Z","steps":["trace[1770027271] 'process raft request' (duration: 89.093446ms)","trace[1770027271] 'compare' (duration: 191.923002ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:34.344689Z","caller":"traceutil/trace.go:171","msg":"trace[705117471] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"177.988517ms","start":"2026-04-20T20:54:34.166656Z","end":"2026-04-20T20:54:34.344645Z","steps":["trace[705117471] 'process raft request' (duration: 146.019964ms)","trace[705117471] 'compare' (duration: 30.555764ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:34.344815Z","caller":"traceutil/trace.go:171","msg":"trace[349737851] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"144.902792ms","start":"2026-04-20T20:54:34.199525Z","end":"2026-04-20T20:54:34.344428Z","steps":["trace[349737851] 'read index received' (duration: 113.186786ms)","trace[349737851] 'applied index is now lower than readState.Index' (duration: 31.713826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:34.34591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.370712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T20:54:34.346002Z","caller":"traceutil/trace.go:171","msg":"trace[633529929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"146.469235ms","start":"2026-04-20T20:54:34.199508Z","end":"2026-04-20T20:54:34.345977Z","steps":["trace[633529929] 'agreement among raft nodes before linearized reading' (duration: 144.983145ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:38.605638Z","caller":"traceutil/trace.go:171","msg":"trace[89686343] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"224.109522ms","start":"2026-04-20T20:54:38.381499Z","end":"2026-04-20T20:54:38.605608Z","steps":["trace[89686343] 'process raft request' (duration: 219.776815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:45.12873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010308002,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T20:54:45.155395Z","caller":"traceutil/trace.go:171","msg":"trace[1996747128] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"766.072295ms","start":"2026-04-20T20:54:44.389299Z","end":"2026-04-20T20:54:45.155371Z","steps":["trace[1996747128] 'process raft request' (duration: 765.943471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:45.15568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:44.389268Z","time spent":"766.188838ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:54:45.680231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.091288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:54:45.680422Z","caller":"traceutil/trace.go:171","msg":"trace[1922651649] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"1.052499959s","start":"2026-04-20T20:54:44.627901Z","end":"2026-04-20T20:54:45.680401Z","steps":["trace[1922651649] 'read index received' (duration: 527.418611ms)","trace[1922651649] 'applied index is now lower than readState.Index' (duration: 525.079948ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:45.680442Z","caller":"traceutil/trace.go:171","msg":"trace[590537668] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"795.917671ms","start":"2026-04-20T20:54:44.884482Z","end":"2026-04-20T20:54:45.680399Z","steps":["trace[590537668] 'process raft request' (duration: 423.410842ms)","trace[590537668] 'compare' (duration: 371.690287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:45.680504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052604343s","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-04-20T20:54:45.680563Z","caller":"traceutil/trace.go:171","msg":"trace[631169072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"1.052732666s","start":"2026-04-20T20:54:44.627817Z","end":"2026-04-20T20:54:45.68055Z","steps":["trace[631169072] 'agreement among raft nodes before linearized reading' (duration: 1.052633282s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:45.68059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:44.627768Z","time spent":"1.052817479s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T20:54:45.680635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:44.884452Z","time spent":"796.084186ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:54:45.680669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.184695ms","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-04-20T20:54:45.680768Z","caller":"traceutil/trace.go:171","msg":"trace[1855117192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"627.337759ms","start":"2026-04-20T20:54:45.053409Z","end":"2026-04-20T20:54:45.680747Z","steps":["trace[1855117192] 'agreement among raft nodes before linearized reading' (duration: 627.140784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:45.680819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:45.053388Z","time spent":"627.421412ms","remote":"127.0.0.1:53044","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-04-20T20:54:54.792855Z","caller":"traceutil/trace.go:171","msg":"trace[428394425] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"373.263679ms","start":"2026-04-20T20:54:54.419561Z","end":"2026-04-20T20:54:54.792824Z","steps":["trace[428394425] 'process raft request' (duration: 368.651175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:54.792968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:54.419535Z","time spent":"373.381932ms","remote":"127.0.0.1:52862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:57.231365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010308149,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T20:54:57.240043Z","caller":"traceutil/trace.go:171","msg":"trace[1839873073] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"776.501657ms","start":"2026-04-20T20:54:56.463525Z","end":"2026-04-20T20:54:57.240027Z","steps":["trace[1839873073] 'process raft request' (duration: 776.387914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:57.240122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.463501Z","time spent":"776.575269ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:57.414251Z","caller":"traceutil/trace.go:171","msg":"trace[624463688] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"948.162192ms","start":"2026-04-20T20:54:56.465997Z","end":"2026-04-20T20:54:57.414159Z","steps":["trace[624463688] 'process raft request' (duration: 947.884195ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.414332Z","caller":"traceutil/trace.go:171","msg":"trace[1483887161] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"936.253151ms","start":"2026-04-20T20:54:56.478069Z","end":"2026-04-20T20:54:57.414322Z","steps":["trace[1483887161] 'process raft request' (duration: 936.022555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:57.414412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.46598Z","time spent":"948.357717ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:57.41442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.478033Z","time spent":"936.332473ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:57.414265Z","caller":"traceutil/trace.go:171","msg":"trace[1945848071] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"944.364779ms","start":"2026-04-20T20:54:56.469865Z","end":"2026-04-20T20:54:57.41423Z","steps":["trace[1945848071] 'process raft request' (duration: 944.169734ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.414619Z","caller":"traceutil/trace.go:171","msg":"trace[1323014821] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"936.295302ms","start":"2026-04-20T20:54:56.478287Z","end":"2026-04-20T20:54:57.414582Z","steps":["trace[1323014821] 'process raft request' (duration: 935.993524ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.414666Z","caller":"traceutil/trace.go:171","msg":"trace[2065696672] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"936.462326ms","start":"2026-04-20T20:54:56.478173Z","end":"2026-04-20T20:54:57.414635Z","steps":["trace[2065696672] 'process raft request' (duration: 936.037185ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.414713Z","caller":"traceutil/trace.go:171","msg":"trace[299982007] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"935.407598ms","start":"2026-04-20T20:54:56.479179Z","end":"2026-04-20T20:54:57.414587Z","steps":["trace[299982007] 'process raft request' (duration: 935.142641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:57.414752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.478166Z","time spent":"936.547978ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:57.414768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.47828Z","time spent":"936.420905ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:57.414745Z","caller":"traceutil/trace.go:171","msg":"trace[865797435] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:494; }","duration":"684.461872ms","start":"2026-04-20T20:54:56.730265Z","end":"2026-04-20T20:54:57.414727Z","steps":["trace[865797435] 'read index received' (duration: 509.681303ms)","trace[865797435] 'applied index is now lower than readState.Index' (duration: 174.779569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:57.414892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.622846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T20:54:57.414908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.479171Z","time spent":"935.674916ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:57.414943Z","caller":"traceutil/trace.go:171","msg":"trace[1914347600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"684.712188ms","start":"2026-04-20T20:54:56.73022Z","end":"2026-04-20T20:54:57.414932Z","steps":["trace[1914347600] 'agreement among raft nodes before linearized reading' (duration: 684.561254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:57.414981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.730196Z","time spent":"684.77776ms","remote":"127.0.0.1:52736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T20:54:57.415007Z","caller":"traceutil/trace.go:171","msg":"trace[1746186620] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"926.193839ms","start":"2026-04-20T20:54:56.4888Z","end":"2026-04-20T20:54:57.414994Z","steps":["trace[1746186620] 'process raft request' (duration: 925.745207ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.41506Z","caller":"traceutil/trace.go:171","msg":"trace[1142464144] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"935.203372ms","start":"2026-04-20T20:54:56.479845Z","end":"2026-04-20T20:54:57.415048Z","steps":["trace[1142464144] 'process raft request' (duration: 934.540745ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.415107Z","caller":"traceutil/trace.go:171","msg":"trace[4654878] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"929.803757ms","start":"2026-04-20T20:54:56.485296Z","end":"2026-04-20T20:54:57.415099Z","steps":["trace[4654878] 'process raft request' (duration: 929.162129ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.415124Z","caller":"traceutil/trace.go:171","msg":"trace[7283965] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"894.105683ms","start":"2026-04-20T20:54:56.520938Z","end":"2026-04-20T20:54:57.415044Z","steps":["trace[7283965] 'process raft request' (duration: 893.663481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:57.41514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.479823Z","time spent":"935.275944ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:57.415163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.485286Z","time spent":"929.858779ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:57.415175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.520911Z","time spent":"894.239436ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:57.41519Z","caller":"traceutil/trace.go:171","msg":"trace[919312087] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"769.494688ms","start":"2026-04-20T20:54:56.64568Z","end":"2026-04-20T20:54:57.415175Z","steps":["trace[919312087] 'process raft request' (duration: 768.990554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:57.415074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.488783Z","time spent":"926.26093ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:57.41526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.645656Z","time spent":"769.570679ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:57.415431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:56.469854Z","time spent":"944.698209ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:57.425379Z","caller":"traceutil/trace.go:171","msg":"trace[1742660979] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"176.514897ms","start":"2026-04-20T20:54:57.247812Z","end":"2026-04-20T20:54:57.424327Z","steps":["trace[1742660979] 'process raft request' (duration: 176.394604ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.430292Z","caller":"traceutil/trace.go:171","msg":"trace[169997373] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"184.484952ms","start":"2026-04-20T20:54:57.245793Z","end":"2026-04-20T20:54:57.430278Z","steps":["trace[169997373] 'process raft request' (duration: 178.344397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:57.430439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.155707ms","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-04-20T20:54:57.430463Z","caller":"traceutil/trace.go:171","msg":"trace[1477347263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"219.220349ms","start":"2026-04-20T20:54:57.211236Z","end":"2026-04-20T20:54:57.430457Z","steps":["trace[1477347263] 'agreement among raft nodes before linearized reading' (duration: 219.132097ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:57.430569Z","caller":"traceutil/trace.go:171","msg":"trace[785367804] transaction","detail":"{read_only:false; number_of_response:1; response_revision:489; }","duration":"182.56855ms","start":"2026-04-20T20:54:57.24799Z","end":"2026-04-20T20:54:57.430558Z","steps":["trace[785367804] 'process raft request' (duration: 176.25695ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:03.97233Z","caller":"traceutil/trace.go:171","msg":"trace[398479935] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"135.342765ms","start":"2026-04-20T20:55:03.836955Z","end":"2026-04-20T20:55:03.972297Z","steps":["trace[398479935] 'process raft request' (duration: 115.392206ms)","trace[398479935] 'compare' (duration: 19.688682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:55:05.207938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.334719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:55:05.208031Z","caller":"traceutil/trace.go:171","msg":"trace[4010857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"477.478283ms","start":"2026-04-20T20:55:04.730535Z","end":"2026-04-20T20:55:05.208013Z","steps":["trace[4010857] 'range keys from in-memory index tree' (duration: 477.210575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:05.208076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:04.730514Z","time spent":"477.553894ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T20:55:05.208147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.375558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6rqwm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T20:55:05.20827Z","caller":"traceutil/trace.go:171","msg":"trace[957740670] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6rqwm; range_end:; response_count:1; response_revision:593; }","duration":"314.517152ms","start":"2026-04-20T20:55:04.893727Z","end":"2026-04-20T20:55:05.208244Z","steps":["trace[957740670] 'range keys from in-memory index tree' (duration: 314.105211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:05.208329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:04.893654Z","time spent":"314.660366ms","remote":"127.0.0.1:52956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-6rqwm\" "} {"level":"info","ts":"2026-04-20T20:55:05.794746Z","caller":"traceutil/trace.go:171","msg":"trace[1676798030] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"214.292407ms","start":"2026-04-20T20:55:05.580431Z","end":"2026-04-20T20:55:05.794723Z","steps":["trace[1676798030] 'read index received' (duration: 213.919366ms)","trace[1676798030] 'applied index is now lower than readState.Index' (duration: 372.141µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:55:05.79479Z","caller":"traceutil/trace.go:171","msg":"trace[1728970569] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"568.433249ms","start":"2026-04-20T20:55:05.226339Z","end":"2026-04-20T20:55:05.794772Z","steps":["trace[1728970569] 'process raft request' (duration: 568.245024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:05.795024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:05.226315Z","time spent":"568.633524ms","remote":"127.0.0.1:52956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:55:05.794999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.567673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T20:55:05.795094Z","caller":"traceutil/trace.go:171","msg":"trace[1085667982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"214.712948ms","start":"2026-04-20T20:55:05.580368Z","end":"2026-04-20T20:55:05.795081Z","steps":["trace[1085667982] 'agreement among raft nodes before linearized reading' (duration: 214.524633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:06.730002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010308418,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T20:55:07.230387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010308418,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T20:55:07.248377Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.362470421s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T20:55:07.248843Z","caller":"traceutil/trace.go:171","msg":"trace[621441385] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.40507841s","start":"2026-04-20T20:55:05.843733Z","end":"2026-04-20T20:55:07.248811Z","steps":["trace[621441385] 'process raft request' (duration: 1.404876685s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:07.249038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:05.843712Z","time spent":"1.405203264s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:55:07.495532Z","caller":"traceutil/trace.go:171","msg":"trace[686038813] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:619; }","duration":"1.266033676s","start":"2026-04-20T20:55:06.229475Z","end":"2026-04-20T20:55:07.495508Z","steps":["trace[686038813] 'read index received' (duration: 1.019217551s)","trace[686038813] 'applied index is now lower than readState.Index' (duration: 246.815095ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:55:07.495653Z","caller":"traceutil/trace.go:171","msg":"trace[1861442054] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.334978659s","start":"2026-04-20T20:55:06.160665Z","end":"2026-04-20T20:55:07.495643Z","steps":["trace[1861442054] 'process raft request' (duration: 1.334791354s)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:07.495664Z","caller":"traceutil/trace.go:171","msg":"trace[1235783088] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.649520521s","start":"2026-04-20T20:55:05.846117Z","end":"2026-04-20T20:55:07.495638Z","steps":["trace[1235783088] 'process raft request' (duration: 1.649258494s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:07.495751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:06.160639Z","time spent":"1.33504012s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:55:07.49578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:05.846075Z","time spent":"1.649658265s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:55:07.495924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.101013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:55:07.495959Z","caller":"traceutil/trace.go:171","msg":"trace[2133969424] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.651778892s","start":"2026-04-20T20:55:05.844158Z","end":"2026-04-20T20:55:07.495937Z","steps":["trace[2133969424] 'process raft request' (duration: 1.592603835s)","trace[2133969424] 'compare' (duration: 58.239793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:55:07.495984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266515239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T20:55:07.496005Z","caller":"traceutil/trace.go:171","msg":"trace[148350919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"764.215216ms","start":"2026-04-20T20:55:06.731771Z","end":"2026-04-20T20:55:07.495986Z","steps":["trace[148350919] 'agreement among raft nodes before linearized reading' (duration: 763.974999ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:07.496027Z","caller":"traceutil/trace.go:171","msg":"trace[136723665] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:599; }","duration":"1.266584931s","start":"2026-04-20T20:55:06.229427Z","end":"2026-04-20T20:55:07.496012Z","steps":["trace[136723665] 'agreement among raft nodes before linearized reading' (duration: 1.2665199s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:07.496055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:05.844149Z","time spent":"1.651849014s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:55:07.49606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:06.229404Z","time spent":"1.266649622s","remote":"127.0.0.1:52768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-04-20T20:55:07.496055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:06.731752Z","time spent":"764.292158ms","remote":"127.0.0.1:52736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T20:55:09.368546Z","caller":"traceutil/trace.go:171","msg":"trace[856220928] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"340.533385ms","start":"2026-04-20T20:55:09.027974Z","end":"2026-04-20T20:55:09.368508Z","steps":["trace[856220928] 'process raft request' (duration: 334.540363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:09.368699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:09.02794Z","time spent":"340.677839ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:55:09.375276Z","caller":"traceutil/trace.go:171","msg":"trace[691197007] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"121.050259ms","start":"2026-04-20T20:55:09.254205Z","end":"2026-04-20T20:55:09.375255Z","steps":["trace[691197007] 'read index received' (duration: 108.330695ms)","trace[691197007] 'applied index is now lower than readState.Index' (duration: 12.715954ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:55:09.375333Z","caller":"traceutil/trace.go:171","msg":"trace[1035341251] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"184.969245ms","start":"2026-04-20T20:55:09.190323Z","end":"2026-04-20T20:55:09.375292Z","steps":["trace[1035341251] 'process raft request' (duration: 184.80843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:09.375476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.262504ms","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-04-20T20:55:09.375507Z","caller":"traceutil/trace.go:171","msg":"trace[1074300942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"121.348376ms","start":"2026-04-20T20:55:09.254149Z","end":"2026-04-20T20:55:09.375498Z","steps":["trace[1074300942] 'agreement among raft nodes before linearized reading' (duration: 121.191662ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:13.059808Z","caller":"traceutil/trace.go:171","msg":"trace[291483948] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"112.177479ms","start":"2026-04-20T20:55:12.947605Z","end":"2026-04-20T20:55:13.059782Z","steps":["trace[291483948] 'process raft request' (duration: 32.859718ms)","trace[291483948] 'compare' (duration: 79.206048ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:55:13.066579Z","caller":"traceutil/trace.go:171","msg":"trace[1176834969] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"109.63934ms","start":"2026-04-20T20:55:12.95689Z","end":"2026-04-20T20:55:13.06653Z","steps":["trace[1176834969] 'process raft request' (duration: 109.488026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:19.882534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.865527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:55:19.882646Z","caller":"traceutil/trace.go:171","msg":"trace[1016681192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"152.037002ms","start":"2026-04-20T20:55:19.730586Z","end":"2026-04-20T20:55:19.882623Z","steps":["trace[1016681192] 'range keys from in-memory index tree' (duration: 151.750195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:23.303988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.098738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:55:23.304495Z","caller":"traceutil/trace.go:171","msg":"trace[1470873330] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.309141734s","start":"2026-04-20T20:55:21.995326Z","end":"2026-04-20T20:55:23.304468Z","steps":["trace[1470873330] 'process raft request' (duration: 735.44966ms)","trace[1470873330] 'compare' (duration: 572.803921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:55:23.304624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:21.995309Z","time spent":"1.309256007s","remote":"127.0.0.1:52956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:55:23.306716Z","caller":"traceutil/trace.go:171","msg":"trace[1611167036] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:776; }","duration":"380.530571ms","start":"2026-04-20T20:55:22.926159Z","end":"2026-04-20T20:55:23.30669Z","steps":["trace[1611167036] 'read index received' (duration: 301.856765ms)","trace[1611167036] 'applied index is now lower than readState.Index' (duration: 78.672906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:55:23.306841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.687716ms","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-04-20T20:55:23.306862Z","caller":"traceutil/trace.go:171","msg":"trace[381150945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"380.766317ms","start":"2026-04-20T20:55:22.92609Z","end":"2026-04-20T20:55:23.306856Z","steps":["trace[381150945] 'agreement among raft nodes before linearized reading' (duration: 380.660955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:23.306942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:22.926061Z","time spent":"380.87129ms","remote":"127.0.0.1:53044","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-04-20T20:56:00.015282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.289013ms","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-04-20T20:56:00.01537Z","caller":"traceutil/trace.go:171","msg":"trace[1084083058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"167.436866ms","start":"2026-04-20T20:55:59.847914Z","end":"2026-04-20T20:56:00.015351Z","steps":["trace[1084083058] 'range keys from in-memory index tree' (duration: 167.094707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:00.388474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010309209,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T20:56:00.852797Z","caller":"traceutil/trace.go:171","msg":"trace[953892708] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"984.234793ms","start":"2026-04-20T20:55:59.868524Z","end":"2026-04-20T20:56:00.852759Z","steps":["trace[953892708] 'process raft request' (duration: 984.061708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:00.85298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:59.868504Z","time spent":"984.373227ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:00.912433Z","caller":"traceutil/trace.go:171","msg":"trace[478701851] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"892.666559ms","start":"2026-04-20T20:56:00.01975Z","end":"2026-04-20T20:56:00.912416Z","steps":["trace[478701851] 'process raft request' (duration: 892.540246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:00.912539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:00.019722Z","time spent":"892.760832ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:00.912644Z","caller":"traceutil/trace.go:171","msg":"trace[1705386992] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"1.025096276s","start":"2026-04-20T20:55:59.887514Z","end":"2026-04-20T20:56:00.912611Z","steps":["trace[1705386992] 'read index received' (duration: 965.281015ms)","trace[1705386992] 'applied index is now lower than readState.Index' (duration: 59.812431ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:00.912697Z","caller":"traceutil/trace.go:171","msg":"trace[1234572903] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"528.169108ms","start":"2026-04-20T20:56:00.384519Z","end":"2026-04-20T20:56:00.912688Z","steps":["trace[1234572903] 'process raft request' (duration: 527.86245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:00.912741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:00.384494Z","time spent":"528.22441ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:56:00.912844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025329712s","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-04-20T20:56:00.912911Z","caller":"traceutil/trace.go:171","msg":"trace[202074221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"1.025418175s","start":"2026-04-20T20:55:59.887483Z","end":"2026-04-20T20:56:00.912901Z","steps":["trace[202074221] 'agreement among raft nodes before linearized reading' (duration: 1.025270611s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:00.912921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.791522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T20:56:00.912943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:59.887466Z","time spent":"1.025470545s","remote":"127.0.0.1:53044","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":"info","ts":"2026-04-20T20:56:00.912955Z","caller":"traceutil/trace.go:171","msg":"trace[981896743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"184.868784ms","start":"2026-04-20T20:56:00.728078Z","end":"2026-04-20T20:56:00.912947Z","steps":["trace[981896743] 'agreement among raft nodes before linearized reading' (duration: 184.815292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:00.913125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.494926ms","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-04-20T20:56:00.913207Z","caller":"traceutil/trace.go:171","msg":"trace[1589633247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"694.589638ms","start":"2026-04-20T20:56:00.218572Z","end":"2026-04-20T20:56:00.913162Z","steps":["trace[1589633247] 'agreement among raft nodes before linearized reading' (duration: 694.294151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:00.913251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:00.218544Z","time spent":"694.69818ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T20:56:02.197625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010309218,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T20:56:02.653469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572280203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T20:56:02.653578Z","caller":"traceutil/trace.go:171","msg":"trace[762903169] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:977; }","duration":"1.572444867s","start":"2026-04-20T20:56:01.081109Z","end":"2026-04-20T20:56:02.653554Z","steps":["trace[762903169] 'count revisions from in-memory index tree' (duration: 1.572121989s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:02.653652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:01.081088Z","time spent":"1.57253962s","remote":"127.0.0.1:53218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:56:02.720204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.909097ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T20:56:02.720361Z","caller":"traceutil/trace.go:171","msg":"trace[2081106917] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"1.022783016s","start":"2026-04-20T20:56:01.697537Z","end":"2026-04-20T20:56:02.72032Z","steps":["trace[2081106917] 'read index received' (duration: 530.831349ms)","trace[2081106917] 'applied index is now lower than readState.Index' (duration: 491.949946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:56:02.72054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.029955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:02.720569Z","caller":"traceutil/trace.go:171","msg":"trace[1000906248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"990.098897ms","start":"2026-04-20T20:56:01.730459Z","end":"2026-04-20T20:56:02.720558Z","steps":["trace[1000906248] 'agreement among raft nodes before linearized reading' (duration: 990.014325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:02.720606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:01.730444Z","time spent":"990.154738ms","remote":"127.0.0.1:52736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T20:56:02.720656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023093883s","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-04-20T20:56:02.720744Z","caller":"traceutil/trace.go:171","msg":"trace[2002253061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"1.023236037s","start":"2026-04-20T20:56:01.697486Z","end":"2026-04-20T20:56:02.720722Z","steps":["trace[2002253061] 'agreement among raft nodes before linearized reading' (duration: 1.02295868s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:02.720795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:01.697466Z","time spent":"1.023319809s","remote":"127.0.0.1:53044","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-04-20T20:56:02.720883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.333301ms","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-04-20T20:56:02.720951Z","caller":"traceutil/trace.go:171","msg":"trace[1679957495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"985.427224ms","start":"2026-04-20T20:56:01.735507Z","end":"2026-04-20T20:56:02.720934Z","steps":["trace[1679957495] 'agreement among raft nodes before linearized reading' (duration: 985.205748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:02.720994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:01.7355Z","time spent":"985.485236ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T20:56:03.23186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010309222,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T20:56:03.414774Z","caller":"traceutil/trace.go:171","msg":"trace[253100644] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"689.642237ms","start":"2026-04-20T20:56:02.725106Z","end":"2026-04-20T20:56:03.414749Z","steps":["trace[253100644] 'process raft request' (duration: 689.493893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:03.415258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:02.725084Z","time spent":"689.844143ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:03.427359Z","caller":"traceutil/trace.go:171","msg":"trace[276471354] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1018; }","duration":"695.78992ms","start":"2026-04-20T20:56:02.731546Z","end":"2026-04-20T20:56:03.427336Z","steps":["trace[276471354] 'read index received' (duration: 683.304922ms)","trace[276471354] 'applied index is now lower than readState.Index' (duration: 12.483698ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:03.427524Z","caller":"traceutil/trace.go:171","msg":"trace[828735378] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"506.11398ms","start":"2026-04-20T20:56:02.921395Z","end":"2026-04-20T20:56:03.427509Z","steps":["trace[828735378] 'process raft request' (duration: 505.898484ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:03.427533Z","caller":"traceutil/trace.go:171","msg":"trace[396789398] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"700.825412ms","start":"2026-04-20T20:56:02.726661Z","end":"2026-04-20T20:56:03.427487Z","steps":["trace[396789398] 'process raft request' (duration: 700.505913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:03.427613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.812063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:03.427676Z","caller":"traceutil/trace.go:171","msg":"trace[2040448327] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:980; }","duration":"510.930786ms","start":"2026-04-20T20:56:02.916726Z","end":"2026-04-20T20:56:03.427657Z","steps":["trace[2040448327] 'agreement among raft nodes before linearized reading' (duration: 510.814553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:03.427719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:02.916699Z","time spent":"511.010768ms","remote":"127.0.0.1:53180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:56:03.427728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.431621ms","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-04-20T20:56:03.427779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:02.726638Z","time spent":"701.013207ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:03.427793Z","caller":"traceutil/trace.go:171","msg":"trace[5602541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"449.541004ms","start":"2026-04-20T20:56:02.978237Z","end":"2026-04-20T20:56:03.427778Z","steps":["trace[5602541] 'agreement among raft nodes before linearized reading' (duration: 449.328298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:03.427837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:02.978225Z","time spent":"449.604776ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T20:56:03.427868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.964205ms","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-04-20T20:56:03.4279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.464274ms","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-04-20T20:56:03.427935Z","caller":"traceutil/trace.go:171","msg":"trace[1117358582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:980; }","duration":"450.111078ms","start":"2026-04-20T20:56:02.977808Z","end":"2026-04-20T20:56:03.427919Z","steps":["trace[1117358582] 'agreement among raft nodes before linearized reading' (duration: 449.906173ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:03.42795Z","caller":"traceutil/trace.go:171","msg":"trace[1258237482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"510.552647ms","start":"2026-04-20T20:56:02.917382Z","end":"2026-04-20T20:56:03.427935Z","steps":["trace[1258237482] 'agreement among raft nodes before linearized reading' (duration: 510.404393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:03.427964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:02.977796Z","time spent":"450.16243ms","remote":"127.0.0.1:53044","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-04-20T20:56:03.427982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:02.917363Z","time spent":"510.612048ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T20:56:03.428092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.54654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:03.428122Z","caller":"traceutil/trace.go:171","msg":"trace[295645817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"696.593221ms","start":"2026-04-20T20:56:02.731518Z","end":"2026-04-20T20:56:03.428111Z","steps":["trace[295645817] 'agreement among raft nodes before linearized reading' (duration: 696.54316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:03.428147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:02.731509Z","time spent":"696.633302ms","remote":"127.0.0.1:52736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T20:56:03.429112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:02.921377Z","time spent":"506.215632ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:09.807103Z","caller":"traceutil/trace.go:171","msg":"trace[1771561616] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"183.782366ms","start":"2026-04-20T20:56:09.623296Z","end":"2026-04-20T20:56:09.807078Z","steps":["trace[1771561616] 'process raft request' (duration: 177.027318ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:09.807397Z","caller":"traceutil/trace.go:171","msg":"trace[970875220] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"184.066473ms","start":"2026-04-20T20:56:09.623313Z","end":"2026-04-20T20:56:09.80738Z","steps":["trace[970875220] 'process raft request' (duration: 183.729154ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:09.807409Z","caller":"traceutil/trace.go:171","msg":"trace[876695577] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"184.039672ms","start":"2026-04-20T20:56:09.623351Z","end":"2026-04-20T20:56:09.80739Z","steps":["trace[876695577] 'process raft request' (duration: 183.929309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:27.301442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.295942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T20:56:27.30149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.922044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:27.301589Z","caller":"traceutil/trace.go:171","msg":"trace[640834] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1052; }","duration":"370.050977ms","start":"2026-04-20T20:56:26.931519Z","end":"2026-04-20T20:56:27.30157Z","steps":["trace[640834] 'count revisions from in-memory index tree' (duration: 369.78303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:27.301662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:26.931462Z","time spent":"370.18425ms","remote":"127.0.0.1:37326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:56:27.30185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416353442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T20:56:27.301892Z","caller":"traceutil/trace.go:171","msg":"trace[1448159110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"1.416431384s","start":"2026-04-20T20:56:25.885448Z","end":"2026-04-20T20:56:27.301879Z","steps":["trace[1448159110] 'range keys from in-memory index tree' (duration: 1.416202338s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:27.301949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:25.885429Z","time spent":"1.416506735s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T20:56:27.302198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.020329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:27.301536Z","caller":"traceutil/trace.go:171","msg":"trace[237759884] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1052; }","duration":"956.453425ms","start":"2026-04-20T20:56:26.345063Z","end":"2026-04-20T20:56:27.301516Z","steps":["trace[237759884] 'count revisions from in-memory index tree' (duration: 956.206239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:27.302297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069044055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T20:56:27.302346Z","caller":"traceutil/trace.go:171","msg":"trace[641146814] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1052; }","duration":"1.069127617s","start":"2026-04-20T20:56:26.233205Z","end":"2026-04-20T20:56:27.302332Z","steps":["trace[641146814] 'range keys from in-memory index tree' (duration: 1.068919572s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:27.302352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:26.345043Z","time spent":"957.274126ms","remote":"127.0.0.1:52862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:56:27.30239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:26.233184Z","time spent":"1.069189109s","remote":"127.0.0.1:52768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-04-20T20:56:27.302432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407218134s","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-04-20T20:56:27.302486Z","caller":"traceutil/trace.go:171","msg":"trace[694371295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1052; }","duration":"1.407298696s","start":"2026-04-20T20:56:25.895176Z","end":"2026-04-20T20:56:27.302475Z","steps":["trace[694371295] 'range keys from in-memory index tree' (duration: 1.407105251s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:27.302513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:25.895161Z","time spent":"1.407344188s","remote":"127.0.0.1:53044","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":"info","ts":"2026-04-20T20:56:27.302242Z","caller":"traceutil/trace.go:171","msg":"trace[1816008351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"571.101781ms","start":"2026-04-20T20:56:26.731131Z","end":"2026-04-20T20:56:27.302233Z","steps":["trace[1816008351] 'range keys from in-memory index tree' (duration: 570.919887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:27.302631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:26.73111Z","time spent":"571.510491ms","remote":"127.0.0.1:52736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T20:56:27.302264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41437237s","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-04-20T20:56:27.30287Z","caller":"traceutil/trace.go:171","msg":"trace[1946540519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"1.415000126s","start":"2026-04-20T20:56:25.887851Z","end":"2026-04-20T20:56:27.302851Z","steps":["trace[1946540519] 'range keys from in-memory index tree' (duration: 1.414196736s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:27.302909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:25.88784Z","time spent":"1.415055128s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T20:56:27.468169Z","caller":"traceutil/trace.go:171","msg":"trace[95889643] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"159.952958ms","start":"2026-04-20T20:56:27.308173Z","end":"2026-04-20T20:56:27.468126Z","steps":["trace[95889643] 'process raft request' (duration: 159.910347ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:27.468252Z","caller":"traceutil/trace.go:171","msg":"trace[1167895687] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"160.502562ms","start":"2026-04-20T20:56:27.307742Z","end":"2026-04-20T20:56:27.468245Z","steps":["trace[1167895687] 'process raft request' (duration: 160.285676ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:27.468186Z","caller":"traceutil/trace.go:171","msg":"trace[547626237] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"160.949464ms","start":"2026-04-20T20:56:27.307202Z","end":"2026-04-20T20:56:27.468152Z","steps":["trace[547626237] 'process raft request' (duration: 160.664396ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:27.493982Z","caller":"traceutil/trace.go:171","msg":"trace[1995966058] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"121.555833ms","start":"2026-04-20T20:56:27.372397Z","end":"2026-04-20T20:56:27.493952Z","steps":["trace[1995966058] 'process raft request' (duration: 121.393008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:29.972618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010309438,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T20:56:30.255682Z","caller":"traceutil/trace.go:171","msg":"trace[1148948863] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"849.714336ms","start":"2026-04-20T20:56:29.405949Z","end":"2026-04-20T20:56:30.255663Z","steps":["trace[1148948863] 'process raft request' (duration: 849.550942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:30.255906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:29.405925Z","time spent":"849.811899ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:30.800864Z","caller":"traceutil/trace.go:171","msg":"trace[915670918] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1104; }","duration":"1.329037307s","start":"2026-04-20T20:56:29.471802Z","end":"2026-04-20T20:56:30.800839Z","steps":["trace[915670918] 'read index received' (duration: 783.759385ms)","trace[915670918] 'applied index is now lower than readState.Index' (duration: 545.276912ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:30.800997Z","caller":"traceutil/trace.go:171","msg":"trace[306946807] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.086893497s","start":"2026-04-20T20:56:29.714089Z","end":"2026-04-20T20:56:30.800983Z","steps":["trace[306946807] 'process raft request' (duration: 1.086561389s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:30.801105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:29.714074Z","time spent":"1.086948448s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:56:30.801191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070562034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T20:56:30.801265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329457477s","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-04-20T20:56:30.801347Z","caller":"traceutil/trace.go:171","msg":"trace[512554691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"1.3295806s","start":"2026-04-20T20:56:29.471743Z","end":"2026-04-20T20:56:30.801323Z","steps":["trace[512554691] 'agreement among raft nodes before linearized reading' (duration: 1.329385255s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:30.801404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:29.471725Z","time spent":"1.329665974s","remote":"127.0.0.1:53044","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-04-20T20:56:30.801513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099093363s","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-04-20T20:56:30.801544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327880797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T20:56:30.801582Z","caller":"traceutil/trace.go:171","msg":"trace[992082410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"1.099224377s","start":"2026-04-20T20:56:29.702341Z","end":"2026-04-20T20:56:30.801565Z","steps":["trace[992082410] 'agreement among raft nodes before linearized reading' (duration: 1.098992731s)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:30.801594Z","caller":"traceutil/trace.go:171","msg":"trace[344321413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"1.328035812s","start":"2026-04-20T20:56:29.473544Z","end":"2026-04-20T20:56:30.80158Z","steps":["trace[344321413] 'agreement among raft nodes before linearized reading' (duration: 1.327860927s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:30.801626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072358271s","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-04-20T20:56:30.801637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:29.473525Z","time spent":"1.328103643s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T20:56:30.801667Z","caller":"traceutil/trace.go:171","msg":"trace[1242188962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"1.072441343s","start":"2026-04-20T20:56:29.729215Z","end":"2026-04-20T20:56:30.801656Z","steps":["trace[1242188962] 'agreement among raft nodes before linearized reading' (duration: 1.072296329s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:30.801707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:29.729194Z","time spent":"1.072506345s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T20:56:30.801297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328148684s","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-04-20T20:56:30.801769Z","caller":"traceutil/trace.go:171","msg":"trace[1898904686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"1.328630266s","start":"2026-04-20T20:56:29.473117Z","end":"2026-04-20T20:56:30.801748Z","steps":["trace[1898904686] 'agreement among raft nodes before linearized reading' (duration: 1.327977979s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:30.80182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:29.473069Z","time spent":"1.32873947s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T20:56:30.801629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:29.702317Z","time spent":"1.099304019s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T20:56:30.802056Z","caller":"traceutil/trace.go:171","msg":"trace[724494535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"1.070694507s","start":"2026-04-20T20:56:29.730563Z","end":"2026-04-20T20:56:30.801257Z","steps":["trace[724494535] 'agreement among raft nodes before linearized reading' (duration: 1.070556594s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:30.802111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:29.730542Z","time spent":"1.071557169s","remote":"127.0.0.1:52736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T20:56:32.008949Z","caller":"traceutil/trace.go:171","msg":"trace[1931850721] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"436.452939ms","start":"2026-04-20T20:56:31.57246Z","end":"2026-04-20T20:56:32.008913Z","steps":["trace[1931850721] 'process raft request' (duration: 436.195322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:32.009182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:31.572433Z","time spent":"436.631954ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:56:32.499627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.859124ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T20:56:32.499836Z","caller":"traceutil/trace.go:171","msg":"trace[396724593] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"123.101533ms","start":"2026-04-20T20:56:32.376709Z","end":"2026-04-20T20:56:32.49981Z","steps":["trace[396724593] 'read index received' (duration: 42.821µs)","trace[396724593] 'applied index is now lower than readState.Index' (duration: 123.055072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:56:32.500003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.286357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:32.500105Z","caller":"traceutil/trace.go:171","msg":"trace[597004359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1067; }","duration":"123.431992ms","start":"2026-04-20T20:56:32.376653Z","end":"2026-04-20T20:56:32.500085Z","steps":["trace[597004359] 'agreement among raft nodes before linearized reading' (duration: 123.279457ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:33.014608Z","caller":"traceutil/trace.go:171","msg":"trace[1139026682] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"125.955377ms","start":"2026-04-20T20:56:32.888628Z","end":"2026-04-20T20:56:33.014583Z","steps":["trace[1139026682] 'process raft request' (duration: 125.853064ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:33.014723Z","caller":"traceutil/trace.go:171","msg":"trace[387263856] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"127.021004ms","start":"2026-04-20T20:56:32.887655Z","end":"2026-04-20T20:56:33.014676Z","steps":["trace[387263856] 'process raft request' (duration: 126.633634ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:33.014834Z","caller":"traceutil/trace.go:171","msg":"trace[846681531] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"125.477214ms","start":"2026-04-20T20:56:32.889311Z","end":"2026-04-20T20:56:33.014788Z","steps":["trace[846681531] 'process raft request' (duration: 125.231808ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:41.76852Z","caller":"traceutil/trace.go:171","msg":"trace[68365294] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"113.215336ms","start":"2026-04-20T20:56:41.655247Z","end":"2026-04-20T20:56:41.768462Z","steps":["trace[68365294] 'process raft request' (duration: 112.908518ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:41.881291Z","caller":"traceutil/trace.go:171","msg":"trace[2044047323] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"203.895718ms","start":"2026-04-20T20:56:41.677345Z","end":"2026-04-20T20:56:41.88124Z","steps":["trace[2044047323] 'process raft request' (duration: 203.004635ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:41.881452Z","caller":"traceutil/trace.go:171","msg":"trace[1791533323] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1178; }","duration":"189.582626ms","start":"2026-04-20T20:56:41.69185Z","end":"2026-04-20T20:56:41.881433Z","steps":["trace[1791533323] 'read index received' (duration: 76.433912ms)","trace[1791533323] 'applied index is now lower than readState.Index' (duration: 113.147744ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:41.881763Z","caller":"traceutil/trace.go:171","msg":"trace[1885785895] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"198.764034ms","start":"2026-04-20T20:56:41.682984Z","end":"2026-04-20T20:56:41.881748Z","steps":["trace[1885785895] 'process raft request' (duration: 198.175209ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:41.88196Z","caller":"traceutil/trace.go:171","msg":"trace[1077435255] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"189.866764ms","start":"2026-04-20T20:56:41.692083Z","end":"2026-04-20T20:56:41.88195Z","steps":["trace[1077435255] 'process raft request' (duration: 189.311509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:41.882172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.270704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-04-20T20:56:41.882218Z","caller":"traceutil/trace.go:171","msg":"trace[399354045] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1137; }","duration":"190.358796ms","start":"2026-04-20T20:56:41.691846Z","end":"2026-04-20T20:56:41.882204Z","steps":["trace[399354045] 'agreement among raft nodes before linearized reading' (duration: 190.187562ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:41.882255Z","caller":"traceutil/trace.go:171","msg":"trace[1696892605] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"199.174665ms","start":"2026-04-20T20:56:41.683049Z","end":"2026-04-20T20:56:41.882224Z","steps":["trace[1696892605] 'process raft request' (duration: 198.185479ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:41.882466Z","caller":"traceutil/trace.go:171","msg":"trace[1123941632] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"196.113346ms","start":"2026-04-20T20:56:41.686335Z","end":"2026-04-20T20:56:41.882448Z","steps":["trace[1123941632] 'process raft request' (duration: 194.954005ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:41.882541Z","caller":"traceutil/trace.go:171","msg":"trace[603575879] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"193.699213ms","start":"2026-04-20T20:56:41.688828Z","end":"2026-04-20T20:56:41.882527Z","steps":["trace[603575879] 'process raft request' (duration: 192.537182ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:41.882508Z","caller":"traceutil/trace.go:171","msg":"trace[1651649777] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"193.829986ms","start":"2026-04-20T20:56:41.688631Z","end":"2026-04-20T20:56:41.882461Z","steps":["trace[1651649777] 'process raft request' (duration: 192.695576ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:43.052507Z","caller":"traceutil/trace.go:171","msg":"trace[718744574] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"130.531835ms","start":"2026-04-20T20:56:42.921957Z","end":"2026-04-20T20:56:43.052489Z","steps":["trace[718744574] 'process raft request' (duration: 130.057683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:43.242478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.193189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:56:43.242573Z","caller":"traceutil/trace.go:171","msg":"trace[1551282060] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"179.166416ms","start":"2026-04-20T20:56:43.063389Z","end":"2026-04-20T20:56:43.242556Z","steps":["trace[1551282060] 'read index received' (duration: 71.70051ms)","trace[1551282060] 'applied index is now lower than readState.Index' (duration: 107.464726ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:56:43.242752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.347971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:43.242795Z","caller":"traceutil/trace.go:171","msg":"trace[131110877] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1154; }","duration":"179.415683ms","start":"2026-04-20T20:56:43.063369Z","end":"2026-04-20T20:56:43.242785Z","steps":["trace[131110877] 'agreement among raft nodes before linearized reading' (duration: 179.30978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:43.242817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.268872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T20:56:43.242873Z","caller":"traceutil/trace.go:171","msg":"trace[938394436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1154; }","duration":"178.422857ms","start":"2026-04-20T20:56:43.064435Z","end":"2026-04-20T20:56:43.242857Z","steps":["trace[938394436] 'agreement among raft nodes before linearized reading' (duration: 178.279973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:43.243078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.81271ms","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-04-20T20:56:43.243099Z","caller":"traceutil/trace.go:171","msg":"trace[1375148666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1154; }","duration":"177.844562ms","start":"2026-04-20T20:56:43.065248Z","end":"2026-04-20T20:56:43.243092Z","steps":["trace[1375148666] 'agreement among raft nodes before linearized reading' (duration: 177.788391ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:43.243101Z","caller":"traceutil/trace.go:171","msg":"trace[598507764] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"187.284567ms","start":"2026-04-20T20:56:43.055388Z","end":"2026-04-20T20:56:43.242673Z","steps":["trace[598507764] 'process raft request' (duration: 79.7895ms)","trace[598507764] 'compare' (duration: 107.027775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:56:43.243174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.063327ms","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-04-20T20:56:43.24319Z","caller":"traceutil/trace.go:171","msg":"trace[1777336092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1154; }","duration":"178.105288ms","start":"2026-04-20T20:56:43.065081Z","end":"2026-04-20T20:56:43.243186Z","steps":["trace[1777336092] 'agreement among raft nodes before linearized reading' (duration: 178.063317ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:45.413953Z","caller":"traceutil/trace.go:171","msg":"trace[1490869825] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"357.515751ms","start":"2026-04-20T20:56:45.056417Z","end":"2026-04-20T20:56:45.413932Z","steps":["trace[1490869825] 'read index received' (duration: 357.302535ms)","trace[1490869825] 'applied index is now lower than readState.Index' (duration: 212.436µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:45.41426Z","caller":"traceutil/trace.go:171","msg":"trace[1542750913] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"546.285237ms","start":"2026-04-20T20:56:44.867963Z","end":"2026-04-20T20:56:45.414248Z","steps":["trace[1542750913] 'process raft request' (duration: 545.834835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:45.414365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:44.867942Z","time spent":"546.353239ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:56:45.414464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.495141ms","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-04-20T20:56:45.414523Z","caller":"traceutil/trace.go:171","msg":"trace[1153169770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1158; }","duration":"168.580943ms","start":"2026-04-20T20:56:45.245925Z","end":"2026-04-20T20:56:45.414506Z","steps":["trace[1153169770] 'agreement among raft nodes before linearized reading' (duration: 168.398937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:45.414703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.081712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T20:56:45.414734Z","caller":"traceutil/trace.go:171","msg":"trace[1589037290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1158; }","duration":"160.125814ms","start":"2026-04-20T20:56:45.254598Z","end":"2026-04-20T20:56:45.414724Z","steps":["trace[1589037290] 'agreement among raft nodes before linearized reading' (duration: 160.037492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:45.41483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.231606ms","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-04-20T20:56:45.414876Z","caller":"traceutil/trace.go:171","msg":"trace[1193513706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1158; }","duration":"160.290548ms","start":"2026-04-20T20:56:45.254572Z","end":"2026-04-20T20:56:45.414863Z","steps":["trace[1193513706] 'agreement among raft nodes before linearized reading' (duration: 160.165304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:45.414915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.483267ms","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-04-20T20:56:45.414944Z","caller":"traceutil/trace.go:171","msg":"trace[917037244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1158; }","duration":"358.57072ms","start":"2026-04-20T20:56:45.056366Z","end":"2026-04-20T20:56:45.414936Z","steps":["trace[917037244] 'agreement among raft nodes before linearized reading' (duration: 358.477547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:45.414975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:45.05634Z","time spent":"358.62813ms","remote":"127.0.0.1:53044","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-04-20T20:56:45.415156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.176382ms","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-04-20T20:56:45.415215Z","caller":"traceutil/trace.go:171","msg":"trace[842576028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1158; }","duration":"163.302226ms","start":"2026-04-20T20:56:45.251886Z","end":"2026-04-20T20:56:45.415188Z","steps":["trace[842576028] 'agreement among raft nodes before linearized reading' (duration: 163.151822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:46.499491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725739010309659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T20:56:46.591652Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172304052s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T20:56:46.591985Z","caller":"traceutil/trace.go:171","msg":"trace[1345755798] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"1.172801015s","start":"2026-04-20T20:56:45.419163Z","end":"2026-04-20T20:56:46.591964Z","steps":["trace[1345755798] 'process raft request' (duration: 1.172767514s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:46.592105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:45.419145Z","time spent":"1.172897837s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:46.592516Z","caller":"traceutil/trace.go:171","msg":"trace[1644228406] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"1.173379419s","start":"2026-04-20T20:56:45.419128Z","end":"2026-04-20T20:56:46.592507Z","steps":["trace[1644228406] 'process raft request' (duration: 1.172671931s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:46.592574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:45.419103Z","time spent":"1.173448011s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:46.594042Z","caller":"traceutil/trace.go:171","msg":"trace[1034742089] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1208; }","duration":"594.933109ms","start":"2026-04-20T20:56:45.999098Z","end":"2026-04-20T20:56:46.594031Z","steps":["trace[1034742089] 'read index received' (duration: 592.758683ms)","trace[1034742089] 'applied index is now lower than readState.Index' (duration: 2.173676ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:46.594298Z","caller":"traceutil/trace.go:171","msg":"trace[117707728] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"1.17420417s","start":"2026-04-20T20:56:45.420072Z","end":"2026-04-20T20:56:46.594276Z","steps":["trace[117707728] 'process raft request' (duration: 1.173910672s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:46.594714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.11205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T20:56:46.594346Z","caller":"traceutil/trace.go:171","msg":"trace[2031394515] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"1.174408786s","start":"2026-04-20T20:56:45.419931Z","end":"2026-04-20T20:56:46.594339Z","steps":["trace[2031394515] 'process raft request' (duration: 1.174007256s)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:46.594748Z","caller":"traceutil/trace.go:171","msg":"trace[1598210804] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1163; }","duration":"360.180942ms","start":"2026-04-20T20:56:46.234558Z","end":"2026-04-20T20:56:46.594739Z","steps":["trace[1598210804] 'agreement among raft nodes before linearized reading' (duration: 360.076819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:46.594833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:45.419915Z","time spent":"1.174853307s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:46.594374Z","caller":"traceutil/trace.go:171","msg":"trace[641938778] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"1.172083536s","start":"2026-04-20T20:56:45.422284Z","end":"2026-04-20T20:56:46.594368Z","steps":["trace[641938778] 'process raft request' (duration: 1.171722007s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:46.594867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:46.234536Z","time spent":"360.286565ms","remote":"127.0.0.1:52768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-04-20T20:56:46.595028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:45.422269Z","time spent":"1.172663741s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:56:46.594414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.319959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:46.59511Z","caller":"traceutil/trace.go:171","msg":"trace[1505399222] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1163; }","duration":"596.060348ms","start":"2026-04-20T20:56:45.99904Z","end":"2026-04-20T20:56:46.595101Z","steps":["trace[1505399222] 'agreement among raft nodes before linearized reading' (duration: 595.332619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:46.595144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:45.998095Z","time spent":"597.037474ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:56:46.595344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:45.420048Z","time spent":"1.175267117s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:56:59.039477Z","caller":"traceutil/trace.go:171","msg":"trace[835360405] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"394.925952ms","start":"2026-04-20T20:56:58.644514Z","end":"2026-04-20T20:56:59.03944Z","steps":["trace[835360405] 'read index received' (duration: 394.700186ms)","trace[835360405] 'applied index is now lower than readState.Index' (duration: 224.625µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:59.039554Z","caller":"traceutil/trace.go:171","msg":"trace[1026753807] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"396.41068ms","start":"2026-04-20T20:56:58.643088Z","end":"2026-04-20T20:56:59.039499Z","steps":["trace[1026753807] 'process raft request' (duration: 396.163074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:59.039711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:58.643076Z","time spent":"396.550513ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:56:59.039765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.23746ms","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-04-20T20:56:59.039806Z","caller":"traceutil/trace.go:171","msg":"trace[168206335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"395.337253ms","start":"2026-04-20T20:56:58.644457Z","end":"2026-04-20T20:56:59.039794Z","steps":["trace[168206335] 'agreement among raft nodes before linearized reading' (duration: 395.134007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:59.039842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:58.644436Z","time spent":"395.397574ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T20:56:59.039876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.058967ms","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-04-20T20:56:59.039933Z","caller":"traceutil/trace.go:171","msg":"trace[2138923549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"375.151429ms","start":"2026-04-20T20:56:58.664767Z","end":"2026-04-20T20:56:59.039918Z","steps":["trace[2138923549] 'agreement among raft nodes before linearized reading' (duration: 375.000995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:59.03996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:58.664724Z","time spent":"375.230011ms","remote":"127.0.0.1:53044","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-04-20T20:56:59.039943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.33669ms","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-04-20T20:56:59.04002Z","caller":"traceutil/trace.go:171","msg":"trace[2131480915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"386.466933ms","start":"2026-04-20T20:56:58.65353Z","end":"2026-04-20T20:56:59.039997Z","steps":["trace[2131480915] 'agreement among raft nodes before linearized reading' (duration: 386.247347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:59.040064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:58.653503Z","time spent":"386.553025ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T20:56:59.040523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.967007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T20:56:59.040561Z","caller":"traceutil/trace.go:171","msg":"trace[1080472242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"387.006778ms","start":"2026-04-20T20:56:58.653544Z","end":"2026-04-20T20:56:59.040551Z","steps":["trace[1080472242] 'agreement among raft nodes before linearized reading' (duration: 386.195166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:59.040588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:58.653482Z","time spent":"387.1001ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T20:56:59.180757Z","caller":"traceutil/trace.go:171","msg":"trace[114226154] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"135.665559ms","start":"2026-04-20T20:56:59.045064Z","end":"2026-04-20T20:56:59.180729Z","steps":["trace[114226154] 'process raft request' (duration: 90.414755ms)","trace[114226154] 'compare' (duration: 44.847063ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:59.180896Z","caller":"traceutil/trace.go:171","msg":"trace[1281019613] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"133.804301ms","start":"2026-04-20T20:56:59.047062Z","end":"2026-04-20T20:56:59.180866Z","steps":["trace[1281019613] 'process raft request' (duration: 133.722119ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:59.181075Z","caller":"traceutil/trace.go:171","msg":"trace[1131803904] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"135.787182ms","start":"2026-04-20T20:56:59.045276Z","end":"2026-04-20T20:56:59.181063Z","steps":["trace[1131803904] 'process raft request' (duration: 135.366711ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:59.181103Z","caller":"traceutil/trace.go:171","msg":"trace[1657383354] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"135.760531ms","start":"2026-04-20T20:56:59.045314Z","end":"2026-04-20T20:56:59.181075Z","steps":["trace[1657383354] 'process raft request' (duration: 135.433523ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:03.223277Z","caller":"traceutil/trace.go:171","msg":"trace[255304712] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"173.247333ms","start":"2026-04-20T20:57:03.050018Z","end":"2026-04-20T20:57:03.223266Z","steps":["trace[255304712] 'read index received' (duration: 173.104529ms)","trace[255304712] 'applied index is now lower than readState.Index' (duration: 142.304µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:03.223544Z","caller":"traceutil/trace.go:171","msg":"trace[295379233] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"332.049292ms","start":"2026-04-20T20:57:02.891488Z","end":"2026-04-20T20:57:03.223537Z","steps":["trace[295379233] 'process raft request' (duration: 331.672342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:03.223614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:02.891467Z","time spent":"332.097143ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:57:03.224001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.989032ms","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-04-20T20:57:03.224025Z","caller":"traceutil/trace.go:171","msg":"trace[2102602993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"174.049523ms","start":"2026-04-20T20:57:03.04997Z","end":"2026-04-20T20:57:03.22402Z","steps":["trace[2102602993] 'agreement among raft nodes before linearized reading' (duration: 173.995652ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:03.363562Z","caller":"traceutil/trace.go:171","msg":"trace[2087861252] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"133.461711ms","start":"2026-04-20T20:57:03.230078Z","end":"2026-04-20T20:57:03.363539Z","steps":["trace[2087861252] 'process raft request' (duration: 133.41549ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:03.363903Z","caller":"traceutil/trace.go:171","msg":"trace[1694564858] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"133.993646ms","start":"2026-04-20T20:57:03.229899Z","end":"2026-04-20T20:57:03.363893Z","steps":["trace[1694564858] 'process raft request' (duration: 66.228378ms)","trace[1694564858] 'compare' (duration: 67.09427ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:03.364044Z","caller":"traceutil/trace.go:171","msg":"trace[155082933] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"134.098788ms","start":"2026-04-20T20:57:03.229936Z","end":"2026-04-20T20:57:03.364034Z","steps":["trace[155082933] 'process raft request' (duration: 133.488302ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:03.364151Z","caller":"traceutil/trace.go:171","msg":"trace[1832196426] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"134.163729ms","start":"2026-04-20T20:57:03.229979Z","end":"2026-04-20T20:57:03.364143Z","steps":["trace[1832196426] 'process raft request' (duration: 133.488052ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:04.236295Z","caller":"traceutil/trace.go:171","msg":"trace[1577911608] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"367.225983ms","start":"2026-04-20T20:57:03.86904Z","end":"2026-04-20T20:57:04.236266Z","steps":["trace[1577911608] 'process raft request' (duration: 366.950506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:04.236481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.625281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:57:04.236551Z","caller":"traceutil/trace.go:171","msg":"trace[974616409] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1240; }","duration":"284.740254ms","start":"2026-04-20T20:57:03.951794Z","end":"2026-04-20T20:57:04.236535Z","steps":["trace[974616409] 'agreement among raft nodes before linearized reading' (duration: 284.543249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:04.236553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:03.869015Z","time spent":"367.382608ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:57:04.236278Z","caller":"traceutil/trace.go:171","msg":"trace[1179526363] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"284.401716ms","start":"2026-04-20T20:57:03.951837Z","end":"2026-04-20T20:57:04.236238Z","steps":["trace[1179526363] 'read index received' (duration: 284.074417ms)","trace[1179526363] 'applied index is now lower than readState.Index' (duration: 325.229µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:57:04.987943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.782172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:57:04.988538Z","caller":"traceutil/trace.go:171","msg":"trace[1382578703] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"747.316352ms","start":"2026-04-20T20:57:04.241196Z","end":"2026-04-20T20:57:04.988512Z","steps":["trace[1382578703] 'process raft request' (duration: 192.878463ms)","trace[1382578703] 'compare' (duration: 553.513684ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:04.988669Z","caller":"traceutil/trace.go:171","msg":"trace[834873795] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"537.624883ms","start":"2026-04-20T20:57:04.451016Z","end":"2026-04-20T20:57:04.988641Z","steps":["trace[834873795] 'process raft request' (duration: 537.034278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:04.9887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:04.241173Z","time spent":"747.486506ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:57:04.988787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:04.450994Z","time spent":"537.732256ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:57:05.153038Z","caller":"traceutil/trace.go:171","msg":"trace[682011572] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"156.958571ms","start":"2026-04-20T20:57:04.996016Z","end":"2026-04-20T20:57:05.152975Z","steps":["trace[682011572] 'process raft request' (duration: 91.619576ms)","trace[682011572] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/kube-system/rbd-csi-ceph-com.18a82c2d0c6d6728; req_size:770; } (duration: 64.978725ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:05.153069Z","caller":"traceutil/trace.go:171","msg":"trace[1084690461] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"117.541578ms","start":"2026-04-20T20:57:05.035502Z","end":"2026-04-20T20:57:05.153043Z","steps":["trace[1084690461] 'process raft request' (duration: 117.419654ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:05.564861Z","caller":"traceutil/trace.go:171","msg":"trace[1967544444] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"191.205659ms","start":"2026-04-20T20:57:05.373633Z","end":"2026-04-20T20:57:05.564838Z","steps":["trace[1967544444] 'process raft request' (duration: 190.922341ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:05.616332Z","caller":"traceutil/trace.go:171","msg":"trace[1223728754] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"242.489239ms","start":"2026-04-20T20:57:05.373802Z","end":"2026-04-20T20:57:05.616292Z","steps":["trace[1223728754] 'process raft request' (duration: 242.283044ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:05.616576Z","caller":"traceutil/trace.go:171","msg":"trace[308381717] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"239.919842ms","start":"2026-04-20T20:57:05.376645Z","end":"2026-04-20T20:57:05.616565Z","steps":["trace[308381717] 'process raft request' (duration: 239.610225ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:05.616884Z","caller":"traceutil/trace.go:171","msg":"trace[41027489] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"239.546572ms","start":"2026-04-20T20:57:05.377316Z","end":"2026-04-20T20:57:05.616862Z","steps":["trace[41027489] 'process raft request' (duration: 239.210023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:06.583118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:06.235824Z","time spent":"347.288787ms","remote":"127.0.0.1:52768","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T20:57:17.601986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.611546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:57:17.602088Z","caller":"traceutil/trace.go:171","msg":"trace[1539556527] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1323; }","duration":"199.774341ms","start":"2026-04-20T20:57:17.40229Z","end":"2026-04-20T20:57:17.602065Z","steps":["trace[1539556527] 'count revisions from in-memory index tree' (duration: 199.505353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.602176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367088234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T20:57:17.602269Z","caller":"traceutil/trace.go:171","msg":"trace[376107229] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1323; }","duration":"1.367252208s","start":"2026-04-20T20:57:16.234993Z","end":"2026-04-20T20:57:17.602245Z","steps":["trace[376107229] 'range keys from in-memory index tree' (duration: 1.366916619s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.602334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:16.234973Z","time spent":"1.367344301s","remote":"127.0.0.1:52768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-04-20T20:57:17.602304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593910905s","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:526"} {"level":"info","ts":"2026-04-20T20:57:17.602454Z","caller":"traceutil/trace.go:171","msg":"trace[620951972] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1323; }","duration":"1.594087011s","start":"2026-04-20T20:57:16.008302Z","end":"2026-04-20T20:57:17.602389Z","steps":["trace[620951972] 'range keys from in-memory index tree' (duration: 1.593605967s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.602535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725182951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T20:57:17.60264Z","caller":"traceutil/trace.go:171","msg":"trace[694153180] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1323; }","duration":"1.725344485s","start":"2026-04-20T20:57:15.877277Z","end":"2026-04-20T20:57:17.602621Z","steps":["trace[694153180] 'count revisions from in-memory index tree' (duration: 1.725061557s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.602677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.705133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T20:57:17.602751Z","caller":"traceutil/trace.go:171","msg":"trace[1270295538] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1323; }","duration":"380.818526ms","start":"2026-04-20T20:57:17.221915Z","end":"2026-04-20T20:57:17.602734Z","steps":["trace[1270295538] 'count revisions from in-memory index tree' (duration: 380.631311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.602806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:17.221896Z","time spent":"380.894438ms","remote":"127.0.0.1:52874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:57:17.602806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.797528ms","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:530"} {"level":"info","ts":"2026-04-20T20:57:17.602863Z","caller":"traceutil/trace.go:171","msg":"trace[1804295832] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1323; }","duration":"458.877751ms","start":"2026-04-20T20:57:17.14397Z","end":"2026-04-20T20:57:17.602848Z","steps":["trace[1804295832] 'range keys from in-memory index tree' (duration: 458.701596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.602698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:15.877254Z","time spent":"1.725426236s","remote":"127.0.0.1:53236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:57:17.60292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:17.143958Z","time spent":"458.945252ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-20T20:57:17.602524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:16.008285Z","time spent":"1.594220935s","remote":"127.0.0.1:53044","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\" "} {"level":"warn","ts":"2026-04-20T20:57:17.603187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.211625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:57:17.603241Z","caller":"traceutil/trace.go:171","msg":"trace[49015645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1323; }","duration":"874.56832ms","start":"2026-04-20T20:57:16.72866Z","end":"2026-04-20T20:57:17.603228Z","steps":["trace[49015645] 'range keys from in-memory index tree' (duration: 873.061491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.603256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.829735ms","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-04-20T20:57:17.603343Z","caller":"traceutil/trace.go:171","msg":"trace[1576154373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1323; }","duration":"250.953618ms","start":"2026-04-20T20:57:17.352378Z","end":"2026-04-20T20:57:17.603331Z","steps":["trace[1576154373] 'range keys from in-memory index tree' (duration: 249.468169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.603275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:16.72864Z","time spent":"874.627772ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T20:57:17.890966Z","caller":"traceutil/trace.go:171","msg":"trace[1778950819] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1379; }","duration":"268.451182ms","start":"2026-04-20T20:57:17.622491Z","end":"2026-04-20T20:57:17.890942Z","steps":["trace[1778950819] 'read index received' (duration: 263.912804ms)","trace[1778950819] 'applied index is now lower than readState.Index' (duration: 4.537328ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:17.891583Z","caller":"traceutil/trace.go:171","msg":"trace[196431721] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"283.4183ms","start":"2026-04-20T20:57:17.608097Z","end":"2026-04-20T20:57:17.891516Z","steps":["trace[196431721] 'process raft request' (duration: 282.772663ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:17.891749Z","caller":"traceutil/trace.go:171","msg":"trace[288716293] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"283.636866ms","start":"2026-04-20T20:57:17.608101Z","end":"2026-04-20T20:57:17.891738Z","steps":["trace[288716293] 'process raft request' (duration: 282.732293ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:17.891735Z","caller":"traceutil/trace.go:171","msg":"trace[283730684] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"283.713798ms","start":"2026-04-20T20:57:17.607995Z","end":"2026-04-20T20:57:17.891709Z","steps":["trace[283730684] 'process raft request' (duration: 282.697771ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:17.891904Z","caller":"traceutil/trace.go:171","msg":"trace[481128147] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"156.382326ms","start":"2026-04-20T20:57:17.735512Z","end":"2026-04-20T20:57:17.891895Z","steps":["trace[481128147] 'process raft request' (duration: 155.38883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.892011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.500999ms","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-04-20T20:57:17.892084Z","caller":"traceutil/trace.go:171","msg":"trace[2046760387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"269.604602ms","start":"2026-04-20T20:57:17.622461Z","end":"2026-04-20T20:57:17.892065Z","steps":["trace[2046760387] 'agreement among raft nodes before linearized reading' (duration: 269.371426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.894981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.491848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T20:57:17.895017Z","caller":"traceutil/trace.go:171","msg":"trace[98688336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1328; }","duration":"151.54995ms","start":"2026-04-20T20:57:17.743459Z","end":"2026-04-20T20:57:17.895008Z","steps":["trace[98688336] 'agreement among raft nodes before linearized reading' (duration: 151.429547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.8952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.656825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:57:17.895407Z","caller":"traceutil/trace.go:171","msg":"trace[1353513602] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1328; }","duration":"266.874201ms","start":"2026-04-20T20:57:17.628514Z","end":"2026-04-20T20:57:17.895388Z","steps":["trace[1353513602] 'agreement among raft nodes before linearized reading' (duration: 266.359718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.895502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.389049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:57:17.895554Z","caller":"traceutil/trace.go:171","msg":"trace[1633413607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"165.440621ms","start":"2026-04-20T20:57:17.730105Z","end":"2026-04-20T20:57:17.895546Z","steps":["trace[1633413607] 'agreement among raft nodes before linearized reading' (duration: 165.374949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:17.895584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.935522ms","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-04-20T20:57:17.895291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.558103ms","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-04-20T20:57:17.89564Z","caller":"traceutil/trace.go:171","msg":"trace[303783127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1328; }","duration":"154.048285ms","start":"2026-04-20T20:57:17.741579Z","end":"2026-04-20T20:57:17.895627Z","steps":["trace[303783127] 'agreement among raft nodes before linearized reading' (duration: 153.885551ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:17.895693Z","caller":"traceutil/trace.go:171","msg":"trace[1001884518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"148.963333ms","start":"2026-04-20T20:57:17.746712Z","end":"2026-04-20T20:57:17.895675Z","steps":["trace[1001884518] 'agreement among raft nodes before linearized reading' (duration: 148.531122ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:24.042885Z","caller":"traceutil/trace.go:171","msg":"trace[900663546] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"109.657275ms","start":"2026-04-20T20:57:23.933203Z","end":"2026-04-20T20:57:24.04286Z","steps":["trace[900663546] 'process raft request' (duration: 53.050076ms)","trace[900663546] 'compare' (duration: 56.458885ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:24.043262Z","caller":"traceutil/trace.go:171","msg":"trace[590083588] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"109.905881ms","start":"2026-04-20T20:57:23.933324Z","end":"2026-04-20T20:57:24.04323Z","steps":["trace[590083588] 'process raft request' (duration: 109.498001ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:24.043381Z","caller":"traceutil/trace.go:171","msg":"trace[255396420] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"108.101594ms","start":"2026-04-20T20:57:23.935267Z","end":"2026-04-20T20:57:24.043368Z","steps":["trace[255396420] 'process raft request' (duration: 107.856717ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:24.043638Z","caller":"traceutil/trace.go:171","msg":"trace[1734911342] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"108.159235ms","start":"2026-04-20T20:57:23.935468Z","end":"2026-04-20T20:57:24.043627Z","steps":["trace[1734911342] 'process raft request' (duration: 108.062773ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:24.043727Z","caller":"traceutil/trace.go:171","msg":"trace[470664350] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"108.382612ms","start":"2026-04-20T20:57:23.935336Z","end":"2026-04-20T20:57:24.043719Z","steps":["trace[470664350] 'process raft request' (duration: 107.988931ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:24.043827Z","caller":"traceutil/trace.go:171","msg":"trace[997268946] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"108.314949ms","start":"2026-04-20T20:57:23.935479Z","end":"2026-04-20T20:57:24.043794Z","steps":["trace[997268946] 'process raft request' (duration: 108.120534ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:32.592883Z","caller":"traceutil/trace.go:171","msg":"trace[1560202275] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"101.683097ms","start":"2026-04-20T20:58:32.491184Z","end":"2026-04-20T20:58:32.592867Z","steps":["trace[1560202275] 'process raft request' (duration: 101.663056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:32.593094Z","caller":"traceutil/trace.go:171","msg":"trace[1171465348] transaction","detail":"{read_only:false; response_revision:1669; number_of_response:1; }","duration":"102.509429ms","start":"2026-04-20T20:58:32.49058Z","end":"2026-04-20T20:58:32.593089Z","steps":["trace[1171465348] 'process raft request' (duration: 99.14424ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:32.59315Z","caller":"traceutil/trace.go:171","msg":"trace[768657265] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"102.070588ms","start":"2026-04-20T20:58:32.491057Z","end":"2026-04-20T20:58:32.593128Z","steps":["trace[768657265] 'process raft request' (duration: 101.77237ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:32.593332Z","caller":"traceutil/trace.go:171","msg":"trace[934134230] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"102.349165ms","start":"2026-04-20T20:58:32.490976Z","end":"2026-04-20T20:58:32.593325Z","steps":["trace[934134230] 'process raft request' (duration: 101.830751ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:48.588929Z","caller":"traceutil/trace.go:171","msg":"trace[2099380949] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"122.952446ms","start":"2026-04-20T20:58:48.465947Z","end":"2026-04-20T20:58:48.588899Z","steps":["trace[2099380949] 'process raft request' (duration: 69.778726ms)","trace[2099380949] 'compare' (duration: 53.064437ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:58:48.833677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.367322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:58:48.833776Z","caller":"traceutil/trace.go:171","msg":"trace[934536423] linearizableReadLoop","detail":"{readStateIndex:1819; appliedIndex:1818; }","duration":"104.031948ms","start":"2026-04-20T20:58:48.729728Z","end":"2026-04-20T20:58:48.83376Z","steps":["trace[934536423] 'read index received' (duration: 33.321µs)","trace[934536423] 'applied index is now lower than readState.Index' (duration: 103.997627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:58:48.834016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.270785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:58:48.834097Z","caller":"traceutil/trace.go:171","msg":"trace[80486598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1744; }","duration":"104.405248ms","start":"2026-04-20T20:58:48.729674Z","end":"2026-04-20T20:58:48.83408Z","steps":["trace[80486598] 'agreement among raft nodes before linearized reading' (duration: 104.230684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:48.834056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.009618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T20:58:48.834269Z","caller":"traceutil/trace.go:171","msg":"trace[790380898] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"181.373133ms","start":"2026-04-20T20:58:48.652869Z","end":"2026-04-20T20:58:48.834242Z","steps":["trace[790380898] 'process raft request' (duration: 42.366935ms)","trace[790380898] 'compare' (duration: 138.267919ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:48.83433Z","caller":"traceutil/trace.go:171","msg":"trace[1021812352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1744; }","duration":"101.302366ms","start":"2026-04-20T20:58:48.733Z","end":"2026-04-20T20:58:48.834303Z","steps":["trace[1021812352] 'agreement among raft nodes before linearized reading' (duration: 100.961917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:56.388058Z","caller":"traceutil/trace.go:171","msg":"trace[1539244385] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"119.27208ms","start":"2026-04-20T20:58:56.268758Z","end":"2026-04-20T20:58:56.388031Z","steps":["trace[1539244385] 'process raft request' (duration: 56.827306ms)","trace[1539244385] 'compare' (duration: 62.311081ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:03.115663Z","caller":"traceutil/trace.go:171","msg":"trace[1275992820] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"129.380605ms","start":"2026-04-20T20:59:02.986236Z","end":"2026-04-20T20:59:03.115616Z","steps":["trace[1275992820] 'read index received' (duration: 41.677487ms)","trace[1275992820] 'applied index is now lower than readState.Index' (duration: 87.702098ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:03.115806Z","caller":"traceutil/trace.go:171","msg":"trace[352778638] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"129.876438ms","start":"2026-04-20T20:59:02.985881Z","end":"2026-04-20T20:59:03.115757Z","steps":["trace[352778638] 'process raft request' (duration: 42.045126ms)","trace[352778638] 'compare' (duration: 87.547885ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:03.115909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.651072ms","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:531"} {"level":"warn","ts":"2026-04-20T20:59:03.115943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.734316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T20:59:03.115985Z","caller":"traceutil/trace.go:171","msg":"trace[62573828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1807; }","duration":"126.819198ms","start":"2026-04-20T20:59:02.989155Z","end":"2026-04-20T20:59:03.115974Z","steps":["trace[62573828] 'agreement among raft nodes before linearized reading' (duration: 126.640013ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:03.115987Z","caller":"traceutil/trace.go:171","msg":"trace[933328274] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1807; }","duration":"129.769555ms","start":"2026-04-20T20:59:02.986198Z","end":"2026-04-20T20:59:03.115967Z","steps":["trace[933328274] 'agreement among raft nodes before linearized reading' (duration: 129.57756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:03.116171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.865419ms","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-04-20T20:59:03.116205Z","caller":"traceutil/trace.go:171","msg":"trace[933034363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1807; }","duration":"126.938951ms","start":"2026-04-20T20:59:02.989252Z","end":"2026-04-20T20:59:03.116191Z","steps":["trace[933034363] 'agreement among raft nodes before linearized reading' (duration: 126.856139ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:35.046779Z","caller":"traceutil/trace.go:171","msg":"trace[242588757] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"219.835187ms","start":"2026-04-20T21:00:34.826919Z","end":"2026-04-20T21:00:35.046754Z","steps":["trace[242588757] 'process raft request' (duration: 219.673483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:35.353761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.696934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T21:00:35.353984Z","caller":"traceutil/trace.go:171","msg":"trace[390530292] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"366.181448ms","start":"2026-04-20T21:00:34.987745Z","end":"2026-04-20T21:00:35.353927Z","steps":["trace[390530292] 'process raft request' (duration: 100.210907ms)","trace[390530292] 'compare' (duration: 265.56354ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:35.354113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:34.987719Z","time spent":"366.338712ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T21:00:35.354311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.356565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-20T21:00:35.354374Z","caller":"traceutil/trace.go:171","msg":"trace[1583989315] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2259; }","duration":"183.421177ms","start":"2026-04-20T21:00:35.170936Z","end":"2026-04-20T21:00:35.354357Z","steps":["trace[1583989315] 'agreement among raft nodes before linearized reading' (duration: 183.053767ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:35.354338Z","caller":"traceutil/trace.go:171","msg":"trace[1830223314] linearizableReadLoop","detail":"{readStateIndex:2356; appliedIndex:2355; }","duration":"182.908934ms","start":"2026-04-20T21:00:35.17096Z","end":"2026-04-20T21:00:35.353868Z","steps":["trace[1830223314] 'read index received' (duration: 33.931µs)","trace[1830223314] 'applied index is now lower than readState.Index' (duration: 182.873113ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:35.354997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.40112ms","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-04-20T21:00:35.355063Z","caller":"traceutil/trace.go:171","msg":"trace[2007955620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2259; }","duration":"117.501623ms","start":"2026-04-20T21:00:35.237545Z","end":"2026-04-20T21:00:35.355047Z","steps":["trace[2007955620] 'agreement among raft nodes before linearized reading' (duration: 117.339129ms)"],"step_count":1}