{"level":"warn","ts":"2026-04-23T13:21:20.668748Z","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-23T13:21:20.66884Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.91:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.91:2380","--initial-cluster=instance=https://199.19.213.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.91: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-23T13:21:20.668908Z","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-23T13:21:20.668923Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-04-23T13:21:20.668945Z","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-23T13:21:20.669535Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"]} {"level":"info","ts":"2026-04-23T13:21:20.669613Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.91: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-23T13:21:20.674193Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.331365ms"} {"level":"info","ts":"2026-04-23T13:21:20.683834Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2c24222821500990","cluster-id":"f38faa6ba493daad"} {"level":"info","ts":"2026-04-23T13:21:20.683927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T13:21:20.683957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 0"} {"level":"info","ts":"2026-04-23T13:21:20.683968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2c24222821500990 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T13:21:20.683984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 1"} {"level":"info","ts":"2026-04-23T13:21:20.684024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"warn","ts":"2026-04-23T13:21:20.692982Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T13:21:20.696959Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T13:21:20.698705Z","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-23T13:21:20.701442Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2c24222821500990","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T13:21:20.701756Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2c24222821500990","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T13:21:20.701947Z","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-23T13:21:20.702131Z","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-23T13:21:20.702151Z","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-23T13:21:20.703362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"info","ts":"2026-04-23T13:21:20.705861Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","added-peer-id":"2c24222821500990","added-peer-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-04-23T13:21:20.707819Z","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-23T13:21:20.707967Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-04-23T13:21:20.708027Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-04-23T13:21:20.708115Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2c24222821500990","initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T13:21:20.708149Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T13:21:21.185203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T13:21:21.185268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T13:21:21.185291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgPreVoteResp from 2c24222821500990 at term 1"} {"level":"info","ts":"2026-04-23T13:21:21.185313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became candidate at term 2"} {"level":"info","ts":"2026-04-23T13:21:21.185325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgVoteResp from 2c24222821500990 at term 2"} {"level":"info","ts":"2026-04-23T13:21:21.185359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became leader at term 2"} {"level":"info","ts":"2026-04-23T13:21:21.18538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2c24222821500990 elected leader 2c24222821500990 at term 2"} {"level":"info","ts":"2026-04-23T13:21:21.190995Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2c24222821500990","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.91:2379]}","request-path":"/0/members/2c24222821500990/attributes","cluster-id":"f38faa6ba493daad","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T13:21:21.191351Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:21:21.191712Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T13:21:21.191795Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T13:21:21.193481Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:21:21.193726Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:21:21.193795Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:21:21.194821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.91:2379"} {"level":"info","ts":"2026-04-23T13:21:21.19608Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T13:21:21.201184Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T13:21:21.201239Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T13:21:47.041015Z","caller":"traceutil/trace.go:171","msg":"trace[2102037467] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"109.804446ms","start":"2026-04-23T13:21:46.931177Z","end":"2026-04-23T13:21:47.040981Z","steps":["trace[2102037467] 'read index received' (duration: 109.613697ms)","trace[2102037467] 'applied index is now lower than readState.Index' (duration: 189.449µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:21:47.041179Z","caller":"traceutil/trace.go:171","msg":"trace[1929686774] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"137.172957ms","start":"2026-04-23T13:21:46.903995Z","end":"2026-04-23T13:21:47.041168Z","steps":["trace[1929686774] 'process raft request' (duration: 136.775097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:21:47.041526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.322859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-23T13:21:47.041709Z","caller":"traceutil/trace.go:171","msg":"trace[1665535503] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:499; }","duration":"110.558553ms","start":"2026-04-23T13:21:46.931115Z","end":"2026-04-23T13:21:47.041674Z","steps":["trace[1665535503] 'agreement among raft nodes before linearized reading' (duration: 110.23899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:21:47.042145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.283546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-23T13:21:47.042267Z","caller":"traceutil/trace.go:171","msg":"trace[2095639097] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:499; }","duration":"110.492127ms","start":"2026-04-23T13:21:46.931747Z","end":"2026-04-23T13:21:47.042239Z","steps":["trace[2095639097] 'agreement among raft nodes before linearized reading' (duration: 109.524168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:21:47.501602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.119455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v5t8k\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-23T13:21:47.501619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.548995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:21:47.501691Z","caller":"traceutil/trace.go:171","msg":"trace[349134058] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v5t8k; range_end:; response_count:1; response_revision:499; }","duration":"456.230896ms","start":"2026-04-23T13:21:47.045438Z","end":"2026-04-23T13:21:47.501669Z","steps":["trace[349134058] 'range keys from in-memory index tree' (duration: 455.944918ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:21:47.501703Z","caller":"traceutil/trace.go:171","msg":"trace[1680945983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"332.681409ms","start":"2026-04-23T13:21:47.168997Z","end":"2026-04-23T13:21:47.501679Z","steps":["trace[1680945983] 'range keys from in-memory index tree' (duration: 332.458226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:21:47.501724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:21:47.045426Z","time spent":"456.291873ms","remote":"127.0.0.1:41204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-v5t8k\" "} {"level":"warn","ts":"2026-04-23T13:21:47.501768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:21:47.168973Z","time spent":"332.786939ms","remote":"127.0.0.1:41012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:21:47.501769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.690441ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:21:47.501948Z","caller":"traceutil/trace.go:171","msg":"trace[1615540423] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:499; }","duration":"123.903383ms","start":"2026-04-23T13:21:47.378009Z","end":"2026-04-23T13:21:47.501912Z","steps":["trace[1615540423] 'range keys from in-memory index tree' (duration: 123.656047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:21:50.328976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.245508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:21:50.329083Z","caller":"traceutil/trace.go:171","msg":"trace[543798399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"159.391793ms","start":"2026-04-23T13:21:50.169669Z","end":"2026-04-23T13:21:50.329061Z","steps":["trace[543798399] 'range keys from in-memory index tree' (duration: 159.152759ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:21:50.329463Z","caller":"traceutil/trace.go:171","msg":"trace[1247906448] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"147.86738ms","start":"2026-04-23T13:21:50.18153Z","end":"2026-04-23T13:21:50.329398Z","steps":["trace[1247906448] 'process raft request' (duration: 146.39538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:21:57.28006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.680738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:21:57.280217Z","caller":"traceutil/trace.go:171","msg":"trace[1236371252] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"381.667583ms","start":"2026-04-23T13:21:56.898532Z","end":"2026-04-23T13:21:57.280199Z","steps":["trace[1236371252] 'read index received' (duration: 178.195674ms)","trace[1236371252] 'applied index is now lower than readState.Index' (duration: 203.470248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:21:57.280458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.901177ms","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-23T13:21:57.280502Z","caller":"traceutil/trace.go:171","msg":"trace[1455414042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"382.010048ms","start":"2026-04-23T13:21:56.898481Z","end":"2026-04-23T13:21:57.280491Z","steps":["trace[1455414042] 'agreement among raft nodes before linearized reading' (duration: 381.792436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:21:57.280537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:21:56.898458Z","time spent":"382.070754ms","remote":"127.0.0.1:41292","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":"info","ts":"2026-04-23T13:21:57.280258Z","caller":"traceutil/trace.go:171","msg":"trace[110354768] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"387.388135ms","start":"2026-04-23T13:21:56.892838Z","end":"2026-04-23T13:21:57.280226Z","steps":["trace[110354768] 'process raft request' (duration: 183.870732ms)","trace[110354768] 'compare' (duration: 202.578367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:21:57.280977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:21:56.892805Z","time spent":"387.920729ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:21:57.293288Z","caller":"traceutil/trace.go:171","msg":"trace[437925108] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"230.193737ms","start":"2026-04-23T13:21:57.063082Z","end":"2026-04-23T13:21:57.293276Z","steps":["trace[437925108] 'process raft request' (duration: 229.997358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:21:57.293329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.547309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T13:21:57.293364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.415958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:21:57.29342Z","caller":"traceutil/trace.go:171","msg":"trace[1065705535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"124.521798ms","start":"2026-04-23T13:21:57.168883Z","end":"2026-04-23T13:21:57.293405Z","steps":["trace[1065705535] 'agreement among raft nodes before linearized reading' (duration: 124.404407ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:21:57.293524Z","caller":"traceutil/trace.go:171","msg":"trace[2107945662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"200.644259ms","start":"2026-04-23T13:21:57.092726Z","end":"2026-04-23T13:21:57.29337Z","steps":["trace[2107945662] 'agreement among raft nodes before linearized reading' (duration: 200.466341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:22:12.928242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.671188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T13:22:12.928355Z","caller":"traceutil/trace.go:171","msg":"trace[1416785246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"155.926164ms","start":"2026-04-23T13:22:12.772409Z","end":"2026-04-23T13:22:12.928335Z","steps":["trace[1416785246] 'range keys from in-memory index tree' (duration: 155.435675ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:23:29.886446Z","caller":"traceutil/trace.go:171","msg":"trace[1668770037] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"102.661606ms","start":"2026-04-23T13:23:29.78375Z","end":"2026-04-23T13:23:29.886411Z","steps":["trace[1668770037] 'read index received' (duration: 102.535103ms)","trace[1668770037] 'applied index is now lower than readState.Index' (duration: 125.463µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:23:29.886536Z","caller":"traceutil/trace.go:171","msg":"trace[1902505905] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"104.495831ms","start":"2026-04-23T13:23:29.782019Z","end":"2026-04-23T13:23:29.886515Z","steps":["trace[1902505905] 'process raft request' (duration: 104.18678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:23:29.886807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.017101ms","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-23T13:23:29.88691Z","caller":"traceutil/trace.go:171","msg":"trace[863131419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"103.153855ms","start":"2026-04-23T13:23:29.783732Z","end":"2026-04-23T13:23:29.886886Z","steps":["trace[863131419] 'agreement among raft nodes before linearized reading' (duration: 102.856815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:23:32.316475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.073981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:23:32.316606Z","caller":"traceutil/trace.go:171","msg":"trace[2055608147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"147.27241ms","start":"2026-04-23T13:23:32.169312Z","end":"2026-04-23T13:23:32.316585Z","steps":["trace[2055608147] 'range keys from in-memory index tree' (duration: 146.942918ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:23:36.061531Z","caller":"traceutil/trace.go:171","msg":"trace[1466114103] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"120.038638ms","start":"2026-04-23T13:23:35.941452Z","end":"2026-04-23T13:23:36.061491Z","steps":["trace[1466114103] 'read index received' (duration: 119.799874ms)","trace[1466114103] 'applied index is now lower than readState.Index' (duration: 237.114µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:23:36.061674Z","caller":"traceutil/trace.go:171","msg":"trace[270159612] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"135.974974ms","start":"2026-04-23T13:23:35.925657Z","end":"2026-04-23T13:23:36.061632Z","steps":["trace[270159612] 'process raft request' (duration: 135.616488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:23:36.061882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.405695ms","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-23T13:23:36.061966Z","caller":"traceutil/trace.go:171","msg":"trace[535251531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1160; }","duration":"120.552219ms","start":"2026-04-23T13:23:35.941396Z","end":"2026-04-23T13:23:36.061948Z","steps":["trace[535251531] 'agreement among raft nodes before linearized reading' (duration: 120.297724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:23:36.061965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.150251ms","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-23T13:23:36.062038Z","caller":"traceutil/trace.go:171","msg":"trace[562166694] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1160; }","duration":"107.34408ms","start":"2026-04-23T13:23:35.954675Z","end":"2026-04-23T13:23:36.062019Z","steps":["trace[562166694] 'agreement among raft nodes before linearized reading' (duration: 107.071313ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:23:49.51129Z","caller":"traceutil/trace.go:171","msg":"trace[102796207] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"208.758258ms","start":"2026-04-23T13:23:49.302492Z","end":"2026-04-23T13:23:49.51125Z","steps":["trace[102796207] 'process raft request' (duration: 208.546147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:23:49.6721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.350789ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T13:23:49.672279Z","caller":"traceutil/trace.go:171","msg":"trace[1617944856] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"105.256228ms","start":"2026-04-23T13:23:49.567003Z","end":"2026-04-23T13:23:49.672259Z","steps":["trace[1617944856] 'read index received' (duration: 61.177µs)","trace[1617944856] 'applied index is now lower than readState.Index' (duration: 105.193211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:23:49.672486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.47416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T13:23:49.672514Z","caller":"traceutil/trace.go:171","msg":"trace[1614311108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"105.557388ms","start":"2026-04-23T13:23:49.566947Z","end":"2026-04-23T13:23:49.672504Z","steps":["trace[1614311108] 'agreement among raft nodes before linearized reading' (duration: 105.366429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:23:52.606631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.677591ms","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-23T13:23:52.606722Z","caller":"traceutil/trace.go:171","msg":"trace[2016987378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"174.833547ms","start":"2026-04-23T13:23:52.43187Z","end":"2026-04-23T13:23:52.606703Z","steps":["trace[2016987378] 'range keys from in-memory index tree' (duration: 174.505682ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:23:52.606802Z","caller":"traceutil/trace.go:171","msg":"trace[165848884] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"116.737753ms","start":"2026-04-23T13:23:52.49003Z","end":"2026-04-23T13:23:52.606768Z","steps":["trace[165848884] 'process raft request' (duration: 99.020106ms)","trace[165848884] 'compare' (duration: 17.442719ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:23:52.745306Z","caller":"traceutil/trace.go:171","msg":"trace[1868545766] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"134.534429ms","start":"2026-04-23T13:23:52.61074Z","end":"2026-04-23T13:23:52.745274Z","steps":["trace[1868545766] 'process raft request' (duration: 134.299304ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:23:54.387182Z","caller":"traceutil/trace.go:171","msg":"trace[1496644326] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"111.987151ms","start":"2026-04-23T13:23:54.275144Z","end":"2026-04-23T13:23:54.387131Z","steps":["trace[1496644326] 'process raft request' (duration: 58.351781ms)","trace[1496644326] 'compare' (duration: 53.482445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:24:08.802483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.418861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:24:08.802783Z","caller":"traceutil/trace.go:171","msg":"trace[433491792] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"225.977474ms","start":"2026-04-23T13:24:08.576766Z","end":"2026-04-23T13:24:08.802744Z","steps":["trace[433491792] 'process raft request' (duration: 25.204937ms)","trace[433491792] 'compare' (duration: 200.313121ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:24:08.970169Z","caller":"traceutil/trace.go:171","msg":"trace[2026230707] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"112.625946ms","start":"2026-04-23T13:24:08.857514Z","end":"2026-04-23T13:24:08.97014Z","steps":["trace[2026230707] 'process raft request' (duration: 100.44102ms)","trace[2026230707] 'compare' (duration: 12.053753ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:24:10.942714Z","caller":"traceutil/trace.go:171","msg":"trace[902234928] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"131.620733ms","start":"2026-04-23T13:24:10.811055Z","end":"2026-04-23T13:24:10.942676Z","steps":["trace[902234928] 'process raft request' (duration: 131.400561ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:21.40342Z","caller":"traceutil/trace.go:171","msg":"trace[472894982] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"185.507261ms","start":"2026-04-23T13:24:21.217878Z","end":"2026-04-23T13:24:21.403386Z","steps":["trace[472894982] 'process raft request' (duration: 185.307297ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:25.10177Z","caller":"traceutil/trace.go:171","msg":"trace[680672139] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"292.747859ms","start":"2026-04-23T13:24:24.808986Z","end":"2026-04-23T13:24:25.101734Z","steps":["trace[680672139] 'process raft request' (duration: 292.503595ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:26.361634Z","caller":"traceutil/trace.go:171","msg":"trace[2042925564] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"126.807387ms","start":"2026-04-23T13:24:26.234796Z","end":"2026-04-23T13:24:26.361603Z","steps":["trace[2042925564] 'read index received' (duration: 126.410052ms)","trace[2042925564] 'applied index is now lower than readState.Index' (duration: 396.185µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:24:26.361703Z","caller":"traceutil/trace.go:171","msg":"trace[104124717] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"160.429738ms","start":"2026-04-23T13:24:26.20124Z","end":"2026-04-23T13:24:26.361669Z","steps":["trace[104124717] 'process raft request' (duration: 160.167104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:24:26.361999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.165031ms","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-23T13:24:26.362114Z","caller":"traceutil/trace.go:171","msg":"trace[1246535744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"127.356084ms","start":"2026-04-23T13:24:26.234733Z","end":"2026-04-23T13:24:26.362089Z","steps":["trace[1246535744] 'agreement among raft nodes before linearized reading' (duration: 126.998369ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:27.624495Z","caller":"traceutil/trace.go:171","msg":"trace[1368013666] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"100.566158ms","start":"2026-04-23T13:24:27.523899Z","end":"2026-04-23T13:24:27.624465Z","steps":["trace[1368013666] 'process raft request' (duration: 100.382195ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:29.255783Z","caller":"traceutil/trace.go:171","msg":"trace[1890009388] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"100.82374ms","start":"2026-04-23T13:24:29.154926Z","end":"2026-04-23T13:24:29.25575Z","steps":["trace[1890009388] 'process raft request' (duration: 100.420685ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:30.794644Z","caller":"traceutil/trace.go:171","msg":"trace[1984954302] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"408.290462ms","start":"2026-04-23T13:24:30.386319Z","end":"2026-04-23T13:24:30.79461Z","steps":["trace[1984954302] 'process raft request' (duration: 408.112919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:24:30.794836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:24:30.386285Z","time spent":"408.454013ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:24:30.799647Z","caller":"traceutil/trace.go:171","msg":"trace[607400913] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"357.199892ms","start":"2026-04-23T13:24:30.442414Z","end":"2026-04-23T13:24:30.799613Z","steps":["trace[607400913] 'process raft request' (duration: 357.001868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:24:30.799838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:24:30.442371Z","time spent":"357.366264ms","remote":"127.0.0.1:41198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:24:34.964428Z","caller":"traceutil/trace.go:171","msg":"trace[1894278827] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"115.50324ms","start":"2026-04-23T13:24:34.848898Z","end":"2026-04-23T13:24:34.964401Z","steps":["trace[1894278827] 'read index received' (duration: 115.293818ms)","trace[1894278827] 'applied index is now lower than readState.Index' (duration: 208.132µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:24:34.964695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.772415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:24:34.964664Z","caller":"traceutil/trace.go:171","msg":"trace[841287444] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"151.685217ms","start":"2026-04-23T13:24:34.812894Z","end":"2026-04-23T13:24:34.964579Z","steps":["trace[841287444] 'process raft request' (duration: 151.334952ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:34.964742Z","caller":"traceutil/trace.go:171","msg":"trace[1292026174] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1352; }","duration":"115.885386ms","start":"2026-04-23T13:24:34.848842Z","end":"2026-04-23T13:24:34.964727Z","steps":["trace[1292026174] 'agreement among raft nodes before linearized reading' (duration: 115.713483ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:35.789457Z","caller":"traceutil/trace.go:171","msg":"trace[1940567057] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"127.227862ms","start":"2026-04-23T13:24:35.662199Z","end":"2026-04-23T13:24:35.789427Z","steps":["trace[1940567057] 'process raft request' (duration: 127.031729ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:37.923004Z","caller":"traceutil/trace.go:171","msg":"trace[175405916] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"125.240225ms","start":"2026-04-23T13:24:37.797729Z","end":"2026-04-23T13:24:37.922969Z","steps":["trace[175405916] 'process raft request' (duration: 125.016742ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:39.63628Z","caller":"traceutil/trace.go:171","msg":"trace[653446723] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"110.006297ms","start":"2026-04-23T13:24:39.526244Z","end":"2026-04-23T13:24:39.63625Z","steps":["trace[653446723] 'process raft request' (duration: 109.768913ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:39.644761Z","caller":"traceutil/trace.go:171","msg":"trace[1890731598] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"116.379784ms","start":"2026-04-23T13:24:39.528355Z","end":"2026-04-23T13:24:39.644735Z","steps":["trace[1890731598] 'process raft request' (duration: 116.199251ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:41.766496Z","caller":"traceutil/trace.go:171","msg":"trace[395612367] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"118.37763ms","start":"2026-04-23T13:24:41.64809Z","end":"2026-04-23T13:24:41.766467Z","steps":["trace[395612367] 'read index received' (duration: 118.072526ms)","trace[395612367] 'applied index is now lower than readState.Index' (duration: 299.004µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:24:41.766569Z","caller":"traceutil/trace.go:171","msg":"trace[987560051] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"120.850674ms","start":"2026-04-23T13:24:41.645687Z","end":"2026-04-23T13:24:41.766538Z","steps":["trace[987560051] 'process raft request' (duration: 120.575211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:24:41.766728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.617234ms","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-23T13:24:41.766775Z","caller":"traceutil/trace.go:171","msg":"trace[130075272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1374; }","duration":"118.726716ms","start":"2026-04-23T13:24:41.648031Z","end":"2026-04-23T13:24:41.766758Z","steps":["trace[130075272] 'agreement among raft nodes before linearized reading' (duration: 118.580083ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:41.949006Z","caller":"traceutil/trace.go:171","msg":"trace[1466135556] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"176.482376ms","start":"2026-04-23T13:24:41.772493Z","end":"2026-04-23T13:24:41.948976Z","steps":["trace[1466135556] 'process raft request' (duration: 176.303044ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:43.576419Z","caller":"traceutil/trace.go:171","msg":"trace[832501886] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"188.997009ms","start":"2026-04-23T13:24:43.387377Z","end":"2026-04-23T13:24:43.576374Z","steps":["trace[832501886] 'process raft request' (duration: 188.700115ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:43.966625Z","caller":"traceutil/trace.go:171","msg":"trace[249588809] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"190.685751ms","start":"2026-04-23T13:24:43.775873Z","end":"2026-04-23T13:24:43.966559Z","steps":["trace[249588809] 'process raft request' (duration: 190.479828ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:44.228732Z","caller":"traceutil/trace.go:171","msg":"trace[356895850] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"220.932476ms","start":"2026-04-23T13:24:44.007773Z","end":"2026-04-23T13:24:44.228706Z","steps":["trace[356895850] 'process raft request' (duration: 125.58697ms)","trace[356895850] 'compare' (duration: 95.145033ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:24:44.228946Z","caller":"traceutil/trace.go:171","msg":"trace[708384115] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"219.579077ms","start":"2026-04-23T13:24:44.009334Z","end":"2026-04-23T13:24:44.228913Z","steps":["trace[708384115] 'process raft request' (duration: 219.325574ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:46.103131Z","caller":"traceutil/trace.go:171","msg":"trace[933201856] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"126.094466ms","start":"2026-04-23T13:24:45.976995Z","end":"2026-04-23T13:24:46.103089Z","steps":["trace[933201856] 'process raft request' (duration: 125.860472ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:47.204686Z","caller":"traceutil/trace.go:171","msg":"trace[762903086] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"118.260319ms","start":"2026-04-23T13:24:47.08638Z","end":"2026-04-23T13:24:47.204641Z","steps":["trace[762903086] 'process raft request' (duration: 117.810713ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:48.385424Z","caller":"traceutil/trace.go:171","msg":"trace[381792414] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"109.159504ms","start":"2026-04-23T13:24:48.276232Z","end":"2026-04-23T13:24:48.385392Z","steps":["trace[381792414] 'process raft request' (duration: 91.523212ms)","trace[381792414] 'compare' (duration: 17.47206ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:24:49.334624Z","caller":"traceutil/trace.go:171","msg":"trace[1592548250] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"119.913832ms","start":"2026-04-23T13:24:49.214679Z","end":"2026-04-23T13:24:49.334593Z","steps":["trace[1592548250] 'process raft request' (duration: 119.72108ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:50.901014Z","caller":"traceutil/trace.go:171","msg":"trace[1115173267] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"167.103058ms","start":"2026-04-23T13:24:50.733883Z","end":"2026-04-23T13:24:50.900987Z","steps":["trace[1115173267] 'process raft request' (duration: 166.924286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:24:52.565332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.448519ms","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-23T13:24:52.565405Z","caller":"traceutil/trace.go:171","msg":"trace[163670291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1409; }","duration":"155.585151ms","start":"2026-04-23T13:24:52.409805Z","end":"2026-04-23T13:24:52.56539Z","steps":["trace[163670291] 'range keys from in-memory index tree' (duration: 155.268886ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:24:59.844379Z","caller":"traceutil/trace.go:171","msg":"trace[1494424396] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"141.390496ms","start":"2026-04-23T13:24:59.702956Z","end":"2026-04-23T13:24:59.844347Z","steps":["trace[1494424396] 'process raft request' (duration: 141.182493ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:04.529371Z","caller":"traceutil/trace.go:171","msg":"trace[935583055] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"119.76291ms","start":"2026-04-23T13:25:04.409574Z","end":"2026-04-23T13:25:04.529337Z","steps":["trace[935583055] 'process raft request' (duration: 119.589018ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:04.629669Z","caller":"traceutil/trace.go:171","msg":"trace[1712385271] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1511; }","duration":"184.450466ms","start":"2026-04-23T13:25:04.445189Z","end":"2026-04-23T13:25:04.629639Z","steps":["trace[1712385271] 'read index received' (duration: 83.97794ms)","trace[1712385271] 'applied index is now lower than readState.Index' (duration: 100.471176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:04.629981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.773221ms","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-23T13:25:04.630068Z","caller":"traceutil/trace.go:171","msg":"trace[336643526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1449; }","duration":"184.913212ms","start":"2026-04-23T13:25:04.445141Z","end":"2026-04-23T13:25:04.630054Z","steps":["trace[336643526] 'agreement among raft nodes before linearized reading' (duration: 184.649839ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:04.630328Z","caller":"traceutil/trace.go:171","msg":"trace[1853758086] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"220.082534ms","start":"2026-04-23T13:25:04.409654Z","end":"2026-04-23T13:25:04.629737Z","steps":["trace[1853758086] 'process raft request' (duration: 207.070915ms)","trace[1853758086] 'compare' (duration: 12.772056ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:05.7862Z","caller":"traceutil/trace.go:171","msg":"trace[421647400] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"239.823235ms","start":"2026-04-23T13:25:05.546348Z","end":"2026-04-23T13:25:05.786171Z","steps":["trace[421647400] 'process raft request' (duration: 239.645472ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:06.138237Z","caller":"traceutil/trace.go:171","msg":"trace[1043944043] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"130.333675ms","start":"2026-04-23T13:25:06.007871Z","end":"2026-04-23T13:25:06.138205Z","steps":["trace[1043944043] 'process raft request' (duration: 130.132982ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:06.690116Z","caller":"traceutil/trace.go:171","msg":"trace[970067975] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"151.042629ms","start":"2026-04-23T13:25:06.539037Z","end":"2026-04-23T13:25:06.69008Z","steps":["trace[970067975] 'process raft request' (duration: 150.754665ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:07.101416Z","caller":"traceutil/trace.go:171","msg":"trace[1984396724] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"166.935156ms","start":"2026-04-23T13:25:06.93445Z","end":"2026-04-23T13:25:07.101385Z","steps":["trace[1984396724] 'process raft request' (duration: 166.744754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:08.904643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.246745ms","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-23T13:25:08.904767Z","caller":"traceutil/trace.go:171","msg":"trace[1404889304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1461; }","duration":"144.426137ms","start":"2026-04-23T13:25:08.760315Z","end":"2026-04-23T13:25:08.904741Z","steps":["trace[1404889304] 'range keys from in-memory index tree' (duration: 143.949751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:08.904741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.870797ms","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-23T13:25:08.904764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.410778ms","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-23T13:25:08.904865Z","caller":"traceutil/trace.go:171","msg":"trace[134245630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1461; }","duration":"151.08724ms","start":"2026-04-23T13:25:08.753752Z","end":"2026-04-23T13:25:08.904839Z","steps":["trace[134245630] 'range keys from in-memory index tree' (duration: 150.39764ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:08.904889Z","caller":"traceutil/trace.go:171","msg":"trace[1471214672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1461; }","duration":"144.56641ms","start":"2026-04-23T13:25:08.760293Z","end":"2026-04-23T13:25:08.904859Z","steps":["trace[1471214672] 'range keys from in-memory index tree' (duration: 144.141774ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:09.154584Z","caller":"traceutil/trace.go:171","msg":"trace[1599651113] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"244.336636ms","start":"2026-04-23T13:25:08.910214Z","end":"2026-04-23T13:25:09.154551Z","steps":["trace[1599651113] 'process raft request' (duration: 244.087392ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:09.154816Z","caller":"traceutil/trace.go:171","msg":"trace[2077082495] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"244.494328ms","start":"2026-04-23T13:25:08.910282Z","end":"2026-04-23T13:25:09.154776Z","steps":["trace[2077082495] 'process raft request' (duration: 244.221644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:10.600293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.917577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:25:10.60042Z","caller":"traceutil/trace.go:171","msg":"trace[2028442722] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1466; }","duration":"178.097739ms","start":"2026-04-23T13:25:10.422288Z","end":"2026-04-23T13:25:10.600386Z","steps":["trace[2028442722] 'count revisions from in-memory index tree' (duration: 177.821815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:11.120896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.397554ms","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-23T13:25:11.120989Z","caller":"traceutil/trace.go:171","msg":"trace[495261935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1467; }","duration":"162.565846ms","start":"2026-04-23T13:25:10.958402Z","end":"2026-04-23T13:25:11.120968Z","steps":["trace[495261935] 'range keys from in-memory index tree' (duration: 162.181941ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:11.122104Z","caller":"traceutil/trace.go:171","msg":"trace[1279765637] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"153.129438ms","start":"2026-04-23T13:25:10.968936Z","end":"2026-04-23T13:25:11.122065Z","steps":["trace[1279765637] 'process raft request' (duration: 152.919405ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:11.284079Z","caller":"traceutil/trace.go:171","msg":"trace[574152850] linearizableReadLoop","detail":"{readStateIndex:1534; appliedIndex:1533; }","duration":"124.571306ms","start":"2026-04-23T13:25:11.159466Z","end":"2026-04-23T13:25:11.284038Z","steps":["trace[574152850] 'read index received' (duration: 124.336673ms)","trace[574152850] 'applied index is now lower than readState.Index' (duration: 233.243µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:11.284162Z","caller":"traceutil/trace.go:171","msg":"trace[1284324808] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"157.77533ms","start":"2026-04-23T13:25:11.126347Z","end":"2026-04-23T13:25:11.284123Z","steps":["trace[1284324808] 'process raft request' (duration: 157.492736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:11.284355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.85202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-23T13:25:11.284415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.726008ms","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-23T13:25:11.284479Z","caller":"traceutil/trace.go:171","msg":"trace[477049597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1469; }","duration":"125.011873ms","start":"2026-04-23T13:25:11.159422Z","end":"2026-04-23T13:25:11.284434Z","steps":["trace[477049597] 'agreement among raft nodes before linearized reading' (duration: 124.746219ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:11.284486Z","caller":"traceutil/trace.go:171","msg":"trace[1059954682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1469; }","duration":"124.876609ms","start":"2026-04-23T13:25:11.159596Z","end":"2026-04-23T13:25:11.284473Z","steps":["trace[1059954682] 'agreement among raft nodes before linearized reading' (duration: 124.649826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:11.896284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.327899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:25:11.896752Z","caller":"traceutil/trace.go:171","msg":"trace[1333456290] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"606.339173ms","start":"2026-04-23T13:25:11.290387Z","end":"2026-04-23T13:25:11.896726Z","steps":["trace[1333456290] 'process raft request' (duration: 606.082509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:11.896834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:11.290366Z","time spent":"606.431554ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:25:11.896894Z","caller":"traceutil/trace.go:171","msg":"trace[1803591987] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"606.466684ms","start":"2026-04-23T13:25:11.290369Z","end":"2026-04-23T13:25:11.896836Z","steps":["trace[1803591987] 'process raft request' (duration: 214.497107ms)","trace[1803591987] 'compare' (duration: 391.115835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:11.89715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:11.290351Z","time spent":"606.667686ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:25:12.53002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.500392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:25:12.530291Z","caller":"traceutil/trace.go:171","msg":"trace[2078093339] linearizableReadLoop","detail":"{readStateIndex:1537; appliedIndex:1536; }","duration":"360.796701ms","start":"2026-04-23T13:25:12.169477Z","end":"2026-04-23T13:25:12.530274Z","steps":["trace[2078093339] 'read index received' (duration: 39.31µs)","trace[2078093339] 'applied index is now lower than readState.Index' (duration: 360.756011ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:12.530291Z","caller":"traceutil/trace.go:171","msg":"trace[137187956] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"558.591929ms","start":"2026-04-23T13:25:11.971666Z","end":"2026-04-23T13:25:12.530258Z","steps":["trace[137187956] 'process raft request' (duration: 128.737402ms)","trace[137187956] 'compare' (duration: 429.33429ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:12.530401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:11.971646Z","time spent":"558.703871ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:25:12.530496Z","caller":"traceutil/trace.go:171","msg":"trace[667638409] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"316.799779ms","start":"2026-04-23T13:25:12.213651Z","end":"2026-04-23T13:25:12.530451Z","steps":["trace[667638409] 'process raft request' (duration: 316.578185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:12.530545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.034173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:12.530679Z","caller":"traceutil/trace.go:171","msg":"trace[75309407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1473; }","duration":"361.243436ms","start":"2026-04-23T13:25:12.16941Z","end":"2026-04-23T13:25:12.530653Z","steps":["trace[75309407] 'agreement among raft nodes before linearized reading' (duration: 360.939433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:12.530707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:12.213615Z","time spent":"316.971231ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:25:12.53075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:12.169384Z","time spent":"361.350848ms","remote":"127.0.0.1:41012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:25:12.98066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.049201ms","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-23T13:25:12.980773Z","caller":"traceutil/trace.go:171","msg":"trace[26559172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1473; }","duration":"176.238184ms","start":"2026-04-23T13:25:12.804511Z","end":"2026-04-23T13:25:12.980749Z","steps":["trace[26559172] 'range keys from in-memory index tree' (duration: 175.721565ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:13.091364Z","caller":"traceutil/trace.go:171","msg":"trace[2037751267] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"105.566395ms","start":"2026-04-23T13:25:12.98576Z","end":"2026-04-23T13:25:13.091326Z","steps":["trace[2037751267] 'process raft request' (duration: 105.380532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:13.554636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.800726ms","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-23T13:25:13.55472Z","caller":"traceutil/trace.go:171","msg":"trace[1836263615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1474; }","duration":"264.936898ms","start":"2026-04-23T13:25:13.289763Z","end":"2026-04-23T13:25:13.5547Z","steps":["trace[1836263615] 'range keys from in-memory index tree' (duration: 264.532852ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:14.039369Z","caller":"traceutil/trace.go:171","msg":"trace[1412410001] linearizableReadLoop","detail":"{readStateIndex:1540; appliedIndex:1539; }","duration":"137.772366ms","start":"2026-04-23T13:25:13.901568Z","end":"2026-04-23T13:25:14.03934Z","steps":["trace[1412410001] 'read index received' (duration: 137.502853ms)","trace[1412410001] 'applied index is now lower than readState.Index' (duration: 268.063µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:14.039436Z","caller":"traceutil/trace.go:171","msg":"trace[1563287360] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"479.995412ms","start":"2026-04-23T13:25:13.559419Z","end":"2026-04-23T13:25:14.039414Z","steps":["trace[1563287360] 'process raft request' (duration: 479.743069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:14.039585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:13.559397Z","time spent":"480.089043ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:25:14.03961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.043061ms","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-23T13:25:14.039644Z","caller":"traceutil/trace.go:171","msg":"trace[1738241065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1475; }","duration":"138.135092ms","start":"2026-04-23T13:25:13.901499Z","end":"2026-04-23T13:25:14.039634Z","steps":["trace[1738241065] 'agreement among raft nodes before linearized reading' (duration: 137.982749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:14.039795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.681667ms","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-23T13:25:14.039892Z","caller":"traceutil/trace.go:171","msg":"trace[2129592934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1475; }","duration":"137.825838ms","start":"2026-04-23T13:25:13.902044Z","end":"2026-04-23T13:25:14.03987Z","steps":["trace[2129592934] 'agreement among raft nodes before linearized reading' (duration: 137.530003ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:14.267524Z","caller":"traceutil/trace.go:171","msg":"trace[226671328] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"223.714513ms","start":"2026-04-23T13:25:14.043782Z","end":"2026-04-23T13:25:14.267497Z","steps":["trace[226671328] 'process raft request' (duration: 211.682129ms)","trace[226671328] 'compare' (duration: 11.618359ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:14.271152Z","caller":"traceutil/trace.go:171","msg":"trace[1902887005] linearizableReadLoop","detail":"{readStateIndex:1542; appliedIndex:1540; }","duration":"103.989624ms","start":"2026-04-23T13:25:14.167132Z","end":"2026-04-23T13:25:14.271122Z","steps":["trace[1902887005] 'read index received' (duration: 88.318629ms)","trace[1902887005] 'applied index is now lower than readState.Index' (duration: 15.669725ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:14.271224Z","caller":"traceutil/trace.go:171","msg":"trace[103938602] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"225.903403ms","start":"2026-04-23T13:25:14.045284Z","end":"2026-04-23T13:25:14.271188Z","steps":["trace[103938602] 'process raft request' (duration: 225.65109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:14.27139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.236737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:14.271435Z","caller":"traceutil/trace.go:171","msg":"trace[733325915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1477; }","duration":"104.340088ms","start":"2026-04-23T13:25:14.167083Z","end":"2026-04-23T13:25:14.271423Z","steps":["trace[733325915] 'agreement among raft nodes before linearized reading' (duration: 104.234037ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:14.463067Z","caller":"traceutil/trace.go:171","msg":"trace[1871055737] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"148.99931ms","start":"2026-04-23T13:25:14.314029Z","end":"2026-04-23T13:25:14.463028Z","steps":["trace[1871055737] 'process raft request' (duration: 147.650772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:15.054182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.31863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:25:15.054375Z","caller":"traceutil/trace.go:171","msg":"trace[507033159] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"513.771765ms","start":"2026-04-23T13:25:14.540585Z","end":"2026-04-23T13:25:15.054357Z","steps":["trace[507033159] 'process raft request' (duration: 513.711524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:15.054455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:14.540554Z","time spent":"513.866556ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:25:15.054427Z","caller":"traceutil/trace.go:171","msg":"trace[1333130192] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"518.362007ms","start":"2026-04-23T13:25:14.536013Z","end":"2026-04-23T13:25:15.054375Z","steps":["trace[1333130192] 'process raft request' (duration: 315.754204ms)","trace[1333130192] 'compare' (duration: 202.167408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:15.054614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:14.535983Z","time spent":"518.53644ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:25:15.418871Z","caller":"traceutil/trace.go:171","msg":"trace[870076972] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1546; }","duration":"277.43219ms","start":"2026-04-23T13:25:15.141402Z","end":"2026-04-23T13:25:15.418834Z","steps":["trace[870076972] 'read index received' (duration: 277.142495ms)","trace[870076972] 'applied index is now lower than readState.Index' (duration: 288.175µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:15.419079Z","caller":"traceutil/trace.go:171","msg":"trace[1105178933] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"318.854807ms","start":"2026-04-23T13:25:15.10019Z","end":"2026-04-23T13:25:15.419044Z","steps":["trace[1105178933] 'process raft request' (duration: 318.380609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:15.419116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.682613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:25:15.419269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:15.100136Z","time spent":"319.031618ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:25:15.419283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.882775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:15.419332Z","caller":"traceutil/trace.go:171","msg":"trace[1736443692] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1481; }","duration":"277.970727ms","start":"2026-04-23T13:25:15.14133Z","end":"2026-04-23T13:25:15.419301Z","steps":["trace[1736443692] 'agreement among raft nodes before linearized reading' (duration: 277.703323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:15.419323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.732141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:15.419418Z","caller":"traceutil/trace.go:171","msg":"trace[1177021602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1481; }","duration":"251.079458ms","start":"2026-04-23T13:25:15.168313Z","end":"2026-04-23T13:25:15.419392Z","steps":["trace[1177021602] 'agreement among raft nodes before linearized reading' (duration: 250.850845ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:15.419493Z","caller":"traceutil/trace.go:171","msg":"trace[1817612652] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1481; }","duration":"206.992055ms","start":"2026-04-23T13:25:15.212478Z","end":"2026-04-23T13:25:15.41947Z","steps":["trace[1817612652] 'agreement among raft nodes before linearized reading' (duration: 206.712421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:15.864591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.08186ms","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-23T13:25:15.86469Z","caller":"traceutil/trace.go:171","msg":"trace[1522824032] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1481; }","duration":"319.268613ms","start":"2026-04-23T13:25:15.545401Z","end":"2026-04-23T13:25:15.86467Z","steps":["trace[1522824032] 'count revisions from in-memory index tree' (duration: 318.837307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:15.864739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:15.54537Z","time spent":"319.358293ms","remote":"127.0.0.1:41314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-23T13:25:16.304083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.851696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:16.304196Z","caller":"traceutil/trace.go:171","msg":"trace[1163041937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1482; }","duration":"135.02298ms","start":"2026-04-23T13:25:16.169146Z","end":"2026-04-23T13:25:16.304169Z","steps":["trace[1163041937] 'range keys from in-memory index tree' (duration: 134.676944ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:16.422465Z","caller":"traceutil/trace.go:171","msg":"trace[556283860] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"113.981001ms","start":"2026-04-23T13:25:16.308464Z","end":"2026-04-23T13:25:16.422445Z","steps":["trace[556283860] 'process raft request' (duration: 113.807499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:16.770662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.944779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:25:16.770799Z","caller":"traceutil/trace.go:171","msg":"trace[1790785444] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"461.604661ms","start":"2026-04-23T13:25:16.309169Z","end":"2026-04-23T13:25:16.770773Z","steps":["trace[1790785444] 'process raft request' (duration: 231.417009ms)","trace[1790785444] 'compare' (duration: 229.642455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:16.770886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:16.309142Z","time spent":"461.711403ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:25:17.423294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.202333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T13:25:17.423367Z","caller":"traceutil/trace.go:171","msg":"trace[940466792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1484; }","duration":"363.328775ms","start":"2026-04-23T13:25:17.060021Z","end":"2026-04-23T13:25:17.423349Z","steps":["trace[940466792] 'range keys from in-memory index tree' (duration: 363.001251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:17.423399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:17.059982Z","time spent":"363.410956ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T13:25:17.423529Z","caller":"traceutil/trace.go:171","msg":"trace[1750918845] linearizableReadLoop","detail":"{readStateIndex:1551; appliedIndex:1550; }","duration":"254.427724ms","start":"2026-04-23T13:25:17.169068Z","end":"2026-04-23T13:25:17.423496Z","steps":["trace[1750918845] 'read index received' (duration: 197.025638ms)","trace[1750918845] 'applied index is now lower than readState.Index' (duration: 57.399806ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:17.423547Z","caller":"traceutil/trace.go:171","msg":"trace[1587532374] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"363.277944ms","start":"2026-04-23T13:25:17.060224Z","end":"2026-04-23T13:25:17.423502Z","steps":["trace[1587532374] 'process raft request' (duration: 305.849788ms)","trace[1587532374] 'compare' (duration: 57.133972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:17.423691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.589546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:17.423787Z","caller":"traceutil/trace.go:171","msg":"trace[1358429944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1485; }","duration":"254.735498ms","start":"2026-04-23T13:25:17.169029Z","end":"2026-04-23T13:25:17.423765Z","steps":["trace[1358429944] 'agreement among raft nodes before linearized reading' (duration: 254.570245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:17.423794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:17.060181Z","time spent":"363.452817ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:25:17.919339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.324474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:25:17.919615Z","caller":"traceutil/trace.go:171","msg":"trace[328987696] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"491.376009ms","start":"2026-04-23T13:25:17.428219Z","end":"2026-04-23T13:25:17.919595Z","steps":["trace[328987696] 'process raft request' (duration: 491.240247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:17.919675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:17.428196Z","time spent":"491.45292ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:25:17.919688Z","caller":"traceutil/trace.go:171","msg":"trace[1397955660] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"674.00102ms","start":"2026-04-23T13:25:17.24566Z","end":"2026-04-23T13:25:17.919661Z","steps":["trace[1397955660] 'process raft request' (duration: 432.247259ms)","trace[1397955660] 'compare' (duration: 241.100311ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:17.919666Z","caller":"traceutil/trace.go:171","msg":"trace[255215185] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"496.031933ms","start":"2026-04-23T13:25:17.423598Z","end":"2026-04-23T13:25:17.91963Z","steps":["trace[255215185] 'read index received' (duration: 254.324623ms)","trace[255215185] 'applied index is now lower than readState.Index' (duration: 241.70432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:17.919815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:17.245624Z","time spent":"674.125442ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:25:17.919905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.529485ms","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-23T13:25:17.919949Z","caller":"traceutil/trace.go:171","msg":"trace[973975017] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1487; }","duration":"357.602936ms","start":"2026-04-23T13:25:17.562331Z","end":"2026-04-23T13:25:17.919934Z","steps":["trace[973975017] 'agreement among raft nodes before linearized reading' (duration: 357.491535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:17.919988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:17.562265Z","time spent":"357.713259ms","remote":"127.0.0.1:45454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T13:25:17.920015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.427708ms","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-23T13:25:17.920117Z","caller":"traceutil/trace.go:171","msg":"trace[2120714743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1487; }","duration":"496.59666ms","start":"2026-04-23T13:25:17.423496Z","end":"2026-04-23T13:25:17.920093Z","steps":["trace[2120714743] 'agreement among raft nodes before linearized reading' (duration: 496.285346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:17.920173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:17.423476Z","time spent":"496.686941ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T13:25:18.503446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.610742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:25:18.503561Z","caller":"traceutil/trace.go:171","msg":"trace[1315990271] linearizableReadLoop","detail":"{readStateIndex:1554; appliedIndex:1553; }","duration":"412.236115ms","start":"2026-04-23T13:25:18.091306Z","end":"2026-04-23T13:25:18.503542Z","steps":["trace[1315990271] 'read index received' (duration: 23.36917ms)","trace[1315990271] 'applied index is now lower than readState.Index' (duration: 388.865255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:18.504165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.279588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:18.504315Z","caller":"traceutil/trace.go:171","msg":"trace[269776480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1488; }","duration":"334.495861ms","start":"2026-04-23T13:25:18.169794Z","end":"2026-04-23T13:25:18.50429Z","steps":["trace[269776480] 'agreement among raft nodes before linearized reading' (duration: 334.277188ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:18.50435Z","caller":"traceutil/trace.go:171","msg":"trace[244911588] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"578.040735ms","start":"2026-04-23T13:25:17.926276Z","end":"2026-04-23T13:25:18.504317Z","steps":["trace[244911588] 'process raft request' (duration: 188.42557ms)","trace[244911588] 'compare' (duration: 388.394328ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:18.504344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.045796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-23T13:25:18.504399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:18.169764Z","time spent":"334.619532ms","remote":"127.0.0.1:41012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T13:25:18.504488Z","caller":"traceutil/trace.go:171","msg":"trace[1072507848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1488; }","duration":"413.240388ms","start":"2026-04-23T13:25:18.091235Z","end":"2026-04-23T13:25:18.504475Z","steps":["trace[1072507848] 'agreement among raft nodes before linearized reading' (duration: 412.987135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:18.504527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:18.091209Z","time spent":"413.30887ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T13:25:18.504541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:17.92624Z","time spent":"578.165427ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:25:18.781478Z","caller":"traceutil/trace.go:171","msg":"trace[1123129749] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"267.609664ms","start":"2026-04-23T13:25:18.513825Z","end":"2026-04-23T13:25:18.781435Z","steps":["trace[1123129749] 'process raft request' (duration: 248.38831ms)","trace[1123129749] 'compare' (duration: 18.964151ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:18.781832Z","caller":"traceutil/trace.go:171","msg":"trace[1670285732] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"267.926068ms","start":"2026-04-23T13:25:18.513834Z","end":"2026-04-23T13:25:18.78176Z","steps":["trace[1670285732] 'process raft request' (duration: 267.537653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:19.201282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.003096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:25:19.201422Z","caller":"traceutil/trace.go:171","msg":"trace[1240964803] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"413.421101ms","start":"2026-04-23T13:25:18.787979Z","end":"2026-04-23T13:25:19.201401Z","steps":["trace[1240964803] 'process raft request' (duration: 195.195432ms)","trace[1240964803] 'compare' (duration: 217.848494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:19.201496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:18.787946Z","time spent":"413.520902ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:25:19.674056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.004421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:19.674159Z","caller":"traceutil/trace.go:171","msg":"trace[1929817746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1491; }","duration":"367.201981ms","start":"2026-04-23T13:25:19.306936Z","end":"2026-04-23T13:25:19.674137Z","steps":["trace[1929817746] 'range keys from in-memory index tree' (duration: 366.868295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:19.67421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:19.306908Z","time spent":"367.291565ms","remote":"127.0.0.1:41012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T13:25:19.676824Z","caller":"traceutil/trace.go:171","msg":"trace[1484852375] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"246.84698ms","start":"2026-04-23T13:25:19.429949Z","end":"2026-04-23T13:25:19.676796Z","steps":["trace[1484852375] 'process raft request' (duration: 246.660271ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:21.44988Z","caller":"traceutil/trace.go:171","msg":"trace[1573974929] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"240.269202ms","start":"2026-04-23T13:25:21.209578Z","end":"2026-04-23T13:25:21.449847Z","steps":["trace[1573974929] 'process raft request' (duration: 240.059332ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:21.813743Z","caller":"traceutil/trace.go:171","msg":"trace[209204673] linearizableReadLoop","detail":"{readStateIndex:1565; appliedIndex:1564; }","duration":"303.766543ms","start":"2026-04-23T13:25:21.509941Z","end":"2026-04-23T13:25:21.813708Z","steps":["trace[209204673] 'read index received' (duration: 290.336446ms)","trace[209204673] 'applied index is now lower than readState.Index' (duration: 13.428597ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:21.813834Z","caller":"traceutil/trace.go:171","msg":"trace[649598525] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"534.540218ms","start":"2026-04-23T13:25:21.279245Z","end":"2026-04-23T13:25:21.813785Z","steps":["trace[649598525] 'process raft request' (duration: 521.013336ms)","trace[649598525] 'compare' (duration: 13.27861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:21.813912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.939271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:21.813956Z","caller":"traceutil/trace.go:171","msg":"trace[895630811] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1498; }","duration":"304.058166ms","start":"2026-04-23T13:25:21.509882Z","end":"2026-04-23T13:25:21.813941Z","steps":["trace[895630811] 'agreement among raft nodes before linearized reading' (duration: 303.955132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:21.814005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:21.509863Z","time spent":"304.13079ms","remote":"127.0.0.1:45346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-23T13:25:21.814034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:21.27922Z","time spent":"534.709916ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:25:21.906127Z","caller":"traceutil/trace.go:171","msg":"trace[2037159678] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"223.162419ms","start":"2026-04-23T13:25:21.682927Z","end":"2026-04-23T13:25:21.906089Z","steps":["trace[2037159678] 'process raft request' (duration: 222.924657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:22.762928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.551725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T13:25:22.763035Z","caller":"traceutil/trace.go:171","msg":"trace[1229824833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1499; }","duration":"826.719672ms","start":"2026-04-23T13:25:21.936289Z","end":"2026-04-23T13:25:22.763008Z","steps":["trace[1229824833] 'range keys from in-memory index tree' (duration: 826.277951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:22.763079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:21.936262Z","time spent":"826.808146ms","remote":"127.0.0.1:41292","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-23T13:25:22.763058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.120575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T13:25:22.763081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.482528ms","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-23T13:25:22.763168Z","caller":"traceutil/trace.go:171","msg":"trace[697930730] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1499; }","duration":"257.293323ms","start":"2026-04-23T13:25:22.505848Z","end":"2026-04-23T13:25:22.763141Z","steps":["trace[697930730] 'count revisions from in-memory index tree' (duration: 257.02559ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:25:22.76321Z","caller":"traceutil/trace.go:171","msg":"trace[259515370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1499; }","duration":"242.700299ms","start":"2026-04-23T13:25:22.52048Z","end":"2026-04-23T13:25:22.76318Z","steps":["trace[259515370] 'range keys from in-memory index tree' (duration: 242.199305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:22.763309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.790843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:22.76335Z","caller":"traceutil/trace.go:171","msg":"trace[1004612515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1499; }","duration":"593.888668ms","start":"2026-04-23T13:25:22.169451Z","end":"2026-04-23T13:25:22.763339Z","steps":["trace[1004612515] 'range keys from in-memory index tree' (duration: 593.627746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:22.763389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:22.16942Z","time spent":"593.960211ms","remote":"127.0.0.1:41012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T13:25:23.040426Z","caller":"traceutil/trace.go:171","msg":"trace[402353099] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"271.97867ms","start":"2026-04-23T13:25:22.76842Z","end":"2026-04-23T13:25:23.040399Z","steps":["trace[402353099] 'process raft request' (duration: 271.785541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:23.617689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.771693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:25:23.617817Z","caller":"traceutil/trace.go:171","msg":"trace[826954812] linearizableReadLoop","detail":"{readStateIndex:1568; appliedIndex:1566; }","duration":"820.643369ms","start":"2026-04-23T13:25:22.797153Z","end":"2026-04-23T13:25:23.617796Z","steps":["trace[826954812] 'read index received' (duration: 243.133461ms)","trace[826954812] 'applied index is now lower than readState.Index' (duration: 577.508308ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:25:23.617879Z","caller":"traceutil/trace.go:171","msg":"trace[1927163554] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"849.350402ms","start":"2026-04-23T13:25:22.768489Z","end":"2026-04-23T13:25:23.617839Z","steps":["trace[1927163554] 'process raft request' (duration: 450.328467ms)","trace[1927163554] 'compare' (duration: 398.378275ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:25:23.618064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.888302ms","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-23T13:25:23.618143Z","caller":"traceutil/trace.go:171","msg":"trace[1552887092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1501; }","duration":"821.049549ms","start":"2026-04-23T13:25:22.797077Z","end":"2026-04-23T13:25:23.618127Z","steps":["trace[1552887092] 'agreement among raft nodes before linearized reading' (duration: 820.770305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:23.618188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:22.797054Z","time spent":"821.126213ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T13:25:23.618182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.995096ms","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-23T13:25:23.618304Z","caller":"traceutil/trace.go:171","msg":"trace[934092235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1501; }","duration":"164.176355ms","start":"2026-04-23T13:25:23.454097Z","end":"2026-04-23T13:25:23.618273Z","steps":["trace[934092235] 'agreement among raft nodes before linearized reading' (duration: 163.855078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:23.618088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.942885ms","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-23T13:25:23.618464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:22.768456Z","time spent":"849.521191ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:25:23.618494Z","caller":"traceutil/trace.go:171","msg":"trace[837066562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1501; }","duration":"820.335555ms","start":"2026-04-23T13:25:22.798102Z","end":"2026-04-23T13:25:23.618438Z","steps":["trace[837066562] 'agreement among raft nodes before linearized reading' (duration: 819.817459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:23.618544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:22.798088Z","time spent":"820.443829ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T13:25:30.484905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.420036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:30.485025Z","caller":"traceutil/trace.go:171","msg":"trace[32107478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1525; }","duration":"315.626397ms","start":"2026-04-23T13:25:30.169381Z","end":"2026-04-23T13:25:30.485008Z","steps":["trace[32107478] 'range keys from in-memory index tree' (duration: 315.297351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:25:30.485098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:25:30.169351Z","time spent":"315.738242ms","remote":"127.0.0.1:41012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:25:57.622759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.652802ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:25:57.622961Z","caller":"traceutil/trace.go:171","msg":"trace[717660911] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1613; }","duration":"244.872166ms","start":"2026-04-23T13:25:57.37805Z","end":"2026-04-23T13:25:57.622922Z","steps":["trace[717660911] 'range keys from in-memory index tree' (duration: 244.618242ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:02.830472Z","caller":"traceutil/trace.go:171","msg":"trace[619663937] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"133.083118ms","start":"2026-04-23T13:26:02.697356Z","end":"2026-04-23T13:26:02.830439Z","steps":["trace[619663937] 'process raft request' (duration: 132.926576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:03.338227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.333129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:03.338336Z","caller":"traceutil/trace.go:171","msg":"trace[507557238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1632; }","duration":"169.475631ms","start":"2026-04-23T13:26:03.168832Z","end":"2026-04-23T13:26:03.338308Z","steps":["trace[507557238] 'range keys from in-memory index tree' (duration: 169.256497ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:03.594853Z","caller":"traceutil/trace.go:171","msg":"trace[580567923] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"241.198605ms","start":"2026-04-23T13:26:03.353622Z","end":"2026-04-23T13:26:03.594821Z","steps":["trace[580567923] 'process raft request' (duration: 240.987992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:03.990441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.821271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T13:26:03.990556Z","caller":"traceutil/trace.go:171","msg":"trace[1551524035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1633; }","duration":"136.973533ms","start":"2026-04-23T13:26:03.853558Z","end":"2026-04-23T13:26:03.990532Z","steps":["trace[1551524035] 'range keys from in-memory index tree' (duration: 136.648218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:03.990535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.897799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T13:26:03.990658Z","caller":"traceutil/trace.go:171","msg":"trace[267195331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1633; }","duration":"142.119863ms","start":"2026-04-23T13:26:03.848511Z","end":"2026-04-23T13:26:03.990631Z","steps":["trace[267195331] 'range keys from in-memory index tree' (duration: 141.657065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:03.990805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.012894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T13:26:03.990872Z","caller":"traceutil/trace.go:171","msg":"trace[897990705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1633; }","duration":"138.118416ms","start":"2026-04-23T13:26:03.852737Z","end":"2026-04-23T13:26:03.990855Z","steps":["trace[897990705] 'range keys from in-memory index tree' (duration: 137.870212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:03.990863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.637954ms","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-23T13:26:03.990985Z","caller":"traceutil/trace.go:171","msg":"trace[999001350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1633; }","duration":"141.795506ms","start":"2026-04-23T13:26:03.849159Z","end":"2026-04-23T13:26:03.990955Z","steps":["trace[999001350] 'range keys from in-memory index tree' (duration: 141.42934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:09.897356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.635928ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T13:26:09.897481Z","caller":"traceutil/trace.go:171","msg":"trace[351122484] linearizableReadLoop","detail":"{readStateIndex:1729; appliedIndex:1728; }","duration":"276.319683ms","start":"2026-04-23T13:26:09.621142Z","end":"2026-04-23T13:26:09.897462Z","steps":["trace[351122484] 'read index received' (duration: 51.271µs)","trace[351122484] 'applied index is now lower than readState.Index' (duration: 276.266712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:09.897651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.68042ms","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-23T13:26:09.897674Z","caller":"traceutil/trace.go:171","msg":"trace[505332721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1652; }","duration":"276.751172ms","start":"2026-04-23T13:26:09.620912Z","end":"2026-04-23T13:26:09.897663Z","steps":["trace[505332721] 'agreement among raft nodes before linearized reading' (duration: 276.595209ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:10.147269Z","caller":"traceutil/trace.go:171","msg":"trace[212168949] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"244.979159ms","start":"2026-04-23T13:26:09.902254Z","end":"2026-04-23T13:26:10.147234Z","steps":["trace[212168949] 'process raft request' (duration: 244.636702ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:10.404975Z","caller":"traceutil/trace.go:171","msg":"trace[1904505382] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"255.492751ms","start":"2026-04-23T13:26:10.149441Z","end":"2026-04-23T13:26:10.404934Z","steps":["trace[1904505382] 'process raft request' (duration: 157.55345ms)","trace[1904505382] 'compare' (duration: 97.638415ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:26:10.444682Z","caller":"traceutil/trace.go:171","msg":"trace[651663615] linearizableReadLoop","detail":"{readStateIndex:1735; appliedIndex:1730; }","duration":"277.406134ms","start":"2026-04-23T13:26:10.167248Z","end":"2026-04-23T13:26:10.444654Z","steps":["trace[651663615] 'read index received' (duration: 139.843499ms)","trace[651663615] 'applied index is now lower than readState.Index' (duration: 137.560575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:10.444817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.551357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:10.444863Z","caller":"traceutil/trace.go:171","msg":"trace[1657305489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1658; }","duration":"277.647639ms","start":"2026-04-23T13:26:10.167199Z","end":"2026-04-23T13:26:10.444846Z","steps":["trace[1657305489] 'agreement among raft nodes before linearized reading' (duration: 277.562518ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:10.445116Z","caller":"traceutil/trace.go:171","msg":"trace[1706856489] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"293.021696ms","start":"2026-04-23T13:26:10.152081Z","end":"2026-04-23T13:26:10.445103Z","steps":["trace[1706856489] 'process raft request' (duration: 292.22928ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:10.445167Z","caller":"traceutil/trace.go:171","msg":"trace[1721233810] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"292.692959ms","start":"2026-04-23T13:26:10.15243Z","end":"2026-04-23T13:26:10.445123Z","steps":["trace[1721233810] 'process raft request' (duration: 292.087177ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:10.445265Z","caller":"traceutil/trace.go:171","msg":"trace[730396667] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"292.21536ms","start":"2026-04-23T13:26:10.15304Z","end":"2026-04-23T13:26:10.445255Z","steps":["trace[730396667] 'process raft request' (duration: 291.510686ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:10.445429Z","caller":"traceutil/trace.go:171","msg":"trace[1103637962] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"293.221309ms","start":"2026-04-23T13:26:10.152189Z","end":"2026-04-23T13:26:10.445411Z","steps":["trace[1103637962] 'process raft request' (duration: 292.28296ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:16.343007Z","caller":"traceutil/trace.go:171","msg":"trace[442118489] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"165.981882ms","start":"2026-04-23T13:26:16.176984Z","end":"2026-04-23T13:26:16.342966Z","steps":["trace[442118489] 'process raft request' (duration: 165.811589ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:16.829921Z","caller":"traceutil/trace.go:171","msg":"trace[348658321] linearizableReadLoop","detail":"{readStateIndex:1752; appliedIndex:1751; }","duration":"359.728173ms","start":"2026-04-23T13:26:16.470165Z","end":"2026-04-23T13:26:16.829893Z","steps":["trace[348658321] 'read index received' (duration: 359.433477ms)","trace[348658321] 'applied index is now lower than readState.Index' (duration: 293.876µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:26:16.830075Z","caller":"traceutil/trace.go:171","msg":"trace[1405824276] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"402.663632ms","start":"2026-04-23T13:26:16.427398Z","end":"2026-04-23T13:26:16.830061Z","steps":["trace[1405824276] 'process raft request' (duration: 402.318175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:16.830214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.42737Z","time spent":"402.736594ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:16.830452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.867877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T13:26:16.830552Z","caller":"traceutil/trace.go:171","msg":"trace[1284521832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1674; }","duration":"356.99901ms","start":"2026-04-23T13:26:16.47353Z","end":"2026-04-23T13:26:16.830529Z","steps":["trace[1284521832] 'agreement among raft nodes before linearized reading' (duration: 356.733485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:16.8306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.473514Z","time spent":"357.077522ms","remote":"127.0.0.1:41292","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-23T13:26:16.830638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.474947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T13:26:16.830691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.793755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T13:26:16.830731Z","caller":"traceutil/trace.go:171","msg":"trace[296693711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1674; }","duration":"360.598369ms","start":"2026-04-23T13:26:16.47011Z","end":"2026-04-23T13:26:16.830709Z","steps":["trace[296693711] 'agreement among raft nodes before linearized reading' (duration: 360.390035ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:16.830754Z","caller":"traceutil/trace.go:171","msg":"trace[811640484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1674; }","duration":"356.927409ms","start":"2026-04-23T13:26:16.47381Z","end":"2026-04-23T13:26:16.830737Z","steps":["trace[811640484] 'agreement among raft nodes before linearized reading' (duration: 356.734525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:16.830777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.470087Z","time spent":"360.681692ms","remote":"127.0.0.1:41292","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-23T13:26:16.830794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.473776Z","time spent":"357.01085ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T13:26:16.830846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.569323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T13:26:16.830971Z","caller":"traceutil/trace.go:171","msg":"trace[1304842847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1674; }","duration":"355.801157ms","start":"2026-04-23T13:26:16.475137Z","end":"2026-04-23T13:26:16.830938Z","steps":["trace[1304842847] 'agreement among raft nodes before linearized reading' (duration: 355.202005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:16.831087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.475113Z","time spent":"355.954229ms","remote":"127.0.0.1:41292","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-23T13:26:17.271257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.762798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:26:17.271565Z","caller":"traceutil/trace.go:171","msg":"trace[486777479] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"435.014175ms","start":"2026-04-23T13:26:16.836527Z","end":"2026-04-23T13:26:17.271541Z","steps":["trace[486777479] 'process raft request' (duration: 434.968964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:17.271652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.8365Z","time spent":"435.116478ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:26:17.271844Z","caller":"traceutil/trace.go:171","msg":"trace[721503953] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"435.842913ms","start":"2026-04-23T13:26:16.835953Z","end":"2026-04-23T13:26:17.271796Z","steps":["trace[721503953] 'process raft request' (duration: 435.443845ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:17.271906Z","caller":"traceutil/trace.go:171","msg":"trace[229827674] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"435.851843ms","start":"2026-04-23T13:26:16.835988Z","end":"2026-04-23T13:26:17.27184Z","steps":["trace[229827674] 'process raft request' (duration: 435.470995ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:17.27186Z","caller":"traceutil/trace.go:171","msg":"trace[1149264370] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"436.182369ms","start":"2026-04-23T13:26:16.835632Z","end":"2026-04-23T13:26:17.271814Z","steps":["trace[1149264370] 'process raft request' (duration: 295.739898ms)","trace[1149264370] 'compare' (duration: 139.604225ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:17.272097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.835935Z","time spent":"436.032136ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:17.272143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.835966Z","time spent":"436.060097ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:17.272374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:16.835605Z","time spent":"436.453634ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:26:23.035323Z","caller":"traceutil/trace.go:171","msg":"trace[460702701] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"179.976294ms","start":"2026-04-23T13:26:22.855316Z","end":"2026-04-23T13:26:23.035292Z","steps":["trace[460702701] 'process raft request' (duration: 179.799611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:23.335247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.820767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:26:23.335412Z","caller":"traceutil/trace.go:171","msg":"trace[1503332917] linearizableReadLoop","detail":"{readStateIndex:1774; appliedIndex:1773; }","duration":"165.692038ms","start":"2026-04-23T13:26:23.169697Z","end":"2026-04-23T13:26:23.335389Z","steps":["trace[1503332917] 'read index received' (duration: 87.522µs)","trace[1503332917] 'applied index is now lower than readState.Index' (duration: 165.602716ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:26:23.335481Z","caller":"traceutil/trace.go:171","msg":"trace[1258198137] transaction","detail":"{read_only:false; response_revision:1695; number_of_response:1; }","duration":"402.941328ms","start":"2026-04-23T13:26:22.932499Z","end":"2026-04-23T13:26:23.335441Z","steps":["trace[1258198137] 'process raft request' (duration: 203.827365ms)","trace[1258198137] 'compare' (duration: 198.664544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:23.335758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:22.932433Z","time spent":"403.133691ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:23.335524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.84165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:23.335915Z","caller":"traceutil/trace.go:171","msg":"trace[1886775704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1695; }","duration":"166.265169ms","start":"2026-04-23T13:26:23.16962Z","end":"2026-04-23T13:26:23.335885Z","steps":["trace[1886775704] 'agreement among raft nodes before linearized reading' (duration: 165.869731ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:23.591289Z","caller":"traceutil/trace.go:171","msg":"trace[430220542] transaction","detail":"{read_only:false; response_revision:1697; number_of_response:1; }","duration":"250.315972ms","start":"2026-04-23T13:26:23.340952Z","end":"2026-04-23T13:26:23.591268Z","steps":["trace[430220542] 'process raft request' (duration: 250.113188ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:23.591527Z","caller":"traceutil/trace.go:171","msg":"trace[1299986955] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"250.477444ms","start":"2026-04-23T13:26:23.341042Z","end":"2026-04-23T13:26:23.591519Z","steps":["trace[1299986955] 'process raft request' (duration: 250.187909ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:23.591565Z","caller":"traceutil/trace.go:171","msg":"trace[376626880] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"250.431164ms","start":"2026-04-23T13:26:23.341112Z","end":"2026-04-23T13:26:23.591543Z","steps":["trace[376626880] 'process raft request' (duration: 250.351712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:28.949483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.721549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:26:28.949575Z","caller":"traceutil/trace.go:171","msg":"trace[860792954] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1713; }","duration":"280.893642ms","start":"2026-04-23T13:26:28.668663Z","end":"2026-04-23T13:26:28.949557Z","steps":["trace[860792954] 'count revisions from in-memory index tree' (duration: 280.527464ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:29.161821Z","caller":"traceutil/trace.go:171","msg":"trace[640565299] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"106.147521ms","start":"2026-04-23T13:26:29.055639Z","end":"2026-04-23T13:26:29.161787Z","steps":["trace[640565299] 'process raft request' (duration: 105.968397ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:29.498368Z","caller":"traceutil/trace.go:171","msg":"trace[676890674] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"396.81071ms","start":"2026-04-23T13:26:29.101531Z","end":"2026-04-23T13:26:29.498341Z","steps":["trace[676890674] 'process raft request' (duration: 374.064043ms)","trace[676890674] 'compare' (duration: 22.450512ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:26:29.498353Z","caller":"traceutil/trace.go:171","msg":"trace[351814657] linearizableReadLoop","detail":"{readStateIndex:1795; appliedIndex:1794; }","duration":"331.23672ms","start":"2026-04-23T13:26:29.167071Z","end":"2026-04-23T13:26:29.498307Z","steps":["trace[351814657] 'read index received' (duration: 308.507273ms)","trace[351814657] 'applied index is now lower than readState.Index' (duration: 22.726947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:29.498507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.101506Z","time spent":"396.924992ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:29.49852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.444043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:29.498639Z","caller":"traceutil/trace.go:171","msg":"trace[1996280170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1715; }","duration":"331.606487ms","start":"2026-04-23T13:26:29.167015Z","end":"2026-04-23T13:26:29.498621Z","steps":["trace[1996280170] 'agreement among raft nodes before linearized reading' (duration: 331.445114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:29.498693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.166991Z","time spent":"331.683859ms","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:26:29.498746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.284676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:26:29.498816Z","caller":"traceutil/trace.go:171","msg":"trace[1067348303] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1715; }","duration":"299.442759ms","start":"2026-04-23T13:26:29.19935Z","end":"2026-04-23T13:26:29.498793Z","steps":["trace[1067348303] 'agreement among raft nodes before linearized reading' (duration: 299.208875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:29.49896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.819148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/rook-ceph/\" range_end:\"/registry/secrets/rook-ceph0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:29.498981Z","caller":"traceutil/trace.go:171","msg":"trace[53679689] range","detail":"{range_begin:/registry/secrets/rook-ceph/; range_end:/registry/secrets/rook-ceph0; response_count:0; response_revision:1715; }","duration":"127.883429ms","start":"2026-04-23T13:26:29.371092Z","end":"2026-04-23T13:26:29.498975Z","steps":["trace[53679689] 'agreement among raft nodes before linearized reading' (duration: 127.851459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:29.843355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.481504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:26:29.843766Z","caller":"traceutil/trace.go:171","msg":"trace[1871200162] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"340.802775ms","start":"2026-04-23T13:26:29.502926Z","end":"2026-04-23T13:26:29.843729Z","steps":["trace[1871200162] 'process raft request' (duration: 111.846121ms)","trace[1871200162] 'compare' (duration: 228.081566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:29.843885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.5029Z","time spent":"340.932907ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:30.133178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.683521ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T13:26:30.133279Z","caller":"traceutil/trace.go:171","msg":"trace[1941676673] linearizableReadLoop","detail":"{readStateIndex:1797; appliedIndex:1795; }","duration":"514.672087ms","start":"2026-04-23T13:26:29.618592Z","end":"2026-04-23T13:26:30.133264Z","steps":["trace[1941676673] 'read index received' (duration: 178.336549ms)","trace[1941676673] 'applied index is now lower than readState.Index' (duration: 336.333948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:30.133625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.841105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:26:30.133635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.217232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T13:26:30.133635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.069016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/rook-ceph/\" range_end:\"/registry/secrets/rook-ceph0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:30.133668Z","caller":"traceutil/trace.go:171","msg":"trace[670837516] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1716; }","duration":"510.302064ms","start":"2026-04-23T13:26:29.623354Z","end":"2026-04-23T13:26:30.133656Z","steps":["trace[670837516] 'agreement among raft nodes before linearized reading' (duration: 510.237153ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:30.133656Z","caller":"traceutil/trace.go:171","msg":"trace[239538369] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:0; response_revision:1716; }","duration":"405.902606ms","start":"2026-04-23T13:26:29.727746Z","end":"2026-04-23T13:26:30.133648Z","steps":["trace[239538369] 'agreement among raft nodes before linearized reading' (duration: 405.832905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:30.13369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.623329Z","time spent":"510.355754ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-23T13:26:30.133696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.727736Z","time spent":"405.955627ms","remote":"127.0.0.1:41112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-23T13:26:30.13365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.027404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T13:26:30.133675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.733858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-23T13:26:30.133756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.532548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:30.133762Z","caller":"traceutil/trace.go:171","msg":"trace[1058961234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1716; }","duration":"515.192817ms","start":"2026-04-23T13:26:29.618543Z","end":"2026-04-23T13:26:30.133736Z","steps":["trace[1058961234] 'agreement among raft nodes before linearized reading' (duration: 514.871551ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:30.133796Z","caller":"traceutil/trace.go:171","msg":"trace[743253518] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:0; response_revision:1716; }","duration":"420.621699ms","start":"2026-04-23T13:26:29.713163Z","end":"2026-04-23T13:26:30.133784Z","steps":["trace[743253518] 'agreement among raft nodes before linearized reading' (duration: 420.562348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:30.133822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.618522Z","time spent":"515.291009ms","remote":"127.0.0.1:41292","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-23T13:26:30.133857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.151766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:26:30.133854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.713134Z","time spent":"420.705ms","remote":"127.0.0.1:41112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-04-23T13:26:30.13388Z","caller":"traceutil/trace.go:171","msg":"trace[1644899185] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io; range_end:; response_count:0; response_revision:1716; }","duration":"491.205476ms","start":"2026-04-23T13:26:29.642671Z","end":"2026-04-23T13:26:30.133876Z","steps":["trace[1644899185] 'agreement among raft nodes before linearized reading' (duration: 491.161655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:30.133894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.642655Z","time spent":"491.235987ms","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" "} {"level":"warn","ts":"2026-04-23T13:26:30.133877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.889081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T13:26:30.133992Z","caller":"traceutil/trace.go:171","msg":"trace[1241449141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1716; }","duration":"515.080325ms","start":"2026-04-23T13:26:29.618892Z","end":"2026-04-23T13:26:30.133973Z","steps":["trace[1241449141] 'agreement among raft nodes before linearized reading' (duration: 514.750669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:30.134042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.618865Z","time spent":"515.169387ms","remote":"127.0.0.1:41292","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-23T13:26:30.133798Z","caller":"traceutil/trace.go:171","msg":"trace[150198835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1716; }","duration":"514.939822ms","start":"2026-04-23T13:26:29.618835Z","end":"2026-04-23T13:26:30.133775Z","steps":["trace[150198835] 'agreement among raft nodes before linearized reading' (duration: 514.609306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:30.134081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.618808Z","time spent":"515.263119ms","remote":"127.0.0.1:41292","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-23T13:26:30.133695Z","caller":"traceutil/trace.go:171","msg":"trace[1211215043] range","detail":"{range_begin:/registry/secrets/rook-ceph/; range_end:/registry/secrets/rook-ceph0; response_count:0; response_revision:1716; }","duration":"423.181698ms","start":"2026-04-23T13:26:29.710497Z","end":"2026-04-23T13:26:30.133679Z","steps":["trace[1211215043] 'agreement among raft nodes before linearized reading' (duration: 423.058316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:30.134139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:29.710468Z","time spent":"423.666368ms","remote":"127.0.0.1:41112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/rook-ceph/\" range_end:\"/registry/secrets/rook-ceph0\" "} {"level":"info","ts":"2026-04-23T13:26:32.196608Z","caller":"traceutil/trace.go:171","msg":"trace[1994562219] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"172.269073ms","start":"2026-04-23T13:26:32.024317Z","end":"2026-04-23T13:26:32.196586Z","steps":["trace[1994562219] 'read index received' (duration: 171.897286ms)","trace[1994562219] 'applied index is now lower than readState.Index' (duration: 371.007µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:26:32.196673Z","caller":"traceutil/trace.go:171","msg":"trace[359189648] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"288.458357ms","start":"2026-04-23T13:26:31.908188Z","end":"2026-04-23T13:26:32.196646Z","steps":["trace[359189648] 'process raft request' (duration: 288.164952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:32.196858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.526337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:61814"} {"level":"info","ts":"2026-04-23T13:26:32.196887Z","caller":"traceutil/trace.go:171","msg":"trace[1571883800] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1837; }","duration":"172.617149ms","start":"2026-04-23T13:26:32.024261Z","end":"2026-04-23T13:26:32.196878Z","steps":["trace[1571883800] 'agreement among raft nodes before linearized reading' (duration: 172.466217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:32.196886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.612336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/openvswitch-server\" ","response":"range_response_count:1 size:543"} {"level":"info","ts":"2026-04-23T13:26:32.196959Z","caller":"traceutil/trace.go:171","msg":"trace[1308427217] range","detail":"{range_begin:/registry/serviceaccounts/openstack/openvswitch-server; range_end:; response_count:1; response_revision:1837; }","duration":"163.712288ms","start":"2026-04-23T13:26:32.033225Z","end":"2026-04-23T13:26:32.196937Z","steps":["trace[1308427217] 'agreement among raft nodes before linearized reading' (duration: 163.514615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:32.560752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.242132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:26:32.560985Z","caller":"traceutil/trace.go:171","msg":"trace[836399389] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1924; }","duration":"354.379905ms","start":"2026-04-23T13:26:32.206584Z","end":"2026-04-23T13:26:32.560964Z","steps":["trace[836399389] 'read index received' (duration: 167.833548ms)","trace[836399389] 'applied index is now lower than readState.Index' (duration: 186.544737ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:26:32.561037Z","caller":"traceutil/trace.go:171","msg":"trace[958777855] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"357.649927ms","start":"2026-04-23T13:26:32.203364Z","end":"2026-04-23T13:26:32.561014Z","steps":["trace[958777855] 'process raft request' (duration: 357.519894ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:32.561044Z","caller":"traceutil/trace.go:171","msg":"trace[1600996948] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"358.393132ms","start":"2026-04-23T13:26:32.202583Z","end":"2026-04-23T13:26:32.560976Z","steps":["trace[1600996948] 'process raft request' (duration: 171.810174ms)","trace[1600996948] 'compare' (duration: 186.093519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:32.561134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.203351Z","time spent":"357.732889ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:32.561135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.525307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:32.561232Z","caller":"traceutil/trace.go:171","msg":"trace[1359403917] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:0; response_revision:1840; }","duration":"354.66451ms","start":"2026-04-23T13:26:32.206543Z","end":"2026-04-23T13:26:32.561207Z","steps":["trace[1359403917] 'agreement among raft nodes before linearized reading' (duration: 354.509207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:32.561252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.202568Z","time spent":"358.582925ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:32.561283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.206524Z","time spent":"354.745203ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" "} {"level":"info","ts":"2026-04-23T13:26:32.582959Z","caller":"traceutil/trace.go:171","msg":"trace[2081651482] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"372.8372ms","start":"2026-04-23T13:26:32.210094Z","end":"2026-04-23T13:26:32.582931Z","steps":["trace[2081651482] 'process raft request' (duration: 372.657826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:32.583117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.210084Z","time spent":"372.956322ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1056,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T13:26:32.583209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.687056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:32.583294Z","caller":"traceutil/trace.go:171","msg":"trace[1602723948] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:1841; }","duration":"347.848229ms","start":"2026-04-23T13:26:32.235426Z","end":"2026-04-23T13:26:32.583274Z","steps":["trace[1602723948] 'agreement among raft nodes before linearized reading' (duration: 347.651725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:32.583342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.235393Z","time spent":"347.939531ms","remote":"127.0.0.1:41210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/memcached-metrics\" "} {"level":"warn","ts":"2026-04-23T13:26:32.583389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.88398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:32.58344Z","caller":"traceutil/trace.go:171","msg":"trace[1979752926] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery; range_end:; response_count:0; response_revision:1841; }","duration":"347.978302ms","start":"2026-04-23T13:26:32.235447Z","end":"2026-04-23T13:26:32.583425Z","steps":["trace[1979752926] 'agreement among raft nodes before linearized reading' (duration: 347.89861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:32.583479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.235425Z","time spent":"348.046173ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery\" "} {"level":"warn","ts":"2026-04-23T13:26:32.583519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.123023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-coredns-7dd4958666-w9qbp\" ","response":"range_response_count:1 size:5009"} {"level":"warn","ts":"2026-04-23T13:26:32.583583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.983749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:32.583612Z","caller":"traceutil/trace.go:171","msg":"trace[619506504] range","detail":"{range_begin:/registry/pods/openstack/neutron-coredns-7dd4958666-w9qbp; range_end:; response_count:1; response_revision:1841; }","duration":"142.297816ms","start":"2026-04-23T13:26:32.441292Z","end":"2026-04-23T13:26:32.58359Z","steps":["trace[619506504] 'agreement among raft nodes before linearized reading' (duration: 141.98903ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:32.583623Z","caller":"traceutil/trace.go:171","msg":"trace[1229889171] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-osd; range_end:; response_count:0; response_revision:1841; }","duration":"247.0689ms","start":"2026-04-23T13:26:32.336542Z","end":"2026-04-23T13:26:32.583611Z","steps":["trace[1229889171] 'agreement among raft nodes before linearized reading' (duration: 247.014779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:33.088407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224167334034642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T13:26:33.282729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.999693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:26:33.282847Z","caller":"traceutil/trace.go:171","msg":"trace[1814948455] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"695.426824ms","start":"2026-04-23T13:26:32.5874Z","end":"2026-04-23T13:26:33.282827Z","steps":["trace[1814948455] 'process raft request' (duration: 529.228777ms)","trace[1814948455] 'compare' (duration: 165.793799ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:33.28289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.587384Z","time spent":"695.488345ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:33.652784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.418355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:26:33.652962Z","caller":"traceutil/trace.go:171","msg":"trace[1303474885] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1927; }","duration":"1.065189105s","start":"2026-04-23T13:26:32.587736Z","end":"2026-04-23T13:26:33.652925Z","steps":["trace[1303474885] 'read index received' (duration: 528.914902ms)","trace[1303474885] 'applied index is now lower than readState.Index' (duration: 536.272633ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:33.653074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065345437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:33.653114Z","caller":"traceutil/trace.go:171","msg":"trace[1389859304] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-mgr; range_end:; response_count:0; response_revision:1843; }","duration":"1.065436569s","start":"2026-04-23T13:26:32.587666Z","end":"2026-04-23T13:26:33.653103Z","steps":["trace[1389859304] 'agreement among raft nodes before linearized reading' (duration: 1.065362247s)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:33.653083Z","caller":"traceutil/trace.go:171","msg":"trace[931493622] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"1.05770911s","start":"2026-04-23T13:26:32.595331Z","end":"2026-04-23T13:26:33.65304Z","steps":["trace[931493622] 'process raft request' (duration: 806.904007ms)","trace[931493622] 'compare' (duration: 250.227382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:26:33.653166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.587642Z","time spent":"1.0655153s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" "} {"level":"warn","ts":"2026-04-23T13:26:33.653283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.595318Z","time spent":"1.057855062s","remote":"127.0.0.1:41204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:26:33.720858Z","caller":"traceutil/trace.go:171","msg":"trace[1196149083] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"542.401621ms","start":"2026-04-23T13:26:33.178429Z","end":"2026-04-23T13:26:33.720831Z","steps":["trace[1196149083] 'process raft request' (duration: 542.088504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:33.720938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.475095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:26:33.721008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.1784Z","time spent":"542.526853ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:33.721035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131392446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-worker\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:33.721003Z","caller":"traceutil/trace.go:171","msg":"trace[1041399872] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"329.848453ms","start":"2026-04-23T13:26:33.391104Z","end":"2026-04-23T13:26:33.720953Z","steps":["trace[1041399872] 'process raft request' (duration: 329.604158ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:33.7211Z","caller":"traceutil/trace.go:171","msg":"trace[1732382424] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery-worker; range_end:; response_count:0; response_revision:1845; }","duration":"1.131462588s","start":"2026-04-23T13:26:32.589619Z","end":"2026-04-23T13:26:33.721082Z","steps":["trace[1732382424] 'agreement among raft nodes before linearized reading' (duration: 1.131383297s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:33.721161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.58961Z","time spent":"1.13153738s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-worker\" "} {"level":"warn","ts":"2026-04-23T13:26:33.721141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131212543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificaterequests.cert-manager.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:33.721042Z","caller":"traceutil/trace.go:171","msg":"trace[1402162038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1845; }","duration":"551.645938ms","start":"2026-04-23T13:26:33.16937Z","end":"2026-04-23T13:26:33.721016Z","steps":["trace[1402162038] 'agreement among raft nodes before linearized reading' (duration: 551.421324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:33.721245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133003487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:110757"} {"level":"info","ts":"2026-04-23T13:26:33.721265Z","caller":"traceutil/trace.go:171","msg":"trace[1483519382] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificaterequests.cert-manager.io; range_end:; response_count:0; response_revision:1845; }","duration":"1.131389466s","start":"2026-04-23T13:26:32.589848Z","end":"2026-04-23T13:26:33.721237Z","steps":["trace[1483519382] 'agreement among raft nodes before linearized reading' (duration: 1.131131431s)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:33.72128Z","caller":"traceutil/trace.go:171","msg":"trace[1946935967] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1845; }","duration":"1.133062409s","start":"2026-04-23T13:26:32.588208Z","end":"2026-04-23T13:26:33.721271Z","steps":["trace[1946935967] 'agreement among raft nodes before linearized reading' (duration: 1.132867535s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:33.721295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.169344Z","time spent":"551.913464ms","remote":"127.0.0.1:41012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:26:33.721322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.588193Z","time spent":"1.1331228s","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":110780,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-23T13:26:33.721337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.589842Z","time spent":"1.131484759s","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificaterequests.cert-manager.io\" "} {"level":"warn","ts":"2026-04-23T13:26:33.720944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.4745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:26:33.721326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126125755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:33.721486Z","caller":"traceutil/trace.go:171","msg":"trace[358786537] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx-admission; range_end:; response_count:0; response_revision:1845; }","duration":"1.126341939s","start":"2026-04-23T13:26:32.595108Z","end":"2026-04-23T13:26:33.72145Z","steps":["trace[358786537] 'agreement among raft nodes before linearized reading' (duration: 1.126029913s)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:33.721463Z","caller":"traceutil/trace.go:171","msg":"trace[245512354] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1845; }","duration":"651.06185ms","start":"2026-04-23T13:26:33.070364Z","end":"2026-04-23T13:26:33.721426Z","steps":["trace[245512354] 'agreement among raft nodes before linearized reading' (duration: 650.444558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:33.721559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:32.595088Z","time spent":"1.126459621s","remote":"127.0.0.1:41376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/ingress-nginx-admission\" "} {"level":"warn","ts":"2026-04-23T13:26:33.721593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.070332Z","time spent":"651.211293ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T13:26:33.721968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.391066Z","time spent":"330.057757ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:34.22116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224167334034652,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T13:26:34.50837Z","caller":"traceutil/trace.go:171","msg":"trace[1678276333] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"849.294571ms","start":"2026-04-23T13:26:33.659046Z","end":"2026-04-23T13:26:34.508341Z","steps":["trace[1678276333] 'process raft request' (duration: 849.048637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:34.508588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.659015Z","time spent":"849.467795ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:26:34.50875Z","caller":"traceutil/trace.go:171","msg":"trace[1049817521] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"848.765971ms","start":"2026-04-23T13:26:33.659964Z","end":"2026-04-23T13:26:34.50873Z","steps":["trace[1049817521] 'process raft request' (duration: 848.580538ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:34.508809Z","caller":"traceutil/trace.go:171","msg":"trace[199465289] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"848.422615ms","start":"2026-04-23T13:26:33.660378Z","end":"2026-04-23T13:26:34.508801Z","steps":["trace[199465289] 'process raft request' (duration: 848.288352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:34.508863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.660367Z","time spent":"848.467576ms","remote":"127.0.0.1:41294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:26:34.508741Z","caller":"traceutil/trace.go:171","msg":"trace[302445628] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"848.739641ms","start":"2026-04-23T13:26:33.65994Z","end":"2026-04-23T13:26:34.508679Z","steps":["trace[302445628] 'process raft request' (duration: 848.340574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:34.508955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.659936Z","time spent":"848.902855ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1043,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:34.509039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.659909Z","time spent":"849.047327ms","remote":"127.0.0.1:41294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1442,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:26:35.125177Z","caller":"traceutil/trace.go:171","msg":"trace[2021556922] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1931; }","duration":"1.404392357s","start":"2026-04-23T13:26:33.720764Z","end":"2026-04-23T13:26:35.125156Z","steps":["trace[2021556922] 'read index received' (duration: 787.448403ms)","trace[2021556922] 'applied index is now lower than readState.Index' (duration: 616.943034ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:26:35.125283Z","caller":"traceutil/trace.go:171","msg":"trace[64999768] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"1.313995728s","start":"2026-04-23T13:26:33.811279Z","end":"2026-04-23T13:26:35.125275Z","steps":["trace[64999768] 'process raft request' (duration: 1.313827335s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.125395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.811264Z","time spent":"1.31405318s","remote":"127.0.0.1:41198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11596,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:35.12545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468801585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:35.125562Z","caller":"traceutil/trace.go:171","msg":"trace[953348250] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter; range_end:; response_count:0; response_revision:1851; }","duration":"1.468984468s","start":"2026-04-23T13:26:33.656553Z","end":"2026-04-23T13:26:35.125537Z","steps":["trace[953348250] 'agreement among raft nodes before linearized reading' (duration: 1.468751934s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.125617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468245974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-lzzm4\" ","response":"range_response_count:1 size:7665"} {"level":"warn","ts":"2026-04-23T13:26:35.125625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.656533Z","time spent":"1.46908183s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter\" "} {"level":"info","ts":"2026-04-23T13:26:35.125652Z","caller":"traceutil/trace.go:171","msg":"trace[172798857] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-lzzm4; range_end:; response_count:1; response_revision:1851; }","duration":"1.468292036s","start":"2026-04-23T13:26:33.657352Z","end":"2026-04-23T13:26:35.125644Z","steps":["trace[172798857] 'agreement among raft nodes before linearized reading' (duration: 1.468183234s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.125672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.657346Z","time spent":"1.468323046s","remote":"127.0.0.1:41204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7688,"request content":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-lzzm4\" "} {"level":"warn","ts":"2026-04-23T13:26:35.125777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402357837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:35.125518Z","caller":"traceutil/trace.go:171","msg":"trace[1217794461] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"1.400634964s","start":"2026-04-23T13:26:33.724877Z","end":"2026-04-23T13:26:35.125512Z","steps":["trace[1217794461] 'process raft request' (duration: 1.400021772s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.126054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.473997ms","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-23T13:26:35.126082Z","caller":"traceutil/trace.go:171","msg":"trace[92700022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1851; }","duration":"560.502148ms","start":"2026-04-23T13:26:34.565572Z","end":"2026-04-23T13:26:35.126074Z","steps":["trace[92700022] 'agreement among raft nodes before linearized reading' (duration: 560.437687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.126098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:34.565507Z","time spent":"560.587309ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T13:26:35.126087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400320178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:35.126137Z","caller":"traceutil/trace.go:171","msg":"trace[77046263] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx-admission; range_end:; response_count:0; response_revision:1851; }","duration":"1.40040214s","start":"2026-04-23T13:26:33.725718Z","end":"2026-04-23T13:26:35.12612Z","steps":["trace[77046263] 'agreement among raft nodes before linearized reading' (duration: 1.400324988s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.126182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.725649Z","time spent":"1.400522202s","remote":"127.0.0.1:41376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/ingress-nginx-admission\" "} {"level":"warn","ts":"2026-04-23T13:26:35.126161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.297598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T13:26:35.126239Z","caller":"traceutil/trace.go:171","msg":"trace[1121428412] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:1851; }","duration":"866.431201ms","start":"2026-04-23T13:26:34.259789Z","end":"2026-04-23T13:26:35.12622Z","steps":["trace[1121428412] 'agreement among raft nodes before linearized reading' (duration: 866.223737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.12623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.099377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-23T13:26:35.12621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.724844Z","time spent":"1.401200984s","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":110743,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:26:35.126288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400422091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/node-feature-discovery-master-conf\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:26:35.126279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.338635ms","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-23T13:26:35.126297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:34.259768Z","time spent":"866.520323ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"info","ts":"2026-04-23T13:26:35.126319Z","caller":"traceutil/trace.go:171","msg":"trace[1384314906] range","detail":"{range_begin:/registry/configmaps/monitoring/node-feature-discovery-master-conf; range_end:; response_count:0; response_revision:1851; }","duration":"1.400469431s","start":"2026-04-23T13:26:33.725842Z","end":"2026-04-23T13:26:35.126312Z","steps":["trace[1384314906] 'agreement among raft nodes before linearized reading' (duration: 1.400418329s)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:26:35.126316Z","caller":"traceutil/trace.go:171","msg":"trace[136376033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"916.22756ms","start":"2026-04-23T13:26:34.210065Z","end":"2026-04-23T13:26:35.126293Z","steps":["trace[136376033] 'agreement among raft nodes before linearized reading' (duration: 915.963934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.126341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.72583Z","time spent":"1.400507702s","remote":"127.0.0.1:41128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/monitoring/node-feature-discovery-master-conf\" "} {"level":"info","ts":"2026-04-23T13:26:35.125877Z","caller":"traceutil/trace.go:171","msg":"trace[536167276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"1.402498059s","start":"2026-04-23T13:26:33.723357Z","end":"2026-04-23T13:26:35.125856Z","steps":["trace[536167276] 'agreement among raft nodes before linearized reading' (duration: 1.402174893s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.126353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:34.210038Z","time spent":"916.306561ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T13:26:35.12635Z","caller":"traceutil/trace.go:171","msg":"trace[1155346203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1851; }","duration":"560.466349ms","start":"2026-04-23T13:26:34.565865Z","end":"2026-04-23T13:26:35.126331Z","steps":["trace[1155346203] 'agreement among raft nodes before linearized reading' (duration: 560.272914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.126392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:34.565834Z","time spent":"560.55076ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T13:26:35.126448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.723336Z","time spent":"1.403065392s","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:26:35.126496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401105554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:26:35.126542Z","caller":"traceutil/trace.go:171","msg":"trace[1529648590] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io; range_end:; response_count:0; response_revision:1851; }","duration":"1.401205865s","start":"2026-04-23T13:26:33.725321Z","end":"2026-04-23T13:26:35.126527Z","steps":["trace[1529648590] 'agreement among raft nodes before linearized reading' (duration: 1.401138394s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.126582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.725296Z","time spent":"1.401279276s","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" "} {"level":"warn","ts":"2026-04-23T13:26:35.126687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267544245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T13:26:35.126763Z","caller":"traceutil/trace.go:171","msg":"trace[2049585978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1851; }","duration":"1.267658198s","start":"2026-04-23T13:26:33.859091Z","end":"2026-04-23T13:26:35.126749Z","steps":["trace[2049585978] 'agreement among raft nodes before linearized reading' (duration: 1.266977144s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:26:35.126804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:26:33.859077Z","time spent":"1.267716428s","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T13:27:13.599497Z","caller":"traceutil/trace.go:171","msg":"trace[567342635] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"249.743513ms","start":"2026-04-23T13:27:13.34971Z","end":"2026-04-23T13:27:13.599453Z","steps":["trace[567342635] 'process raft request' (duration: 242.710917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:27:13.599705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.610162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T13:27:13.599504Z","caller":"traceutil/trace.go:171","msg":"trace[954715965] linearizableReadLoop","detail":"{readStateIndex:2694; appliedIndex:2693; }","duration":"210.034949ms","start":"2026-04-23T13:27:13.38936Z","end":"2026-04-23T13:27:13.599395Z","steps":["trace[954715965] 'read index received' (duration: 203.184516ms)","trace[954715965] 'applied index is now lower than readState.Index' (duration: 6.849102ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:27:13.599795Z","caller":"traceutil/trace.go:171","msg":"trace[1478998071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2597; }","duration":"248.717473ms","start":"2026-04-23T13:27:13.351056Z","end":"2026-04-23T13:27:13.599773Z","steps":["trace[1478998071] 'agreement among raft nodes before linearized reading' (duration: 248.461889ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:27:13.618038Z","caller":"traceutil/trace.go:171","msg":"trace[71777219] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"224.258602ms","start":"2026-04-23T13:27:13.393734Z","end":"2026-04-23T13:27:13.617993Z","steps":["trace[71777219] 'process raft request' (duration: 223.984597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:27:13.618068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.129851ms","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-23T13:27:13.618152Z","caller":"traceutil/trace.go:171","msg":"trace[2045609857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2598; }","duration":"119.272653ms","start":"2026-04-23T13:27:13.498859Z","end":"2026-04-23T13:27:13.618131Z","steps":["trace[2045609857] 'agreement among raft nodes before linearized reading' (duration: 119.031179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:27:14.294143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.348131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:27:14.294262Z","caller":"traceutil/trace.go:171","msg":"trace[676897493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2601; }","duration":"125.547585ms","start":"2026-04-23T13:27:14.16869Z","end":"2026-04-23T13:27:14.294237Z","steps":["trace[676897493] 'range keys from in-memory index tree' (duration: 125.162448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:27:37.712862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.575039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:27:37.712992Z","caller":"traceutil/trace.go:171","msg":"trace[1638684046] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2683; }","duration":"111.756873ms","start":"2026-04-23T13:27:37.601202Z","end":"2026-04-23T13:27:37.712959Z","steps":["trace[1638684046] 'count revisions from in-memory index tree' (duration: 111.473958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:27:37.712989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.157478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:27:37.71312Z","caller":"traceutil/trace.go:171","msg":"trace[1850694109] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2683; }","duration":"115.371972ms","start":"2026-04-23T13:27:37.59772Z","end":"2026-04-23T13:27:37.713092Z","steps":["trace[1850694109] 'count revisions from in-memory index tree' (duration: 115.043626ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:27:38.257727Z","caller":"traceutil/trace.go:171","msg":"trace[484860863] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"112.283663ms","start":"2026-04-23T13:27:38.145404Z","end":"2026-04-23T13:27:38.257688Z","steps":["trace[484860863] 'process raft request' (duration: 104.398459ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:31:21.215544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1688} {"level":"info","ts":"2026-04-23T13:31:21.264886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1688,"took":"48.20368ms","hash":475118262,"current-db-size-bytes":33161216,"current-db-size":"33 MB","current-db-size-in-use-bytes":15163392,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-04-23T13:31:21.264977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":475118262,"revision":1688,"compact-revision":-1} {"level":"info","ts":"2026-04-23T13:36:21.227812Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3480} {"level":"info","ts":"2026-04-23T13:36:21.274335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3480,"took":"44.896177ms","hash":3098227446,"current-db-size-bytes":33161216,"current-db-size":"33 MB","current-db-size-in-use-bytes":16830464,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-04-23T13:36:21.274425Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3098227446,"revision":3480,"compact-revision":1688} {"level":"info","ts":"2026-04-23T13:40:37.681442Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242f.wal"} {"level":"info","ts":"2026-04-23T13:41:02.496585Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2c24222821500990","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-23T13:41:02.501501Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-23T13:41:02.501575Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-23T13:41:21.232877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5881} {"level":"info","ts":"2026-04-23T13:41:21.307314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5881,"took":"72.384562ms","hash":3804052185,"current-db-size-bytes":48951296,"current-db-size":"49 MB","current-db-size-in-use-bytes":41148416,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-04-23T13:41:21.307386Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3804052185,"revision":5881,"compact-revision":3480} {"level":"info","ts":"2026-04-23T13:42:08.613928Z","caller":"traceutil/trace.go:171","msg":"trace[617275715] transaction","detail":"{read_only:false; response_revision:10909; number_of_response:1; }","duration":"104.316816ms","start":"2026-04-23T13:42:08.509571Z","end":"2026-04-23T13:42:08.613888Z","steps":["trace[617275715] 'process raft request' (duration: 103.784686ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:46:21.2427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10137} {"level":"info","ts":"2026-04-23T13:46:21.356802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10137,"took":"112.157495ms","hash":3915246761,"current-db-size-bytes":58601472,"current-db-size":"59 MB","current-db-size-in-use-bytes":35966976,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-23T13:46:21.356859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3915246761,"revision":10137,"compact-revision":5881}