{"level":"warn","ts":"2026-05-14T21:21:16.160896Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T21:21:16.160983Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.204: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.204:2380","--initial-cluster=instance=https://199.19.213.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.204: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-05-14T21:21:16.161062Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T21:21:16.161076Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.204:2380"]} {"level":"info","ts":"2026-05-14T21:21:16.161093Z","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-05-14T21:21:16.161815Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.204:2379"]} {"level":"info","ts":"2026-05-14T21:21:16.161912Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.204:2380"],"listen-peer-urls":["https://199.19.213.204:2380"],"advertise-client-urls":["https://199.19.213.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.204: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-05-14T21:21:16.203262Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"41.094452ms"} {"level":"info","ts":"2026-05-14T21:21:16.26227Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e62df5d1e8b96ac","cluster-id":"502b8ba4e757e03a"} {"level":"info","ts":"2026-05-14T21:21:16.2624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T21:21:16.262429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became follower at term 0"} {"level":"info","ts":"2026-05-14T21:21:16.262613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e62df5d1e8b96ac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T21:21:16.262652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became follower at term 1"} {"level":"info","ts":"2026-05-14T21:21:16.262762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac switched to configuration voters=(9107086987533981356)"} {"level":"warn","ts":"2026-05-14T21:21:16.292153Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T21:21:16.297606Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T21:21:16.299611Z","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-05-14T21:21:16.302335Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e62df5d1e8b96ac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T21:21:16.302507Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e62df5d1e8b96ac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T21:21:16.302702Z","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-05-14T21:21:16.302855Z","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-05-14T21:21:16.302884Z","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-05-14T21:21:16.305051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac switched to configuration voters=(9107086987533981356)"} {"level":"info","ts":"2026-05-14T21:21:16.305301Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"502b8ba4e757e03a","local-member-id":"7e62df5d1e8b96ac","added-peer-id":"7e62df5d1e8b96ac","added-peer-peer-urls":["https://199.19.213.204:2380"]} {"level":"info","ts":"2026-05-14T21:21:16.307401Z","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-05-14T21:21:16.307628Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e62df5d1e8b96ac","initial-advertise-peer-urls":["https://199.19.213.204:2380"],"listen-peer-urls":["https://199.19.213.204:2380"],"advertise-client-urls":["https://199.19.213.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T21:21:16.307657Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T21:21:16.307729Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.204:2380"} {"level":"info","ts":"2026-05-14T21:21:16.307741Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.204:2380"} {"level":"info","ts":"2026-05-14T21:21:17.0639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T21:21:17.063945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T21:21:17.063959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac received MsgPreVoteResp from 7e62df5d1e8b96ac at term 1"} {"level":"info","ts":"2026-05-14T21:21:17.06397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became candidate at term 2"} {"level":"info","ts":"2026-05-14T21:21:17.063976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac received MsgVoteResp from 7e62df5d1e8b96ac at term 2"} {"level":"info","ts":"2026-05-14T21:21:17.063984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e62df5d1e8b96ac became leader at term 2"} {"level":"info","ts":"2026-05-14T21:21:17.063991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e62df5d1e8b96ac elected leader 7e62df5d1e8b96ac at term 2"} {"level":"info","ts":"2026-05-14T21:21:17.066236Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e62df5d1e8b96ac","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.204:2379]}","request-path":"/0/members/7e62df5d1e8b96ac/attributes","cluster-id":"502b8ba4e757e03a","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T21:21:17.066403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T21:21:17.066479Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T21:21:17.066744Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T21:21:17.069459Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T21:21:17.073861Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T21:21:17.073924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T21:21:17.074128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"502b8ba4e757e03a","local-member-id":"7e62df5d1e8b96ac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T21:21:17.074242Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T21:21:17.074287Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T21:21:17.082725Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.204:2379"} {"level":"info","ts":"2026-05-14T21:21:25.242396Z","caller":"traceutil/trace.go:171","msg":"trace[1455110019] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"114.761627ms","start":"2026-05-14T21:21:25.127613Z","end":"2026-05-14T21:21:25.242374Z","steps":["trace[1455110019] 'process raft request' (duration: 114.537036ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:25.242416Z","caller":"traceutil/trace.go:171","msg":"trace[608759012] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"114.659847ms","start":"2026-05-14T21:21:25.127744Z","end":"2026-05-14T21:21:25.242404Z","steps":["trace[608759012] 'process raft request' (duration: 114.434977ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:25.242268Z","caller":"traceutil/trace.go:171","msg":"trace[1652984676] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"115.628042ms","start":"2026-05-14T21:21:25.12662Z","end":"2026-05-14T21:21:25.242248Z","steps":["trace[1652984676] 'process raft request' (duration: 94.572454ms)","trace[1652984676] 'compare' (duration: 20.841027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:21:25.768624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.870577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:21:25.768733Z","caller":"traceutil/trace.go:171","msg":"trace[1602955916] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:324; }","duration":"366.222627ms","start":"2026-05-14T21:21:25.402493Z","end":"2026-05-14T21:21:25.768716Z","steps":["trace[1602955916] 'read index received' (duration: 116.859128ms)","trace[1602955916] 'applied index is now lower than readState.Index' (duration: 249.362269ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:25.768793Z","caller":"traceutil/trace.go:171","msg":"trace[1185910516] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"377.328858ms","start":"2026-05-14T21:21:25.391457Z","end":"2026-05-14T21:21:25.768786Z","steps":["trace[1185910516] 'process raft request' (duration: 127.902149ms)","trace[1185910516] 'compare' (duration: 248.772206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:21:25.768846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:25.391435Z","time spent":"377.375708ms","remote":"127.0.0.1:51382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:21:25.769106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.079663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-14T21:21:25.769103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.528123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-14T21:21:25.769183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.689009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-14T21:21:25.769199Z","caller":"traceutil/trace.go:171","msg":"trace[1994879693] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:314; }","duration":"316.677414ms","start":"2026-05-14T21:21:25.452498Z","end":"2026-05-14T21:21:25.769176Z","steps":["trace[1994879693] 'agreement among raft nodes before linearized reading' (duration: 316.411423ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:25.769216Z","caller":"traceutil/trace.go:171","msg":"trace[1271103725] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:314; }","duration":"366.747869ms","start":"2026-05-14T21:21:25.402462Z","end":"2026-05-14T21:21:25.76921Z","steps":["trace[1271103725] 'agreement among raft nodes before linearized reading' (duration: 366.698219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:25.76924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:25.402446Z","time spent":"366.788309ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-14T21:21:25.769252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:25.452473Z","time spent":"316.768034ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-05-14T21:21:25.769281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.814425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-14T21:21:25.769267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.148209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-14T21:21:25.769348Z","caller":"traceutil/trace.go:171","msg":"trace[562256586] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:314; }","duration":"216.908846ms","start":"2026-05-14T21:21:25.55242Z","end":"2026-05-14T21:21:25.769329Z","steps":["trace[562256586] 'agreement among raft nodes before linearized reading' (duration: 216.783565ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:25.769353Z","caller":"traceutil/trace.go:171","msg":"trace[1826998957] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:314; }","duration":"117.30898ms","start":"2026-05-14T21:21:25.652028Z","end":"2026-05-14T21:21:25.769337Z","steps":["trace[1826998957] 'agreement among raft nodes before linearized reading' (duration: 117.164259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:25.769408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.7275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-14T21:21:25.769433Z","caller":"traceutil/trace.go:171","msg":"trace[800161952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:314; }","duration":"266.7737ms","start":"2026-05-14T21:21:25.502653Z","end":"2026-05-14T21:21:25.769427Z","steps":["trace[800161952] 'agreement among raft nodes before linearized reading' (duration: 266.72933ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:25.769143Z","caller":"traceutil/trace.go:171","msg":"trace[959750366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:314; }","duration":"167.155734ms","start":"2026-05-14T21:21:25.601979Z","end":"2026-05-14T21:21:25.769135Z","steps":["trace[959750366] 'agreement among raft nodes before linearized reading' (duration: 167.076443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.114437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.222225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:21:26.114761Z","caller":"traceutil/trace.go:171","msg":"trace[1086713043] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"335.938191ms","start":"2026-05-14T21:21:25.778793Z","end":"2026-05-14T21:21:26.114731Z","steps":["trace[1086713043] 'process raft request' (duration: 335.789729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.114922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:25.778787Z","time spent":"336.073181ms","remote":"127.0.0.1:51456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:21:26.11491Z","caller":"traceutil/trace.go:171","msg":"trace[818672451] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"338.374523ms","start":"2026-05-14T21:21:25.776471Z","end":"2026-05-14T21:21:26.114845Z","steps":["trace[818672451] 'process raft request' (duration: 218.682415ms)","trace[818672451] 'compare' (duration: 119.090225ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:26.115015Z","caller":"traceutil/trace.go:171","msg":"trace[1779017867] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"337.069017ms","start":"2026-05-14T21:21:25.777922Z","end":"2026-05-14T21:21:26.114991Z","steps":["trace[1779017867] 'process raft request' (duration: 336.612564ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.115033Z","caller":"traceutil/trace.go:171","msg":"trace[1902290633] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"336.028442ms","start":"2026-05-14T21:21:25.778986Z","end":"2026-05-14T21:21:26.115015Z","steps":["trace[1902290633] 'process raft request' (duration: 335.642999ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.115042Z","caller":"traceutil/trace.go:171","msg":"trace[446673184] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:326; }","duration":"179.106717ms","start":"2026-05-14T21:21:25.935917Z","end":"2026-05-14T21:21:26.115024Z","steps":["trace[446673184] 'read index received' (duration: 59.218588ms)","trace[446673184] 'applied index is now lower than readState.Index' (duration: 119.884059ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:26.115109Z","caller":"traceutil/trace.go:171","msg":"trace[1403776161] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"335.251418ms","start":"2026-05-14T21:21:25.779849Z","end":"2026-05-14T21:21:26.115101Z","steps":["trace[1403776161] 'process raft request' (duration: 334.852317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.115103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:25.777898Z","time spent":"337.172187ms","remote":"127.0.0.1:51582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T21:21:26.115116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:25.77898Z","time spent":"336.097492ms","remote":"127.0.0.1:51780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T21:21:26.11515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:25.779845Z","time spent":"335.288078ms","remote":"127.0.0.1:51788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T21:21:26.115168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.244967ms","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-05-14T21:21:26.115212Z","caller":"traceutil/trace.go:171","msg":"trace[1289477787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:320; }","duration":"179.329328ms","start":"2026-05-14T21:21:25.93587Z","end":"2026-05-14T21:21:26.115199Z","steps":["trace[1289477787] 'agreement among raft nodes before linearized reading' (duration: 179.226007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.115369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:25.776454Z","time spent":"338.571534ms","remote":"127.0.0.1:51350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T21:21:26.312217Z","caller":"traceutil/trace.go:171","msg":"trace[247751553] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"190.539359ms","start":"2026-05-14T21:21:26.121651Z","end":"2026-05-14T21:21:26.312191Z","steps":["trace[247751553] 'process raft request' (duration: 152.451691ms)","trace[247751553] 'compare' (duration: 37.873987ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:26.31278Z","caller":"traceutil/trace.go:171","msg":"trace[705430749] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"191.093582ms","start":"2026-05-14T21:21:26.12167Z","end":"2026-05-14T21:21:26.312763Z","steps":["trace[705430749] 'process raft request' (duration: 190.859131ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.313212Z","caller":"traceutil/trace.go:171","msg":"trace[87858409] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:331; }","duration":"189.998556ms","start":"2026-05-14T21:21:26.12319Z","end":"2026-05-14T21:21:26.313189Z","steps":["trace[87858409] 'read index received' (duration: 150.997243ms)","trace[87858409] 'applied index is now lower than readState.Index' (duration: 39.000403ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:26.313373Z","caller":"traceutil/trace.go:171","msg":"trace[1944567517] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"190.9846ms","start":"2026-05-14T21:21:26.122377Z","end":"2026-05-14T21:21:26.313362Z","steps":["trace[1944567517] 'process raft request' (duration: 190.337128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.313568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.360018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-14T21:21:26.313606Z","caller":"traceutil/trace.go:171","msg":"trace[1127589400] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:324; }","duration":"190.432028ms","start":"2026-05-14T21:21:26.123165Z","end":"2026-05-14T21:21:26.313597Z","steps":["trace[1127589400] 'agreement among raft nodes before linearized reading' (duration: 190.085996ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.314451Z","caller":"traceutil/trace.go:171","msg":"trace[875152344] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"192.395278ms","start":"2026-05-14T21:21:26.122027Z","end":"2026-05-14T21:21:26.314423Z","steps":["trace[875152344] 'process raft request' (duration: 190.598869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.66406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.425411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:21:26.664354Z","caller":"traceutil/trace.go:171","msg":"trace[335203841] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"345.479292ms","start":"2026-05-14T21:21:26.318838Z","end":"2026-05-14T21:21:26.664318Z","steps":["trace[335203841] 'process raft request' (duration: 160.71351ms)","trace[335203841] 'compare' (duration: 184.22083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:21:26.664469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:26.318814Z","time spent":"345.620593ms","remote":"127.0.0.1:51350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T21:21:26.664685Z","caller":"traceutil/trace.go:171","msg":"trace[1687207143] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"344.969919ms","start":"2026-05-14T21:21:26.319618Z","end":"2026-05-14T21:21:26.664588Z","steps":["trace[1687207143] 'process raft request' (duration: 344.578778ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.664751Z","caller":"traceutil/trace.go:171","msg":"trace[1545820342] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"343.517243ms","start":"2026-05-14T21:21:26.321215Z","end":"2026-05-14T21:21:26.664732Z","steps":["trace[1545820342] 'process raft request' (duration: 343.064081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.664828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:26.321205Z","time spent":"343.588973ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T21:21:26.664884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:26.319606Z","time spent":"345.17806ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:21:26.66494Z","caller":"traceutil/trace.go:171","msg":"trace[1669174447] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:335; }","duration":"344.554238ms","start":"2026-05-14T21:21:26.320372Z","end":"2026-05-14T21:21:26.664926Z","steps":["trace[1669174447] 'read index received' (duration: 65.699186ms)","trace[1669174447] 'applied index is now lower than readState.Index' (duration: 278.852922ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:21:26.665139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.28264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-57tsb\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-14T21:21:26.66526Z","caller":"traceutil/trace.go:171","msg":"trace[1595386486] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-57tsb; range_end:; response_count:1; response_revision:329; }","duration":"274.4274ms","start":"2026-05-14T21:21:26.39082Z","end":"2026-05-14T21:21:26.665248Z","steps":["trace[1595386486] 'agreement among raft nodes before linearized reading' (duration: 274.257299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.665392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.834979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"warn","ts":"2026-05-14T21:21:26.665423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.367063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-14T21:21:26.665472Z","caller":"traceutil/trace.go:171","msg":"trace[1390789309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:329; }","duration":"144.416093ms","start":"2026-05-14T21:21:26.521042Z","end":"2026-05-14T21:21:26.665458Z","steps":["trace[1390789309] 'agreement among raft nodes before linearized reading' (duration: 144.330833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.665592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.158293ms","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-05-14T21:21:26.665488Z","caller":"traceutil/trace.go:171","msg":"trace[1488812892] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:329; }","duration":"345.09112ms","start":"2026-05-14T21:21:26.320346Z","end":"2026-05-14T21:21:26.665437Z","steps":["trace[1488812892] 'agreement among raft nodes before linearized reading' (duration: 344.662578ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.665696Z","caller":"traceutil/trace.go:171","msg":"trace[2117784239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:329; }","duration":"159.262383ms","start":"2026-05-14T21:21:26.506393Z","end":"2026-05-14T21:21:26.665656Z","steps":["trace[2117784239] 'agreement among raft nodes before linearized reading' (duration: 159.158463ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.665068Z","caller":"traceutil/trace.go:171","msg":"trace[1694218871] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"274.655251ms","start":"2026-05-14T21:21:26.390398Z","end":"2026-05-14T21:21:26.665054Z","steps":["trace[1694218871] 'process raft request' (duration: 274.29594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.66583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:26.320337Z","time spent":"345.358671ms","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3728,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-14T21:21:26.665807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.851267ms","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-05-14T21:21:26.666129Z","caller":"traceutil/trace.go:171","msg":"trace[1053485863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:329; }","duration":"263.210428ms","start":"2026-05-14T21:21:26.402902Z","end":"2026-05-14T21:21:26.666112Z","steps":["trace[1053485863] 'agreement among raft nodes before linearized reading' (duration: 262.670686ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.88048Z","caller":"traceutil/trace.go:171","msg":"trace[441895230] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"207.462208ms","start":"2026-05-14T21:21:26.672999Z","end":"2026-05-14T21:21:26.880461Z","steps":["trace[441895230] 'process raft request' (duration: 207.386558ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.880542Z","caller":"traceutil/trace.go:171","msg":"trace[1175092305] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"207.645228ms","start":"2026-05-14T21:21:26.672864Z","end":"2026-05-14T21:21:26.880509Z","steps":["trace[1175092305] 'process raft request' (duration: 207.449977ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.880542Z","caller":"traceutil/trace.go:171","msg":"trace[782046201] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"207.624238ms","start":"2026-05-14T21:21:26.672896Z","end":"2026-05-14T21:21:26.88052Z","steps":["trace[782046201] 'process raft request' (duration: 207.456608ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.880574Z","caller":"traceutil/trace.go:171","msg":"trace[1330379298] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"207.920299ms","start":"2026-05-14T21:21:26.672608Z","end":"2026-05-14T21:21:26.880528Z","steps":["trace[1330379298] 'process raft request' (duration: 207.664248ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.880944Z","caller":"traceutil/trace.go:171","msg":"trace[1929403809] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"207.82889ms","start":"2026-05-14T21:21:26.672533Z","end":"2026-05-14T21:21:26.880362Z","steps":["trace[1929403809] 'process raft request' (duration: 191.169692ms)","trace[1929403809] 'compare' (duration: 16.450647ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:26.880972Z","caller":"traceutil/trace.go:171","msg":"trace[1400396583] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"202.279313ms","start":"2026-05-14T21:21:26.678669Z","end":"2026-05-14T21:21:26.880948Z","steps":["trace[1400396583] 'process raft request' (duration: 201.744881ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:26.88119Z","caller":"traceutil/trace.go:171","msg":"trace[280841134] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:340; }","duration":"206.236001ms","start":"2026-05-14T21:21:26.674935Z","end":"2026-05-14T21:21:26.881171Z","steps":["trace[280841134] 'read index received' (duration: 43.759944ms)","trace[280841134] 'applied index is now lower than readState.Index' (duration: 162.474767ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:26.881291Z","caller":"traceutil/trace.go:171","msg":"trace[2039068721] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"200.144713ms","start":"2026-05-14T21:21:26.681129Z","end":"2026-05-14T21:21:26.881274Z","steps":["trace[2039068721] 'process raft request' (duration: 199.730221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.88133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.381552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-14T21:21:26.881376Z","caller":"traceutil/trace.go:171","msg":"trace[1671395302] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:337; }","duration":"206.461422ms","start":"2026-05-14T21:21:26.674906Z","end":"2026-05-14T21:21:26.881367Z","steps":["trace[1671395302] 'agreement among raft nodes before linearized reading' (duration: 206.367702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:26.881369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.771306ms","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-05-14T21:21:26.88142Z","caller":"traceutil/trace.go:171","msg":"trace[1237969678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:337; }","duration":"110.868757ms","start":"2026-05-14T21:21:26.770538Z","end":"2026-05-14T21:21:26.881407Z","steps":["trace[1237969678] 'agreement among raft nodes before linearized reading' (duration: 110.758867ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:28.842559Z","caller":"traceutil/trace.go:171","msg":"trace[1345549998] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"116.361718ms","start":"2026-05-14T21:21:28.726174Z","end":"2026-05-14T21:21:28.842535Z","steps":["trace[1345549998] 'process raft request' (duration: 116.235007ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:31.096411Z","caller":"traceutil/trace.go:171","msg":"trace[1194214849] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"250.310295ms","start":"2026-05-14T21:21:30.846081Z","end":"2026-05-14T21:21:31.096391Z","steps":["trace[1194214849] 'read index received' (duration: 250.177184ms)","trace[1194214849] 'applied index is now lower than readState.Index' (duration: 132.471µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:31.096455Z","caller":"traceutil/trace.go:171","msg":"trace[37070730] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"251.469661ms","start":"2026-05-14T21:21:30.84496Z","end":"2026-05-14T21:21:31.09643Z","steps":["trace[37070730] 'process raft request' (duration: 251.31694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:31.096549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.058492ms","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-05-14T21:21:31.096576Z","caller":"traceutil/trace.go:171","msg":"trace[30581398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:361; }","duration":"184.135693ms","start":"2026-05-14T21:21:30.912432Z","end":"2026-05-14T21:21:31.096567Z","steps":["trace[30581398] 'agreement among raft nodes before linearized reading' (duration: 184.061192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:31.096669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.846907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-14T21:21:31.096808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.697797ms","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-05-14T21:21:31.096855Z","caller":"traceutil/trace.go:171","msg":"trace[184975855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"156.991108ms","start":"2026-05-14T21:21:30.939775Z","end":"2026-05-14T21:21:31.096766Z","steps":["trace[184975855] 'agreement among raft nodes before linearized reading' (duration: 156.748126ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:31.096917Z","caller":"traceutil/trace.go:171","msg":"trace[70976519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:361; }","duration":"250.862568ms","start":"2026-05-14T21:21:30.846031Z","end":"2026-05-14T21:21:31.096893Z","steps":["trace[70976519] 'agreement among raft nodes before linearized reading' (duration: 250.429076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:31.720444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.708025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T21:21:31.720475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.537497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-14T21:21:31.720531Z","caller":"traceutil/trace.go:171","msg":"trace[878545906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:364; }","duration":"239.857275ms","start":"2026-05-14T21:21:31.480655Z","end":"2026-05-14T21:21:31.720512Z","steps":["trace[878545906] 'range keys from in-memory index tree' (duration: 239.587775ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:31.720553Z","caller":"traceutil/trace.go:171","msg":"trace[1463157408] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:364; }","duration":"439.708127ms","start":"2026-05-14T21:21:31.280824Z","end":"2026-05-14T21:21:31.720532Z","steps":["trace[1463157408] 'range keys from in-memory index tree' (duration: 439.405796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:31.720636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:31.280807Z","time spent":"439.814798ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-05-14T21:21:32.620561Z","caller":"traceutil/trace.go:171","msg":"trace[1747623588] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"891.411667ms","start":"2026-05-14T21:21:31.729131Z","end":"2026-05-14T21:21:32.620543Z","steps":["trace[1747623588] 'process raft request' (duration: 891.286627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:32.620688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:31.729065Z","time spent":"891.559358ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:21:33.248659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.901905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:21:33.248766Z","caller":"traceutil/trace.go:171","msg":"trace[558938539] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:377; }","duration":"832.008678ms","start":"2026-05-14T21:21:32.41674Z","end":"2026-05-14T21:21:33.248749Z","steps":["trace[558938539] 'read index received' (duration: 203.748617ms)","trace[558938539] 'applied index is now lower than readState.Index' (duration: 628.258711ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:33.248848Z","caller":"traceutil/trace.go:171","msg":"trace[1212454850] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.265288816s","start":"2026-05-14T21:21:31.983548Z","end":"2026-05-14T21:21:33.248837Z","steps":["trace[1212454850] 'process raft request' (duration: 783.13014ms)","trace[1212454850] 'compare' (duration: 481.462823ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:21:33.248904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:31.983517Z","time spent":"1.265357457s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:21:33.249668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.872382ms","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-05-14T21:21:33.249766Z","caller":"traceutil/trace.go:171","msg":"trace[1547266412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"833.025693ms","start":"2026-05-14T21:21:32.416697Z","end":"2026-05-14T21:21:33.249722Z","steps":["trace[1547266412] 'agreement among raft nodes before linearized reading' (duration: 832.872031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:33.249813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:32.416679Z","time spent":"833.124553ms","remote":"127.0.0.1:51566","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-05-14T21:21:33.249881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.857681ms","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-05-14T21:21:33.249956Z","caller":"traceutil/trace.go:171","msg":"trace[1510523004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:366; }","duration":"134.990872ms","start":"2026-05-14T21:21:33.114948Z","end":"2026-05-14T21:21:33.249939Z","steps":["trace[1510523004] 'agreement among raft nodes before linearized reading' (duration: 134.827801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:33.24999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.336699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-14T21:21:33.250032Z","caller":"traceutil/trace.go:171","msg":"trace[388782627] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:366; }","duration":"625.414129ms","start":"2026-05-14T21:21:32.624604Z","end":"2026-05-14T21:21:33.250019Z","steps":["trace[388782627] 'agreement among raft nodes before linearized reading' (duration: 625.346009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:33.250072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:32.624586Z","time spent":"625.479069ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-14T21:21:33.250419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.496814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:21:33.250501Z","caller":"traceutil/trace.go:171","msg":"trace[1455579143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"769.610624ms","start":"2026-05-14T21:21:32.480875Z","end":"2026-05-14T21:21:33.250486Z","steps":["trace[1455579143] 'agreement among raft nodes before linearized reading' (duration: 768.236757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:33.250568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:32.480856Z","time spent":"769.701064ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T21:21:33.753614Z","caller":"traceutil/trace.go:171","msg":"trace[432648086] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"499.3167ms","start":"2026-05-14T21:21:33.254261Z","end":"2026-05-14T21:21:33.753578Z","steps":["trace[432648086] 'process raft request' (duration: 445.343247ms)","trace[432648086] 'compare' (duration: 53.053719ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:33.753703Z","caller":"traceutil/trace.go:171","msg":"trace[1440753046] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"499.408541ms","start":"2026-05-14T21:21:33.254267Z","end":"2026-05-14T21:21:33.753676Z","steps":["trace[1440753046] 'process raft request' (duration: 499.18213ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:33.75375Z","caller":"traceutil/trace.go:171","msg":"trace[346806738] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"498.840859ms","start":"2026-05-14T21:21:33.25488Z","end":"2026-05-14T21:21:33.753721Z","steps":["trace[346806738] 'process raft request' (duration: 498.668458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:33.753802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:33.254243Z","time spent":"499.471451ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:21:33.753835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:33.254244Z","time spent":"499.509141ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:21:33.75393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:33.25486Z","time spent":"498.968069ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:21:33.754002Z","caller":"traceutil/trace.go:171","msg":"trace[1912730323] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:379; }","duration":"272.766946ms","start":"2026-05-14T21:21:33.48122Z","end":"2026-05-14T21:21:33.753987Z","steps":["trace[1912730323] 'read index received' (duration: 218.50501ms)","trace[1912730323] 'applied index is now lower than readState.Index' (duration: 54.261096ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:33.754093Z","caller":"traceutil/trace.go:171","msg":"trace[700821238] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"499.1126ms","start":"2026-05-14T21:21:33.254968Z","end":"2026-05-14T21:21:33.75408Z","steps":["trace[700821238] 'process raft request' (duration: 498.694978ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:33.754098Z","caller":"traceutil/trace.go:171","msg":"trace[743656623] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"497.377972ms","start":"2026-05-14T21:21:33.256699Z","end":"2026-05-14T21:21:33.754077Z","steps":["trace[743656623] 'process raft request' (duration: 497.08094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:33.754154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.933487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T21:21:33.754159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:33.254942Z","time spent":"499.18059ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:21:33.754187Z","caller":"traceutil/trace.go:171","msg":"trace[1921879687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"273.004877ms","start":"2026-05-14T21:21:33.481171Z","end":"2026-05-14T21:21:33.754176Z","steps":["trace[1921879687] 'agreement among raft nodes before linearized reading' (duration: 272.871886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:33.754189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:33.256691Z","time spent":"497.447483ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:21:52.143999Z","caller":"traceutil/trace.go:171","msg":"trace[1622431052] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"114.000532ms","start":"2026-05-14T21:21:52.029975Z","end":"2026-05-14T21:21:52.143976Z","steps":["trace[1622431052] 'process raft request' (duration: 113.121512ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.144186Z","caller":"traceutil/trace.go:171","msg":"trace[303431774] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"104.532986ms","start":"2026-05-14T21:21:52.039638Z","end":"2026-05-14T21:21:52.144171Z","steps":["trace[303431774] 'process raft request' (duration: 103.637595ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.144343Z","caller":"traceutil/trace.go:171","msg":"trace[923727924] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"114.319043ms","start":"2026-05-14T21:21:52.030017Z","end":"2026-05-14T21:21:52.144336Z","steps":["trace[923727924] 'process raft request' (duration: 113.215984ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.144579Z","caller":"traceutil/trace.go:171","msg":"trace[94694536] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"102.42469ms","start":"2026-05-14T21:21:52.042146Z","end":"2026-05-14T21:21:52.144571Z","steps":["trace[94694536] 'process raft request' (duration: 101.2834ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.144748Z","caller":"traceutil/trace.go:171","msg":"trace[991062743] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"105.031443ms","start":"2026-05-14T21:21:52.03971Z","end":"2026-05-14T21:21:52.144742Z","steps":["trace[991062743] 'process raft request' (duration: 103.681596ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.197242Z","caller":"traceutil/trace.go:171","msg":"trace[356283620] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"115.435822ms","start":"2026-05-14T21:21:52.08174Z","end":"2026-05-14T21:21:52.197176Z","steps":["trace[356283620] 'process raft request' (duration: 114.973006ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.267833Z","caller":"traceutil/trace.go:171","msg":"trace[396536357] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"108.524287ms","start":"2026-05-14T21:21:52.159275Z","end":"2026-05-14T21:21:52.267799Z","steps":["trace[396536357] 'process raft request' (duration: 108.347901ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.267914Z","caller":"traceutil/trace.go:171","msg":"trace[418912822] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"109.534573ms","start":"2026-05-14T21:21:52.158342Z","end":"2026-05-14T21:21:52.267876Z","steps":["trace[418912822] 'process raft request' (duration: 58.718311ms)","trace[418912822] 'compare' (duration: 50.358006ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:52.267948Z","caller":"traceutil/trace.go:171","msg":"trace[280230380] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"108.031809ms","start":"2026-05-14T21:21:52.159877Z","end":"2026-05-14T21:21:52.267909Z","steps":["trace[280230380] 'process raft request' (duration: 107.78635ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.268147Z","caller":"traceutil/trace.go:171","msg":"trace[1655570843] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"102.850126ms","start":"2026-05-14T21:21:52.165283Z","end":"2026-05-14T21:21:52.268133Z","steps":["trace[1655570843] 'process raft request' (duration: 102.41449ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.26827Z","caller":"traceutil/trace.go:171","msg":"trace[267074718] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"102.718791ms","start":"2026-05-14T21:21:52.165523Z","end":"2026-05-14T21:21:52.268242Z","steps":["trace[267074718] 'process raft request' (duration: 102.240545ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.268294Z","caller":"traceutil/trace.go:171","msg":"trace[764303777] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"100.97719ms","start":"2026-05-14T21:21:52.167296Z","end":"2026-05-14T21:21:52.268273Z","steps":["trace[764303777] 'process raft request' (duration: 100.740631ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.268921Z","caller":"traceutil/trace.go:171","msg":"trace[1182152844] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"101.375454ms","start":"2026-05-14T21:21:52.16753Z","end":"2026-05-14T21:21:52.268905Z","steps":["trace[1182152844] 'process raft request' (duration: 100.598946ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.699459Z","caller":"traceutil/trace.go:171","msg":"trace[1534481866] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"126.250427ms","start":"2026-05-14T21:21:52.573185Z","end":"2026-05-14T21:21:52.699435Z","steps":["trace[1534481866] 'process raft request' (duration: 120.71156ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:52.700146Z","caller":"traceutil/trace.go:171","msg":"trace[26564513] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"106.85633ms","start":"2026-05-14T21:21:52.593264Z","end":"2026-05-14T21:21:52.70012Z","steps":["trace[26564513] 'process raft request' (duration: 106.371552ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:53.587067Z","caller":"traceutil/trace.go:171","msg":"trace[1943823166] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:565; }","duration":"105.462624ms","start":"2026-05-14T21:21:53.481586Z","end":"2026-05-14T21:21:53.587049Z","steps":["trace[1943823166] 'read index received' (duration: 93.262837ms)","trace[1943823166] 'applied index is now lower than readState.Index' (duration: 12.199247ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:21:53.587206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.596558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:21:53.587253Z","caller":"traceutil/trace.go:171","msg":"trace[270608118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"105.698621ms","start":"2026-05-14T21:21:53.481541Z","end":"2026-05-14T21:21:53.58724Z","steps":["trace[270608118] 'agreement among raft nodes before linearized reading' (duration: 105.593748ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:53.587251Z","caller":"traceutil/trace.go:171","msg":"trace[1800220132] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"526.804004ms","start":"2026-05-14T21:21:53.060418Z","end":"2026-05-14T21:21:53.587222Z","steps":["trace[1800220132] 'process raft request' (duration: 526.366159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:53.587359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:53.060387Z","time spent":"526.920628ms","remote":"127.0.0.1:51310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T21:21:53.587448Z","caller":"traceutil/trace.go:171","msg":"trace[1104166663] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"557.741257ms","start":"2026-05-14T21:21:53.02968Z","end":"2026-05-14T21:21:53.587422Z","steps":["trace[1104166663] 'process raft request' (duration: 545.135846ms)","trace[1104166663] 'compare' (duration: 11.499862ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:21:53.587555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:53.029656Z","time spent":"557.83423ms","remote":"127.0.0.1:51310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:21:54.172486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.914445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:21:54.172607Z","caller":"traceutil/trace.go:171","msg":"trace[158032534] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"244.292827ms","start":"2026-05-14T21:21:53.928295Z","end":"2026-05-14T21:21:54.172587Z","steps":["trace[158032534] 'process raft request' (duration: 84.164145ms)","trace[158032534] 'compare' (duration: 159.80293ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:54.227045Z","caller":"traceutil/trace.go:171","msg":"trace[1157557470] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"261.147366ms","start":"2026-05-14T21:21:53.96586Z","end":"2026-05-14T21:21:54.227007Z","steps":["trace[1157557470] 'read index received' (duration: 46.61085ms)","trace[1157557470] 'applied index is now lower than readState.Index' (duration: 214.535406ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:54.227125Z","caller":"traceutil/trace.go:171","msg":"trace[1733571941] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"270.387029ms","start":"2026-05-14T21:21:53.956699Z","end":"2026-05-14T21:21:54.227086Z","steps":["trace[1733571941] 'process raft request' (duration: 270.10108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:54.227436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.550609ms","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-05-14T21:21:54.22749Z","caller":"traceutil/trace.go:171","msg":"trace[659788317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"261.642853ms","start":"2026-05-14T21:21:53.965829Z","end":"2026-05-14T21:21:54.227472Z","steps":["trace[659788317] 'agreement among raft nodes before linearized reading' (duration: 261.336892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:54.240214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.126918ms","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-05-14T21:21:54.24029Z","caller":"traceutil/trace.go:171","msg":"trace[2128957150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"267.237392ms","start":"2026-05-14T21:21:53.973033Z","end":"2026-05-14T21:21:54.240271Z","steps":["trace[2128957150] 'agreement among raft nodes before linearized reading' (duration: 267.078147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:54.240282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.632992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"warn","ts":"2026-05-14T21:21:54.240306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.209041ms","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-05-14T21:21:54.24035Z","caller":"traceutil/trace.go:171","msg":"trace[1605724311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"267.259723ms","start":"2026-05-14T21:21:53.973078Z","end":"2026-05-14T21:21:54.240338Z","steps":["trace[1605724311] 'agreement among raft nodes before linearized reading' (duration: 267.16153ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:54.240348Z","caller":"traceutil/trace.go:171","msg":"trace[1005345740] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:553; }","duration":"266.720264ms","start":"2026-05-14T21:21:53.973611Z","end":"2026-05-14T21:21:54.240331Z","steps":["trace[1005345740] 'agreement among raft nodes before linearized reading' (duration: 266.523187ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:54.24041Z","caller":"traceutil/trace.go:171","msg":"trace[1034030595] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"150.187947ms","start":"2026-05-14T21:21:54.090207Z","end":"2026-05-14T21:21:54.240395Z","steps":["trace[1034030595] 'process raft request' (duration: 148.396286ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:21:59.829036Z","caller":"traceutil/trace.go:171","msg":"trace[866380634] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"347.630451ms","start":"2026-05-14T21:21:59.481372Z","end":"2026-05-14T21:21:59.829002Z","steps":["trace[866380634] 'read index received' (duration: 346.776823ms)","trace[866380634] 'applied index is now lower than readState.Index' (duration: 852.747µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:21:59.829083Z","caller":"traceutil/trace.go:171","msg":"trace[1650022539] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"579.215256ms","start":"2026-05-14T21:21:59.249824Z","end":"2026-05-14T21:21:59.829039Z","steps":["trace[1650022539] 'process raft request' (duration: 578.565425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:59.829199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.807867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T21:21:59.829216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:59.249799Z","time spent":"579.346999ms","remote":"127.0.0.1:51370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:21:59.829229Z","caller":"traceutil/trace.go:171","msg":"trace[1239658459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"347.891259ms","start":"2026-05-14T21:21:59.481328Z","end":"2026-05-14T21:21:59.829219Z","steps":["trace[1239658459] 'agreement among raft nodes before linearized reading' (duration: 347.803417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:21:59.829255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:21:59.481309Z","time spent":"347.93953ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T21:21:59.845983Z","caller":"traceutil/trace.go:171","msg":"trace[2085442986] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"127.289512ms","start":"2026-05-14T21:21:59.718668Z","end":"2026-05-14T21:21:59.845957Z","steps":["trace[2085442986] 'process raft request' (duration: 127.163948ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:00.543103Z","caller":"traceutil/trace.go:171","msg":"trace[36237181] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"165.596219ms","start":"2026-05-14T21:22:00.37747Z","end":"2026-05-14T21:22:00.543066Z","steps":["trace[36237181] 'process raft request' (duration: 128.529234ms)","trace[36237181] 'compare' (duration: 36.211688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:22:01.28211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.722837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:22:01.282881Z","caller":"traceutil/trace.go:171","msg":"trace[1417158450] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"671.509818ms","start":"2026-05-14T21:22:00.610765Z","end":"2026-05-14T21:22:01.282275Z","steps":["trace[1417158450] 'process raft request' (duration: 212.520752ms)","trace[1417158450] 'compare' (duration: 458.18516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:22:01.283088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:00.610746Z","time spent":"672.247841ms","remote":"127.0.0.1:51350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T21:22:01.362858Z","caller":"traceutil/trace.go:171","msg":"trace[232532888] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:621; }","duration":"449.938454ms","start":"2026-05-14T21:22:00.912895Z","end":"2026-05-14T21:22:01.362834Z","steps":["trace[232532888] 'read index received' (duration: 449.673726ms)","trace[232532888] 'applied index is now lower than readState.Index' (duration: 263.938µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:22:01.362941Z","caller":"traceutil/trace.go:171","msg":"trace[979118783] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"751.2934ms","start":"2026-05-14T21:22:00.611604Z","end":"2026-05-14T21:22:01.362898Z","steps":["trace[979118783] 'process raft request' (duration: 751.137636ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:01.362996Z","caller":"traceutil/trace.go:171","msg":"trace[510927329] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"751.525507ms","start":"2026-05-14T21:22:00.611438Z","end":"2026-05-14T21:22:01.362963Z","steps":["trace[510927329] 'process raft request' (duration: 751.189447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:01.363084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.171691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h2k7x\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-14T21:22:01.363217Z","caller":"traceutil/trace.go:171","msg":"trace[426347316] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"750.814385ms","start":"2026-05-14T21:22:00.61222Z","end":"2026-05-14T21:22:01.363034Z","steps":["trace[426347316] 'process raft request' (duration: 750.575957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:01.363265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:00.611415Z","time spent":"751.687823ms","remote":"127.0.0.1:51350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T21:22:01.36329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:00.611588Z","time spent":"751.443245ms","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:22:01.363482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:00.61213Z","time spent":"751.192917ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:22:01.364342Z","caller":"traceutil/trace.go:171","msg":"trace[2093453969] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h2k7x; range_end:; response_count:1; response_revision:598; }","duration":"450.252104ms","start":"2026-05-14T21:22:00.912857Z","end":"2026-05-14T21:22:01.363109Z","steps":["trace[2093453969] 'agreement among raft nodes before linearized reading' (duration: 450.073298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:01.364407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:00.912834Z","time spent":"451.558115ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-h2k7x\" "} {"level":"info","ts":"2026-05-14T21:22:01.830629Z","caller":"traceutil/trace.go:171","msg":"trace[1842830174] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"349.519379ms","start":"2026-05-14T21:22:01.481086Z","end":"2026-05-14T21:22:01.830606Z","steps":["trace[1842830174] 'read index received' (duration: 349.374845ms)","trace[1842830174] 'applied index is now lower than readState.Index' (duration: 143.644µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:22:01.830758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.659614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:22:01.830787Z","caller":"traceutil/trace.go:171","msg":"trace[67139271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"349.744437ms","start":"2026-05-14T21:22:01.481034Z","end":"2026-05-14T21:22:01.830779Z","steps":["trace[67139271] 'agreement among raft nodes before linearized reading' (duration: 349.659364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:01.830818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:01.481012Z","time spent":"349.799369ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T21:22:01.830833Z","caller":"traceutil/trace.go:171","msg":"trace[744984548] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"454.651209ms","start":"2026-05-14T21:22:01.376147Z","end":"2026-05-14T21:22:01.830798Z","steps":["trace[744984548] 'process raft request' (duration: 454.339369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:01.831024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:01.376121Z","time spent":"454.809404ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:22:03.300115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09315095s","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-05-14T21:22:03.30014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.619173ms","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-05-14T21:22:03.300177Z","caller":"traceutil/trace.go:171","msg":"trace[1053750783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"1.093305525s","start":"2026-05-14T21:22:02.206853Z","end":"2026-05-14T21:22:03.300159Z","steps":["trace[1053750783] 'range keys from in-memory index tree' (duration: 1.092991214s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:03.300148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.939642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T21:22:03.300197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029124973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-14T21:22:03.300218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:02.206826Z","time spent":"1.093384176s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T21:22:03.300239Z","caller":"traceutil/trace.go:171","msg":"trace[1834932888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"1.029203725s","start":"2026-05-14T21:22:02.271023Z","end":"2026-05-14T21:22:03.300226Z","steps":["trace[1834932888] 'range keys from in-memory index tree' (duration: 1.02901574s)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:03.30022Z","caller":"traceutil/trace.go:171","msg":"trace[1760912300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"960.718887ms","start":"2026-05-14T21:22:02.33948Z","end":"2026-05-14T21:22:03.300199Z","steps":["trace[1760912300] 'range keys from in-memory index tree' (duration: 960.403108ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:03.300239Z","caller":"traceutil/trace.go:171","msg":"trace[175650137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"819.073446ms","start":"2026-05-14T21:22:02.481146Z","end":"2026-05-14T21:22:03.30022Z","steps":["trace[175650137] 'range keys from in-memory index tree' (duration: 818.859198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:03.300271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:02.270999Z","time spent":"1.029265327s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T21:22:03.300282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:02.339474Z","time spent":"960.79927ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T21:22:03.300289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:02.48112Z","time spent":"819.1577ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T21:22:03.300103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.271097ms","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-05-14T21:22:03.300356Z","caller":"traceutil/trace.go:171","msg":"trace[1474256912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"962.594558ms","start":"2026-05-14T21:22:02.337753Z","end":"2026-05-14T21:22:03.300347Z","steps":["trace[1474256912] 'range keys from in-memory index tree' (duration: 962.05297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:03.300381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:02.337736Z","time spent":"962.638269ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T21:22:05.29032Z","caller":"traceutil/trace.go:171","msg":"trace[123303917] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"187.036363ms","start":"2026-05-14T21:22:05.103267Z","end":"2026-05-14T21:22:05.290303Z","steps":["trace[123303917] 'process raft request' (duration: 186.919479ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:08.239585Z","caller":"traceutil/trace.go:171","msg":"trace[1667204318] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"106.41097ms","start":"2026-05-14T21:22:08.133146Z","end":"2026-05-14T21:22:08.239557Z","steps":["trace[1667204318] 'process raft request' (duration: 106.273956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:19.630256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.207331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T21:22:19.630303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.807686ms","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-05-14T21:22:19.630355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.506383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T21:22:19.630335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.717058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:22:19.630396Z","caller":"traceutil/trace.go:171","msg":"trace[892583146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"198.591886ms","start":"2026-05-14T21:22:19.431795Z","end":"2026-05-14T21:22:19.630387Z","steps":["trace[892583146] 'range keys from in-memory index tree' (duration: 198.382321ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:19.630396Z","caller":"traceutil/trace.go:171","msg":"trace[2062278733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"230.957899ms","start":"2026-05-14T21:22:19.399412Z","end":"2026-05-14T21:22:19.63037Z","steps":["trace[2062278733] 'range keys from in-memory index tree' (duration: 230.633921ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:19.630434Z","caller":"traceutil/trace.go:171","msg":"trace[1526449409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"150.863272ms","start":"2026-05-14T21:22:19.479543Z","end":"2026-05-14T21:22:19.630406Z","steps":["trace[1526449409] 'range keys from in-memory index tree' (duration: 150.630137ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:19.630353Z","caller":"traceutil/trace.go:171","msg":"trace[1974914647] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:755; }","duration":"686.337744ms","start":"2026-05-14T21:22:18.943995Z","end":"2026-05-14T21:22:19.630332Z","steps":["trace[1974914647] 'count revisions from in-memory index tree' (duration: 686.14443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:19.630663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:18.943988Z","time spent":"686.644543ms","remote":"127.0.0.1:51698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-14T21:22:19.630727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.10197ms","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-05-14T21:22:19.630772Z","caller":"traceutil/trace.go:171","msg":"trace[112467506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"855.180092ms","start":"2026-05-14T21:22:18.775582Z","end":"2026-05-14T21:22:19.630762Z","steps":["trace[112467506] 'range keys from in-memory index tree' (duration: 854.717471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:19.630813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:18.775564Z","time spent":"855.239613ms","remote":"127.0.0.1:51566","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-05-14T21:22:19.630893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.842869ms","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-05-14T21:22:19.630924Z","caller":"traceutil/trace.go:171","msg":"trace[1651026575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"209.905381ms","start":"2026-05-14T21:22:19.421008Z","end":"2026-05-14T21:22:19.630913Z","steps":["trace[1651026575] 'range keys from in-memory index tree' (duration: 209.717456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:19.63087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.668691ms","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-05-14T21:22:19.631012Z","caller":"traceutil/trace.go:171","msg":"trace[293624725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"187.844065ms","start":"2026-05-14T21:22:19.443154Z","end":"2026-05-14T21:22:19.630998Z","steps":["trace[293624725] 'range keys from in-memory index tree' (duration: 187.498847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:19.631027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.120388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6kjl7\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-14T21:22:19.631164Z","caller":"traceutil/trace.go:171","msg":"trace[2003352743] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6kjl7; range_end:; response_count:1; response_revision:755; }","duration":"611.262671ms","start":"2026-05-14T21:22:19.019879Z","end":"2026-05-14T21:22:19.631142Z","steps":["trace[2003352743] 'range keys from in-memory index tree' (duration: 610.8364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:19.631275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:19.019861Z","time spent":"611.395324ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6kjl7\" "} {"level":"warn","ts":"2026-05-14T21:22:19.631526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.570698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:22:19.632451Z","caller":"traceutil/trace.go:171","msg":"trace[599193255] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:755; }","duration":"689.384836ms","start":"2026-05-14T21:22:18.942987Z","end":"2026-05-14T21:22:19.632372Z","steps":["trace[599193255] 'count revisions from in-memory index tree' (duration: 687.496945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:22:19.632585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:22:18.942972Z","time spent":"689.589951ms","remote":"127.0.0.1:51552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-05-14T21:22:19.864832Z","caller":"traceutil/trace.go:171","msg":"trace[134317825] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"229.550951ms","start":"2026-05-14T21:22:19.635248Z","end":"2026-05-14T21:22:19.864799Z","steps":["trace[134317825] 'process raft request' (duration: 229.394967ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:19.874211Z","caller":"traceutil/trace.go:171","msg":"trace[1087742772] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"238.194388ms","start":"2026-05-14T21:22:19.635977Z","end":"2026-05-14T21:22:19.874172Z","steps":["trace[1087742772] 'process raft request' (duration: 237.984023ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:19.874267Z","caller":"traceutil/trace.go:171","msg":"trace[1366636389] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"237.925081ms","start":"2026-05-14T21:22:19.636299Z","end":"2026-05-14T21:22:19.874224Z","steps":["trace[1366636389] 'process raft request' (duration: 237.782368ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:19.874341Z","caller":"traceutil/trace.go:171","msg":"trace[775525041] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"237.582452ms","start":"2026-05-14T21:22:19.636668Z","end":"2026-05-14T21:22:19.87425Z","steps":["trace[775525041] 'process raft request' (duration: 237.464319ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:19.874408Z","caller":"traceutil/trace.go:171","msg":"trace[662613716] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"238.044974ms","start":"2026-05-14T21:22:19.636347Z","end":"2026-05-14T21:22:19.874392Z","steps":["trace[662613716] 'process raft request' (duration: 237.761186ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:19.874946Z","caller":"traceutil/trace.go:171","msg":"trace[1759830121] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"237.10505ms","start":"2026-05-14T21:22:19.63711Z","end":"2026-05-14T21:22:19.874215Z","steps":["trace[1759830121] 'process raft request' (duration: 237.058939ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:22:31.011056Z","caller":"traceutil/trace.go:171","msg":"trace[450045812] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"156.863084ms","start":"2026-05-14T21:22:30.854167Z","end":"2026-05-14T21:22:31.01103Z","steps":["trace[450045812] 'process raft request' (duration: 156.708331ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:10.87667Z","caller":"traceutil/trace.go:171","msg":"trace[1221653096] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"103.883571ms","start":"2026-05-14T21:23:10.772763Z","end":"2026-05-14T21:23:10.876647Z","steps":["trace[1221653096] 'read index received' (duration: 103.735489ms)","trace[1221653096] 'applied index is now lower than readState.Index' (duration: 146.922µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:23:10.876688Z","caller":"traceutil/trace.go:171","msg":"trace[1496794629] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"150.777835ms","start":"2026-05-14T21:23:10.72588Z","end":"2026-05-14T21:23:10.876658Z","steps":["trace[1496794629] 'process raft request' (duration: 150.633222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:10.876852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.054804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:23:10.876929Z","caller":"traceutil/trace.go:171","msg":"trace[1455660121] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1415; }","duration":"104.187506ms","start":"2026-05-14T21:23:10.772722Z","end":"2026-05-14T21:23:10.876909Z","steps":["trace[1455660121] 'agreement among raft nodes before linearized reading' (duration: 103.977892ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:11.07108Z","caller":"traceutil/trace.go:171","msg":"trace[891570938] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"139.118276ms","start":"2026-05-14T21:23:10.931929Z","end":"2026-05-14T21:23:11.071047Z","steps":["trace[891570938] 'process raft request' (duration: 137.175735ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:11.076184Z","caller":"traceutil/trace.go:171","msg":"trace[256404973] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"139.748606ms","start":"2026-05-14T21:23:10.936413Z","end":"2026-05-14T21:23:11.076162Z","steps":["trace[256404973] 'process raft request' (duration: 139.625714ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:12.555625Z","caller":"traceutil/trace.go:171","msg":"trace[1982419220] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"202.869663ms","start":"2026-05-14T21:23:12.352729Z","end":"2026-05-14T21:23:12.555598Z","steps":["trace[1982419220] 'process raft request' (duration: 202.68784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:30.053525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.60305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-zsm54\" ","response":"range_response_count:1 size:7308"} {"level":"info","ts":"2026-05-14T21:23:30.053621Z","caller":"traceutil/trace.go:171","msg":"trace[1396040423] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-zsm54; range_end:; response_count:1; response_revision:1722; }","duration":"264.742282ms","start":"2026-05-14T21:23:29.788867Z","end":"2026-05-14T21:23:30.053609Z","steps":["trace[1396040423] 'range keys from in-memory index tree' (duration: 264.452188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:37.872875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226697887839389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T21:23:38.373111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226697887839389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T21:23:38.425744Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072681318s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T21:23:38.426405Z","caller":"traceutil/trace.go:171","msg":"trace[440500408] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"1.073418158s","start":"2026-05-14T21:23:37.352958Z","end":"2026-05-14T21:23:38.426376Z","steps":["trace[440500408] 'process raft request' (duration: 1.073206824s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.426564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:37.352938Z","time spent":"1.07355872s","remote":"127.0.0.1:51378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2505,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T21:23:38.436479Z","caller":"traceutil/trace.go:171","msg":"trace[1000980299] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1906; }","duration":"1.063964389s","start":"2026-05-14T21:23:37.372498Z","end":"2026-05-14T21:23:38.436463Z","steps":["trace[1000980299] 'read index received' (duration: 1.053615659s)","trace[1000980299] 'applied index is now lower than readState.Index' (duration: 10.34801ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:23:38.436611Z","caller":"traceutil/trace.go:171","msg":"trace[854367328] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"736.655941ms","start":"2026-05-14T21:23:37.699945Z","end":"2026-05-14T21:23:38.436601Z","steps":["trace[854367328] 'process raft request' (duration: 736.490589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.436707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:37.699919Z","time spent":"736.729382ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:23:38.436937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064407205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc37240e-fd33-43c2-a466-43a438fb9c22\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-14T21:23:38.437052Z","caller":"traceutil/trace.go:171","msg":"trace[1083803388] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc37240e-fd33-43c2-a466-43a438fb9c22; range_end:; response_count:1; response_revision:1835; }","duration":"1.064562078s","start":"2026-05-14T21:23:37.372458Z","end":"2026-05-14T21:23:38.43702Z","steps":["trace[1083803388] 'agreement among raft nodes before linearized reading' (duration: 1.064151112s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.43711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:37.37244Z","time spent":"1.064656269s","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc37240e-fd33-43c2-a466-43a438fb9c22\" "} {"level":"info","ts":"2026-05-14T21:23:38.43712Z","caller":"traceutil/trace.go:171","msg":"trace[738627112] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"840.370054ms","start":"2026-05-14T21:23:37.596734Z","end":"2026-05-14T21:23:38.437104Z","steps":["trace[738627112] 'process raft request' (duration: 839.609964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.437183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:37.59669Z","time spent":"840.463535ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:23:38.592736Z","caller":"traceutil/trace.go:171","msg":"trace[1192180117] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"156.119099ms","start":"2026-05-14T21:23:38.43658Z","end":"2026-05-14T21:23:38.592699Z","steps":["trace[1192180117] 'read index received' (duration: 152.4395ms)","trace[1192180117] 'applied index is now lower than readState.Index' (duration: 3.678509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:23:38.592852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.521597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:23:38.592917Z","caller":"traceutil/trace.go:171","msg":"trace[1593242744] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1836; }","duration":"827.635889ms","start":"2026-05-14T21:23:37.765259Z","end":"2026-05-14T21:23:38.592895Z","steps":["trace[1593242744] 'agreement among raft nodes before linearized reading' (duration: 827.506187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.592975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:37.7652Z","time spent":"827.758709ms","remote":"127.0.0.1:37108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T21:23:38.593104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218995993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc37240e-fd33-43c2-a466-43a438fb9c22\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-14T21:23:38.593293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.674045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-14T21:23:38.593322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.916126ms","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-05-14T21:23:38.593346Z","caller":"traceutil/trace.go:171","msg":"trace[1161920440] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"156.97118ms","start":"2026-05-14T21:23:38.436348Z","end":"2026-05-14T21:23:38.593319Z","steps":["trace[1161920440] 'process raft request' (duration: 152.484361ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:38.593358Z","caller":"traceutil/trace.go:171","msg":"trace[1262951009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1836; }","duration":"289.800767ms","start":"2026-05-14T21:23:38.303543Z","end":"2026-05-14T21:23:38.593343Z","steps":["trace[1262951009] 'agreement among raft nodes before linearized reading' (duration: 289.582294ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:38.593373Z","caller":"traceutil/trace.go:171","msg":"trace[470565870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1836; }","duration":"298.006337ms","start":"2026-05-14T21:23:38.295352Z","end":"2026-05-14T21:23:38.593358Z","steps":["trace[470565870] 'agreement among raft nodes before linearized reading' (duration: 297.870475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.593422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028222542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T21:23:38.593477Z","caller":"traceutil/trace.go:171","msg":"trace[521685145] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1836; }","duration":"1.028307513s","start":"2026-05-14T21:23:37.565154Z","end":"2026-05-14T21:23:38.593462Z","steps":["trace[521685145] 'agreement among raft nodes before linearized reading' (duration: 1.028160671s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.593518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:37.565136Z","time spent":"1.028373685s","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T21:23:38.593556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.439171ms","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-05-14T21:23:38.593606Z","caller":"traceutil/trace.go:171","msg":"trace[1123337236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1836; }","duration":"286.539193ms","start":"2026-05-14T21:23:38.307054Z","end":"2026-05-14T21:23:38.593593Z","steps":["trace[1123337236] 'agreement among raft nodes before linearized reading' (duration: 286.419451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.593693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112143478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:23:38.593723Z","caller":"traceutil/trace.go:171","msg":"trace[1171332998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1836; }","duration":"1.112215408s","start":"2026-05-14T21:23:37.481499Z","end":"2026-05-14T21:23:38.593715Z","steps":["trace[1171332998] 'agreement among raft nodes before linearized reading' (duration: 1.112173858s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.593742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:37.481484Z","time spent":"1.11225449s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T21:23:38.593777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.499709ms","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-05-14T21:23:38.593822Z","caller":"traceutil/trace.go:171","msg":"trace[1924939775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1836; }","duration":"288.583861ms","start":"2026-05-14T21:23:38.305227Z","end":"2026-05-14T21:23:38.593811Z","steps":["trace[1924939775] 'agreement among raft nodes before linearized reading' (duration: 288.459459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.594092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.100457ms","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-05-14T21:23:38.594114Z","caller":"traceutil/trace.go:171","msg":"trace[693524816] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc37240e-fd33-43c2-a466-43a438fb9c22; range_end:; response_count:1; response_revision:1836; }","duration":"1.219236666s","start":"2026-05-14T21:23:37.3739Z","end":"2026-05-14T21:23:38.593136Z","steps":["trace[693524816] 'agreement among raft nodes before linearized reading' (duration: 1.218900711s)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:38.594148Z","caller":"traceutil/trace.go:171","msg":"trace[703045599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1836; }","duration":"289.189849ms","start":"2026-05-14T21:23:38.304945Z","end":"2026-05-14T21:23:38.594134Z","steps":["trace[703045599] 'agreement among raft nodes before linearized reading' (duration: 287.77056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:38.594156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:37.37389Z","time spent":"1.2202543s","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc37240e-fd33-43c2-a466-43a438fb9c22\" "} {"level":"warn","ts":"2026-05-14T21:23:39.097006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226697887839401,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T21:23:39.509379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.553976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:23:39.50945Z","caller":"traceutil/trace.go:171","msg":"trace[1565483454] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"1.066276778s","start":"2026-05-14T21:23:38.44316Z","end":"2026-05-14T21:23:39.509436Z","steps":["trace[1565483454] 'process raft request' (duration: 739.430848ms)","trace[1565483454] 'compare' (duration: 326.394634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:23:39.509488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.443136Z","time spent":"1.066333119s","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:23:39.742432Z","caller":"traceutil/trace.go:171","msg":"trace[1240275763] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"1.144261138s","start":"2026-05-14T21:23:38.598148Z","end":"2026-05-14T21:23:39.742409Z","steps":["trace[1240275763] 'process raft request' (duration: 1.144105026s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.742573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.59813Z","time spent":"1.144372391s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:23:39.742757Z","caller":"traceutil/trace.go:171","msg":"trace[324545219] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"1.143058993s","start":"2026-05-14T21:23:38.599674Z","end":"2026-05-14T21:23:39.742733Z","steps":["trace[324545219] 'process raft request' (duration: 1.142697628s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.742915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.599663Z","time spent":"1.143154175s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:23:39.743222Z","caller":"traceutil/trace.go:171","msg":"trace[1285926227] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"1.141768156s","start":"2026-05-14T21:23:38.60144Z","end":"2026-05-14T21:23:39.743208Z","steps":["trace[1285926227] 'process raft request' (duration: 1.141692355s)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:39.743235Z","caller":"traceutil/trace.go:171","msg":"trace[1750689818] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1910; }","duration":"1.146772894s","start":"2026-05-14T21:23:38.59645Z","end":"2026-05-14T21:23:39.743223Z","steps":["trace[1750689818] 'read index received' (duration: 586.147058ms)","trace[1750689818] 'applied index is now lower than readState.Index' (duration: 560.624986ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:23:39.743263Z","caller":"traceutil/trace.go:171","msg":"trace[1606596041] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"1.1435184s","start":"2026-05-14T21:23:38.599736Z","end":"2026-05-14T21:23:39.743254Z","steps":["trace[1606596041] 'process raft request' (duration: 1.142912051s)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:39.743302Z","caller":"traceutil/trace.go:171","msg":"trace[920857869] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"1.086374831s","start":"2026-05-14T21:23:38.656919Z","end":"2026-05-14T21:23:39.743294Z","steps":["trace[920857869] 'process raft request' (duration: 1.086236329s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.74334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.59972Z","time spent":"1.143573691s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:23:39.743365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.65689Z","time spent":"1.086434582s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:23:39.743394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146944026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:23:39.743432Z","caller":"traceutil/trace.go:171","msg":"trace[2084817214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1845; }","duration":"1.147182009s","start":"2026-05-14T21:23:38.59624Z","end":"2026-05-14T21:23:39.743422Z","steps":["trace[2084817214] 'agreement among raft nodes before linearized reading' (duration: 1.147081887s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.74346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.596222Z","time spent":"1.14723126s","remote":"127.0.0.1:51250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T21:23:39.743476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.71781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:23:39.743498Z","caller":"traceutil/trace.go:171","msg":"trace[1243472222] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1845; }","duration":"576.79397ms","start":"2026-05-14T21:23:39.166698Z","end":"2026-05-14T21:23:39.743492Z","steps":["trace[1243472222] 'agreement among raft nodes before linearized reading' (duration: 576.74184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.743516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:39.166669Z","time spent":"576.842472ms","remote":"127.0.0.1:51382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":25,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-05-14T21:23:39.743631Z","caller":"traceutil/trace.go:171","msg":"trace[1673230035] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"1.042825105s","start":"2026-05-14T21:23:38.700792Z","end":"2026-05-14T21:23:39.743617Z","steps":["trace[1673230035] 'process raft request' (duration: 1.042387539s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.743687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.89408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T21:23:39.743713Z","caller":"traceutil/trace.go:171","msg":"trace[1535301347] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"142.934881ms","start":"2026-05-14T21:23:39.600772Z","end":"2026-05-14T21:23:39.743706Z","steps":["trace[1535301347] 'agreement among raft nodes before linearized reading' (duration: 142.84845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.743731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.700768Z","time spent":"1.042908657s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:23:39.743804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.785143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc37240e-fd33-43c2-a466-43a438fb9c22\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-14T21:23:39.743831Z","caller":"traceutil/trace.go:171","msg":"trace[2048086859] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"946.319607ms","start":"2026-05-14T21:23:38.797449Z","end":"2026-05-14T21:23:39.743769Z","steps":["trace[2048086859] 'process raft request' (duration: 945.74883ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:39.743851Z","caller":"traceutil/trace.go:171","msg":"trace[1595437901] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc37240e-fd33-43c2-a466-43a438fb9c22; range_end:; response_count:1; response_revision:1845; }","duration":"143.865664ms","start":"2026-05-14T21:23:39.599973Z","end":"2026-05-14T21:23:39.743839Z","steps":["trace[1595437901] 'agreement among raft nodes before linearized reading' (duration: 143.694822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.743294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.601429Z","time spent":"1.141828446s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:23:39.743223Z","caller":"traceutil/trace.go:171","msg":"trace[1668999608] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"1.142922411s","start":"2026-05-14T21:23:38.600285Z","end":"2026-05-14T21:23:39.743207Z","steps":["trace[1668999608] 'process raft request' (duration: 1.142762589s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:39.743975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.797414Z","time spent":"946.47822ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:23:39.743995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:38.600276Z","time spent":"1.143696132s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:23:48.088337Z","caller":"traceutil/trace.go:171","msg":"trace[1520004691] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"230.996655ms","start":"2026-05-14T21:23:47.857316Z","end":"2026-05-14T21:23:48.088312Z","steps":["trace[1520004691] 'process raft request' (duration: 230.829123ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:48.24341Z","caller":"traceutil/trace.go:171","msg":"trace[1738325788] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:1999; }","duration":"384.582575ms","start":"2026-05-14T21:23:47.858807Z","end":"2026-05-14T21:23:48.24339Z","steps":["trace[1738325788] 'read index received' (duration: 229.413914ms)","trace[1738325788] 'applied index is now lower than readState.Index' (duration: 155.167811ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:23:48.24363Z","caller":"traceutil/trace.go:171","msg":"trace[576781924] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"385.508848ms","start":"2026-05-14T21:23:47.85811Z","end":"2026-05-14T21:23:48.243619Z","steps":["trace[576781924] 'process raft request' (duration: 385.165444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:48.243723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:47.858066Z","time spent":"385.592218ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:23:48.243767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.449285ms","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-05-14T21:23:48.243816Z","caller":"traceutil/trace.go:171","msg":"trace[1059174062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1925; }","duration":"381.546086ms","start":"2026-05-14T21:23:47.862256Z","end":"2026-05-14T21:23:48.243802Z","steps":["trace[1059174062] 'agreement among raft nodes before linearized reading' (duration: 381.457505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:48.243801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.359248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T21:23:48.243852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:47.862224Z","time spent":"381.621478ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T21:23:48.243836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.455745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T21:23:48.243885Z","caller":"traceutil/trace.go:171","msg":"trace[2090095291] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1925; }","duration":"101.482949ms","start":"2026-05-14T21:23:48.142378Z","end":"2026-05-14T21:23:48.243861Z","steps":["trace[2090095291] 'agreement among raft nodes before linearized reading' (duration: 101.331047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:48.243747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.584543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-14T21:23:48.243904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.159674ms","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-05-14T21:23:48.243948Z","caller":"traceutil/trace.go:171","msg":"trace[256940510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1925; }","duration":"383.850296ms","start":"2026-05-14T21:23:47.860089Z","end":"2026-05-14T21:23:48.243939Z","steps":["trace[256940510] 'agreement among raft nodes before linearized reading' (duration: 383.496881ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:48.243946Z","caller":"traceutil/trace.go:171","msg":"trace[482089566] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1925; }","duration":"381.595037ms","start":"2026-05-14T21:23:47.862321Z","end":"2026-05-14T21:23:48.243916Z","steps":["trace[482089566] 'agreement among raft nodes before linearized reading' (duration: 381.285214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:48.243979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:47.860047Z","time spent":"383.924887ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T21:23:48.243985Z","caller":"traceutil/trace.go:171","msg":"trace[975629226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1925; }","duration":"382.283865ms","start":"2026-05-14T21:23:47.861683Z","end":"2026-05-14T21:23:48.243967Z","steps":["trace[975629226] 'agreement among raft nodes before linearized reading' (duration: 382.052502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:48.244004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:47.862311Z","time spent":"381.674198ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T21:23:48.244023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:47.861672Z","time spent":"382.343597ms","remote":"127.0.0.1:51566","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-05-14T21:23:48.243665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.848908ms","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-05-14T21:23:48.244387Z","caller":"traceutil/trace.go:171","msg":"trace[1671706056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1925; }","duration":"385.563837ms","start":"2026-05-14T21:23:47.858757Z","end":"2026-05-14T21:23:48.244321Z","steps":["trace[1671706056] 'agreement among raft nodes before linearized reading' (duration: 384.761858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:48.244477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:47.858743Z","time spent":"385.713289ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T21:23:57.641327Z","caller":"traceutil/trace.go:171","msg":"trace[1161701932] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"549.229891ms","start":"2026-05-14T21:23:57.092084Z","end":"2026-05-14T21:23:57.641314Z","steps":["trace[1161701932] 'process raft request' (duration: 548.996128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:57.641433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:57.092034Z","time spent":"549.342901ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:23:57.641459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.530279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T21:23:57.641259Z","caller":"traceutil/trace.go:171","msg":"trace[1377123830] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2067; }","duration":"335.320417ms","start":"2026-05-14T21:23:57.305916Z","end":"2026-05-14T21:23:57.641236Z","steps":["trace[1377123830] 'read index received' (duration: 335.169215ms)","trace[1377123830] 'applied index is now lower than readState.Index' (duration: 150.402µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:23:57.641508Z","caller":"traceutil/trace.go:171","msg":"trace[1951024861] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1990; }","duration":"335.622761ms","start":"2026-05-14T21:23:57.305874Z","end":"2026-05-14T21:23:57.641497Z","steps":["trace[1951024861] 'agreement among raft nodes before linearized reading' (duration: 335.480229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:57.641551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:57.305854Z","time spent":"335.688611ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T21:23:57.641569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.100762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:23:57.641603Z","caller":"traceutil/trace.go:171","msg":"trace[1086776007] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1990; }","duration":"127.180393ms","start":"2026-05-14T21:23:57.514412Z","end":"2026-05-14T21:23:57.641593Z","steps":["trace[1086776007] 'agreement among raft nodes before linearized reading' (duration: 127.119692ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:23:58.440278Z","caller":"traceutil/trace.go:171","msg":"trace[1876067780] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"302.770553ms","start":"2026-05-14T21:23:58.137484Z","end":"2026-05-14T21:23:58.440254Z","steps":["trace[1876067780] 'process raft request' (duration: 302.555321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:58.440434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:23:58.137467Z","time spent":"302.890006ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:23:58.448351Z","caller":"traceutil/trace.go:171","msg":"trace[1349349919] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2069; }","duration":"164.410125ms","start":"2026-05-14T21:23:58.28392Z","end":"2026-05-14T21:23:58.44833Z","steps":["trace[1349349919] 'read index received' (duration: 156.142115ms)","trace[1349349919] 'applied index is now lower than readState.Index' (duration: 8.26706ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:23:58.448509Z","caller":"traceutil/trace.go:171","msg":"trace[1730233297] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"174.147872ms","start":"2026-05-14T21:23:58.274349Z","end":"2026-05-14T21:23:58.448497Z","steps":["trace[1730233297] 'process raft request' (duration: 173.861618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:58.448696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.761409ms","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-05-14T21:23:58.448749Z","caller":"traceutil/trace.go:171","msg":"trace[1229902677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1993; }","duration":"164.860649ms","start":"2026-05-14T21:23:58.283875Z","end":"2026-05-14T21:23:58.448736Z","steps":["trace[1229902677] 'agreement among raft nodes before linearized reading' (duration: 164.737518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:58.448871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.547793ms","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-05-14T21:23:58.448909Z","caller":"traceutil/trace.go:171","msg":"trace[1840228677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1993; }","duration":"163.605024ms","start":"2026-05-14T21:23:58.285298Z","end":"2026-05-14T21:23:58.448903Z","steps":["trace[1840228677] 'agreement among raft nodes before linearized reading' (duration: 163.526133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:58.449024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.110904ms","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-05-14T21:23:58.449067Z","caller":"traceutil/trace.go:171","msg":"trace[1580300588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1993; }","duration":"156.181145ms","start":"2026-05-14T21:23:58.292875Z","end":"2026-05-14T21:23:58.449056Z","steps":["trace[1580300588] 'agreement among raft nodes before linearized reading' (duration: 156.078234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:23:58.449108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.183021ms","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-05-14T21:23:58.449142Z","caller":"traceutil/trace.go:171","msg":"trace[214844054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1993; }","duration":"159.254142ms","start":"2026-05-14T21:23:58.289882Z","end":"2026-05-14T21:23:58.449136Z","steps":["trace[214844054] 'agreement among raft nodes before linearized reading' (duration: 159.132071ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:08.037817Z","caller":"traceutil/trace.go:171","msg":"trace[247613435] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"208.627762ms","start":"2026-05-14T21:24:07.829159Z","end":"2026-05-14T21:24:08.037786Z","steps":["trace[247613435] 'process raft request' (duration: 208.390499ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:14.738835Z","caller":"traceutil/trace.go:171","msg":"trace[336046474] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"178.370296ms","start":"2026-05-14T21:24:14.560435Z","end":"2026-05-14T21:24:14.738805Z","steps":["trace[336046474] 'read index received' (duration: 178.278295ms)","trace[336046474] 'applied index is now lower than readState.Index' (duration: 91.251µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:24:14.739028Z","caller":"traceutil/trace.go:171","msg":"trace[1211987624] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"217.96948ms","start":"2026-05-14T21:24:14.521041Z","end":"2026-05-14T21:24:14.739011Z","steps":["trace[1211987624] 'process raft request' (duration: 217.596846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:14.739952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.459517ms","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-05-14T21:24:14.740058Z","caller":"traceutil/trace.go:171","msg":"trace[1469684866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2089; }","duration":"179.628879ms","start":"2026-05-14T21:24:14.560402Z","end":"2026-05-14T21:24:14.740031Z","steps":["trace[1469684866] 'agreement among raft nodes before linearized reading' (duration: 178.679949ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:15.136672Z","caller":"traceutil/trace.go:171","msg":"trace[1324010228] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"391.653084ms","start":"2026-05-14T21:24:14.744981Z","end":"2026-05-14T21:24:15.136634Z","steps":["trace[1324010228] 'process raft request' (duration: 391.599133ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:15.136753Z","caller":"traceutil/trace.go:171","msg":"trace[1995143] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"534.737142ms","start":"2026-05-14T21:24:14.60197Z","end":"2026-05-14T21:24:15.136707Z","steps":["trace[1995143] 'process raft request' (duration: 499.775961ms)","trace[1995143] 'compare' (duration: 34.575027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:24:15.13688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:14.601935Z","time spent":"534.872703ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:24:15.136868Z","caller":"traceutil/trace.go:171","msg":"trace[1331197429] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"528.955617ms","start":"2026-05-14T21:24:14.607862Z","end":"2026-05-14T21:24:15.136818Z","steps":["trace[1331197429] 'process raft request' (duration: 528.635664ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:15.136894Z","caller":"traceutil/trace.go:171","msg":"trace[824780087] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2170; }","duration":"397.967575ms","start":"2026-05-14T21:24:14.738902Z","end":"2026-05-14T21:24:15.136869Z","steps":["trace[824780087] 'read index received' (duration: 362.856552ms)","trace[824780087] 'applied index is now lower than readState.Index' (duration: 35.108613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:24:15.137014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:14.607841Z","time spent":"529.099718ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:24:15.137097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.108135ms","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-05-14T21:24:15.137129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.383957ms","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-05-14T21:24:15.137161Z","caller":"traceutil/trace.go:171","msg":"trace[1224015391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2092; }","duration":"542.196286ms","start":"2026-05-14T21:24:14.594948Z","end":"2026-05-14T21:24:15.137144Z","steps":["trace[1224015391] 'agreement among raft nodes before linearized reading' (duration: 541.991523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:15.137206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:14.594935Z","time spent":"542.262906ms","remote":"127.0.0.1:51566","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-05-14T21:24:15.137266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.31965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-14T21:24:15.13738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.229906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T21:24:15.137422Z","caller":"traceutil/trace.go:171","msg":"trace[591602470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2092; }","duration":"372.967274ms","start":"2026-05-14T21:24:14.764444Z","end":"2026-05-14T21:24:15.137411Z","steps":["trace[591602470] 'agreement among raft nodes before linearized reading' (duration: 372.775692ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:15.137167Z","caller":"traceutil/trace.go:171","msg":"trace[1572927358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2092; }","duration":"542.461819ms","start":"2026-05-14T21:24:14.594694Z","end":"2026-05-14T21:24:15.137156Z","steps":["trace[1572927358] 'agreement among raft nodes before linearized reading' (duration: 542.296757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:15.137446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:14.764415Z","time spent":"373.025565ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T21:24:15.13748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:14.594675Z","time spent":"542.790012ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T21:24:15.137337Z","caller":"traceutil/trace.go:171","msg":"trace[812598196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2092; }","duration":"568.435051ms","start":"2026-05-14T21:24:14.568887Z","end":"2026-05-14T21:24:15.137322Z","steps":["trace[812598196] 'agreement among raft nodes before linearized reading' (duration: 568.297979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:15.137555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:14.568862Z","time spent":"568.668334ms","remote":"127.0.0.1:51566","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-05-14T21:24:15.137422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:14.744949Z","time spent":"391.821217ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:24:15.138018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.992505ms","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-05-14T21:24:15.138073Z","caller":"traceutil/trace.go:171","msg":"trace[931026430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2092; }","duration":"543.061665ms","start":"2026-05-14T21:24:14.594997Z","end":"2026-05-14T21:24:15.138058Z","steps":["trace[931026430] 'agreement among raft nodes before linearized reading' (duration: 542.231125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:15.138124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:14.594989Z","time spent":"543.123686ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T21:24:16.489152Z","caller":"traceutil/trace.go:171","msg":"trace[1012447694] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"395.407778ms","start":"2026-05-14T21:24:16.093714Z","end":"2026-05-14T21:24:16.489122Z","steps":["trace[1012447694] 'process raft request' (duration: 395.195675ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:16.489106Z","caller":"traceutil/trace.go:171","msg":"trace[446554642] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2178; }","duration":"343.726812ms","start":"2026-05-14T21:24:16.145356Z","end":"2026-05-14T21:24:16.489083Z","steps":["trace[446554642] 'read index received' (duration: 343.449119ms)","trace[446554642] 'applied index is now lower than readState.Index' (duration: 276.513µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:24:16.489316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:16.093694Z","time spent":"395.532509ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:24:16.48941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.046325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-14T21:24:16.489749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.621549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-05-14T21:24:16.48984Z","caller":"traceutil/trace.go:171","msg":"trace[590067385] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2097; }","duration":"308.754881ms","start":"2026-05-14T21:24:16.181069Z","end":"2026-05-14T21:24:16.489824Z","steps":["trace[590067385] 'agreement among raft nodes before linearized reading' (duration: 308.443998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:16.489958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:16.181054Z","time spent":"308.888112ms","remote":"127.0.0.1:51480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-05-14T21:24:16.489749Z","caller":"traceutil/trace.go:171","msg":"trace[931333202] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2097; }","duration":"344.410309ms","start":"2026-05-14T21:24:16.14532Z","end":"2026-05-14T21:24:16.48973Z","steps":["trace[931333202] 'agreement among raft nodes before linearized reading' (duration: 343.958954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:16.490235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:16.145308Z","time spent":"344.887054ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T21:24:16.489621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.373928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-05-14T21:24:16.490498Z","caller":"traceutil/trace.go:171","msg":"trace[1870357612] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2097; }","duration":"197.276647ms","start":"2026-05-14T21:24:16.293195Z","end":"2026-05-14T21:24:16.490472Z","steps":["trace[1870357612] 'agreement among raft nodes before linearized reading' (duration: 196.323867ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:17.055768Z","caller":"traceutil/trace.go:171","msg":"trace[1297162039] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"310.540631ms","start":"2026-05-14T21:24:16.745209Z","end":"2026-05-14T21:24:17.05575Z","steps":["trace[1297162039] 'process raft request' (duration: 310.399879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.055938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:16.745192Z","time spent":"310.683672ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:24:17.910343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.289961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:24:17.910439Z","caller":"traceutil/trace.go:171","msg":"trace[1529565434] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2180; }","duration":"765.973474ms","start":"2026-05-14T21:24:17.14445Z","end":"2026-05-14T21:24:17.910423Z","steps":["trace[1529565434] 'read index received' (duration: 386.689733ms)","trace[1529565434] 'applied index is now lower than readState.Index' (duration: 379.282321ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:24:17.910562Z","caller":"traceutil/trace.go:171","msg":"trace[991901171] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"958.234633ms","start":"2026-05-14T21:24:16.952289Z","end":"2026-05-14T21:24:17.910523Z","steps":["trace[991901171] 'process raft request' (duration: 578.677109ms)","trace[991901171] 'compare' (duration: 378.893046ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:24:17.910726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.287119ms","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-05-14T21:24:17.91077Z","caller":"traceutil/trace.go:171","msg":"trace[1781483571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2099; }","duration":"766.907465ms","start":"2026-05-14T21:24:17.143849Z","end":"2026-05-14T21:24:17.910757Z","steps":["trace[1781483571] 'agreement among raft nodes before linearized reading' (duration: 766.778374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.910803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.143747Z","time spent":"767.048746ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T21:24:17.910877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:16.952268Z","time spent":"958.406164ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:24:17.951023Z","caller":"traceutil/trace.go:171","msg":"trace[193458159] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"806.158771ms","start":"2026-05-14T21:24:17.144817Z","end":"2026-05-14T21:24:17.950976Z","steps":["trace[193458159] 'process raft request' (duration: 805.778187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.95121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.144775Z","time spent":"806.336214ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:24:17.951043Z","caller":"traceutil/trace.go:171","msg":"trace[558991931] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"804.538293ms","start":"2026-05-14T21:24:17.146435Z","end":"2026-05-14T21:24:17.950973Z","steps":["trace[558991931] 'process raft request' (duration: 804.409402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.951609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.892597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18af8b94b0870e3d\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-05-14T21:24:17.951713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.399715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T21:24:17.9518Z","caller":"traceutil/trace.go:171","msg":"trace[1585629358] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2102; }","duration":"453.665097ms","start":"2026-05-14T21:24:17.498116Z","end":"2026-05-14T21:24:17.951781Z","steps":["trace[1585629358] 'agreement among raft nodes before linearized reading' (duration: 453.321243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.951864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.146421Z","time spent":"805.11738ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:24:17.95195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.777124ms","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-05-14T21:24:17.952Z","caller":"traceutil/trace.go:171","msg":"trace[859824829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2102; }","duration":"757.858684ms","start":"2026-05-14T21:24:17.194127Z","end":"2026-05-14T21:24:17.951986Z","steps":["trace[859824829] 'agreement among raft nodes before linearized reading' (duration: 757.736863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.952121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.194109Z","time spent":"757.998297ms","remote":"127.0.0.1:51566","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-05-14T21:24:17.951951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T21:24:17.951909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.498098Z","time spent":"453.797688ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T21:24:17.952232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.786626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:24:17.952224Z","caller":"traceutil/trace.go:171","msg":"trace[471140956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2102; }","duration":"756.714183ms","start":"2026-05-14T21:24:17.195493Z","end":"2026-05-14T21:24:17.952207Z","steps":["trace[471140956] 'agreement among raft nodes before linearized reading' (duration: 756.256308ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:17.952327Z","caller":"traceutil/trace.go:171","msg":"trace[1078240103] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2102; }","duration":"125.936559ms","start":"2026-05-14T21:24:17.826368Z","end":"2026-05-14T21:24:17.952304Z","steps":["trace[1078240103] 'agreement among raft nodes before linearized reading' (duration: 125.746687ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:17.951705Z","caller":"traceutil/trace.go:171","msg":"trace[50997767] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18af8b94b0870e3d; range_end:; response_count:1; response_revision:2102; }","duration":"805.024929ms","start":"2026-05-14T21:24:17.146662Z","end":"2026-05-14T21:24:17.951687Z","steps":["trace[50997767] 'agreement among raft nodes before linearized reading' (duration: 804.703756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.952446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.146646Z","time spent":"805.784127ms","remote":"127.0.0.1:51350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18af8b94b0870e3d\" "} {"level":"warn","ts":"2026-05-14T21:24:17.952463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.195485Z","time spent":"756.907165ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T21:24:17.952534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.548203ms","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-05-14T21:24:17.952644Z","caller":"traceutil/trace.go:171","msg":"trace[76983803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2102; }","duration":"776.705104ms","start":"2026-05-14T21:24:17.175915Z","end":"2026-05-14T21:24:17.95262Z","steps":["trace[76983803] 'agreement among raft nodes before linearized reading' (duration: 776.387391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.952697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.175893Z","time spent":"776.792406ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T21:24:17.952723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.518981ms","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-05-14T21:24:17.952763Z","caller":"traceutil/trace.go:171","msg":"trace[443609904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2102; }","duration":"757.617933ms","start":"2026-05-14T21:24:17.195138Z","end":"2026-05-14T21:24:17.952756Z","steps":["trace[443609904] 'agreement among raft nodes before linearized reading' (duration: 756.863464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.952787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.195117Z","time spent":"757.664883ms","remote":"127.0.0.1:51566","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":"info","ts":"2026-05-14T21:24:17.95164Z","caller":"traceutil/trace.go:171","msg":"trace[85784675] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"671.511526ms","start":"2026-05-14T21:24:17.280111Z","end":"2026-05-14T21:24:17.951623Z","steps":["trace[85784675] 'process raft request' (duration: 670.804789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:17.952938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:17.280054Z","time spent":"672.824121ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:24:18.146711Z","caller":"traceutil/trace.go:171","msg":"trace[1399334091] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2185; }","duration":"143.838341ms","start":"2026-05-14T21:24:18.00285Z","end":"2026-05-14T21:24:18.146689Z","steps":["trace[1399334091] 'read index received' (duration: 89.332848ms)","trace[1399334091] 'applied index is now lower than readState.Index' (duration: 54.503823ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:24:18.146943Z","caller":"traceutil/trace.go:171","msg":"trace[2025287177] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"187.378404ms","start":"2026-05-14T21:24:17.959555Z","end":"2026-05-14T21:24:18.146934Z","steps":["trace[2025287177] 'process raft request' (duration: 186.977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:18.147034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.166745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:24:18.146802Z","caller":"traceutil/trace.go:171","msg":"trace[9135587] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"187.686698ms","start":"2026-05-14T21:24:17.959081Z","end":"2026-05-14T21:24:18.146768Z","steps":["trace[9135587] 'process raft request' (duration: 187.370584ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:18.146847Z","caller":"traceutil/trace.go:171","msg":"trace[653150789] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"192.281549ms","start":"2026-05-14T21:24:17.954532Z","end":"2026-05-14T21:24:18.146813Z","steps":["trace[653150789] 'process raft request' (duration: 137.815745ms)","trace[653150789] 'compare' (duration: 53.929707ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:24:18.146866Z","caller":"traceutil/trace.go:171","msg":"trace[1131618804] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"186.596876ms","start":"2026-05-14T21:24:17.96026Z","end":"2026-05-14T21:24:18.146857Z","steps":["trace[1131618804] 'process raft request' (duration: 186.333253ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:18.14694Z","caller":"traceutil/trace.go:171","msg":"trace[295372070] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"186.313903ms","start":"2026-05-14T21:24:17.960616Z","end":"2026-05-14T21:24:18.14693Z","steps":["trace[295372070] 'process raft request' (duration: 186.03017ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:18.147318Z","caller":"traceutil/trace.go:171","msg":"trace[741748394] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2108; }","duration":"144.351037ms","start":"2026-05-14T21:24:18.002739Z","end":"2026-05-14T21:24:18.14709Z","steps":["trace[741748394] 'agreement among raft nodes before linearized reading' (duration: 144.218025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:19.147335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.942972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:24:19.147449Z","caller":"traceutil/trace.go:171","msg":"trace[1689609490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"666.062063ms","start":"2026-05-14T21:24:18.481347Z","end":"2026-05-14T21:24:19.147409Z","steps":["trace[1689609490] 'range keys from in-memory index tree' (duration: 665.89172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:19.147499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:18.481334Z","time spent":"666.152543ms","remote":"127.0.0.1:51250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T21:24:19.147752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.487895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-14T21:24:19.147794Z","caller":"traceutil/trace.go:171","msg":"trace[2052683644] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2108; }","duration":"806.571407ms","start":"2026-05-14T21:24:18.341213Z","end":"2026-05-14T21:24:19.147784Z","steps":["trace[2052683644] 'range keys from in-memory index tree' (duration: 806.392495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:19.147826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:18.341191Z","time spent":"806.628827ms","remote":"127.0.0.1:51338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-14T21:24:19.147857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.701968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:102430"} {"level":"info","ts":"2026-05-14T21:24:19.147939Z","caller":"traceutil/trace.go:171","msg":"trace[1947517224] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2108; }","duration":"806.830509ms","start":"2026-05-14T21:24:18.341084Z","end":"2026-05-14T21:24:19.147915Z","steps":["trace[1947517224] 'range keys from in-memory index tree' (duration: 806.461725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:19.148002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:18.341051Z","time spent":"806.93721ms","remote":"127.0.0.1:51326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":102452,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-14T21:24:19.148106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.201221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:24:19.148145Z","caller":"traceutil/trace.go:171","msg":"trace[88613127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"768.303422ms","start":"2026-05-14T21:24:18.379832Z","end":"2026-05-14T21:24:19.148136Z","steps":["trace[88613127] 'range keys from in-memory index tree' (duration: 768.11925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:19.148173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:18.379821Z","time spent":"768.345703ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T21:24:19.147805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:18.540733Z","time spent":"607.06519ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-14T21:24:19.148535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.125643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:24:19.148628Z","caller":"traceutil/trace.go:171","msg":"trace[1980555042] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"187.963724ms","start":"2026-05-14T21:24:18.960649Z","end":"2026-05-14T21:24:19.148612Z","steps":["trace[1980555042] 'read index received' (duration: 34.641µs)","trace[1980555042] 'applied index is now lower than readState.Index' (duration: 187.928033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:24:19.148762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.112456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T21:24:19.148794Z","caller":"traceutil/trace.go:171","msg":"trace[1850013763] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2109; }","duration":"188.179966ms","start":"2026-05-14T21:24:18.960605Z","end":"2026-05-14T21:24:19.148785Z","steps":["trace[1850013763] 'agreement among raft nodes before linearized reading' (duration: 188.050695ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:19.150172Z","caller":"traceutil/trace.go:171","msg":"trace[1529160110] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"654.381453ms","start":"2026-05-14T21:24:18.494278Z","end":"2026-05-14T21:24:19.148659Z","steps":["trace[1529160110] 'process raft request' (duration: 241.053638ms)","trace[1529160110] 'compare' (duration: 412.708438ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:24:19.150418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:24:18.494253Z","time spent":"656.013041ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:24:19.169314Z","caller":"traceutil/trace.go:171","msg":"trace[2022823963] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"108.9015ms","start":"2026-05-14T21:24:19.060381Z","end":"2026-05-14T21:24:19.169283Z","steps":["trace[2022823963] 'process raft request' (duration: 108.611817ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:23.301405Z","caller":"traceutil/trace.go:171","msg":"trace[2062446924] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"128.570636ms","start":"2026-05-14T21:24:23.172812Z","end":"2026-05-14T21:24:23.301383Z","steps":["trace[2062446924] 'process raft request' (duration: 128.438514ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:24:23.322582Z","caller":"traceutil/trace.go:171","msg":"trace[1030014432] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"143.283115ms","start":"2026-05-14T21:24:23.179262Z","end":"2026-05-14T21:24:23.322545Z","steps":["trace[1030014432] 'process raft request' (duration: 143.087943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:24:24.11169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.11051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:24:24.111981Z","caller":"traceutil/trace.go:171","msg":"trace[1167923254] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"135.400835ms","start":"2026-05-14T21:24:23.976483Z","end":"2026-05-14T21:24:24.111884Z","steps":["trace[1167923254] 'process raft request' (duration: 25.005552ms)","trace[1167923254] 'compare' (duration: 109.928008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:25:54.279041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.315811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:25:54.279126Z","caller":"traceutil/trace.go:171","msg":"trace[1240917048] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2651; }","duration":"159.457906ms","start":"2026-05-14T21:25:54.119654Z","end":"2026-05-14T21:25:54.279112Z","steps":["trace[1240917048] 'count revisions from in-memory index tree' (duration: 159.245458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:03.633909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.659662ms","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-05-14T21:26:03.633966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.894917ms","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-05-14T21:26:03.634047Z","caller":"traceutil/trace.go:171","msg":"trace[1159591655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2754; }","duration":"628.846539ms","start":"2026-05-14T21:26:03.00518Z","end":"2026-05-14T21:26:03.634027Z","steps":["trace[1159591655] 'range keys from in-memory index tree' (duration: 628.530787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:03.634097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:03.005164Z","time spent":"628.922622ms","remote":"127.0.0.1:51566","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":"info","ts":"2026-05-14T21:26:03.634098Z","caller":"traceutil/trace.go:171","msg":"trace[308869904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2754; }","duration":"659.119626ms","start":"2026-05-14T21:26:02.974956Z","end":"2026-05-14T21:26:03.634075Z","steps":["trace[308869904] 'range keys from in-memory index tree' (duration: 658.725341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:03.633964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.041913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-14T21:26:03.634149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:02.974941Z","time spent":"659.196468ms","remote":"127.0.0.1:51566","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-05-14T21:26:03.63404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.763801ms","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-05-14T21:26:03.634235Z","caller":"traceutil/trace.go:171","msg":"trace[1016942673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2754; }","duration":"659.421547ms","start":"2026-05-14T21:26:02.974785Z","end":"2026-05-14T21:26:03.634206Z","steps":["trace[1016942673] 'range keys from in-memory index tree' (duration: 658.856786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:03.634315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:02.974763Z","time spent":"659.52371ms","remote":"127.0.0.1:51566","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-05-14T21:26:03.634334Z","caller":"traceutil/trace.go:171","msg":"trace[2002619798] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2754; }","duration":"722.043791ms","start":"2026-05-14T21:26:02.912232Z","end":"2026-05-14T21:26:03.634276Z","steps":["trace[2002619798] 'count revisions from in-memory index tree' (duration: 721.693009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:03.634384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.775518ms","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-05-14T21:26:03.634427Z","caller":"traceutil/trace.go:171","msg":"trace[1041956193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2754; }","duration":"663.84068ms","start":"2026-05-14T21:26:02.970573Z","end":"2026-05-14T21:26:03.634414Z","steps":["trace[1041956193] 'range keys from in-memory index tree' (duration: 663.602472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:03.634431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:02.912216Z","time spent":"722.180286ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-14T21:26:03.634466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:02.970558Z","time spent":"663.893392ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T21:26:03.634537Z","caller":"traceutil/trace.go:171","msg":"trace[500287894] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"555.068677ms","start":"2026-05-14T21:26:03.079445Z","end":"2026-05-14T21:26:03.634514Z","steps":["trace[500287894] 'process raft request' (duration: 552.353393ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:03.634505Z","caller":"traceutil/trace.go:171","msg":"trace[1130963330] linearizableReadLoop","detail":"{readStateIndex:2861; appliedIndex:2860; }","duration":"180.391192ms","start":"2026-05-14T21:26:03.45406Z","end":"2026-05-14T21:26:03.634452Z","steps":["trace[1130963330] 'read index received' (duration: 177.800312ms)","trace[1130963330] 'applied index is now lower than readState.Index' (duration: 2.58932ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:26:03.634671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:03.079423Z","time spent":"555.15274ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:26:03.634803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.716714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-14T21:26:03.634804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.381375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:26:03.634834Z","caller":"traceutil/trace.go:171","msg":"trace[835331701] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2755; }","duration":"180.805387ms","start":"2026-05-14T21:26:03.454017Z","end":"2026-05-14T21:26:03.634823Z","steps":["trace[835331701] 'agreement among raft nodes before linearized reading' (duration: 180.61361ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:03.634852Z","caller":"traceutil/trace.go:171","msg":"trace[1213135754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2755; }","duration":"153.426506ms","start":"2026-05-14T21:26:03.481411Z","end":"2026-05-14T21:26:03.634837Z","steps":["trace[1213135754] 'agreement among raft nodes before linearized reading' (duration: 153.24619ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:29.995657Z","caller":"traceutil/trace.go:171","msg":"trace[1937743828] linearizableReadLoop","detail":"{readStateIndex:3013; appliedIndex:3012; }","duration":"168.056984ms","start":"2026-05-14T21:26:29.827575Z","end":"2026-05-14T21:26:29.995632Z","steps":["trace[1937743828] 'read index received' (duration: 167.834888ms)","trace[1937743828] 'applied index is now lower than readState.Index' (duration: 221.206µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:26:29.995725Z","caller":"traceutil/trace.go:171","msg":"trace[1438792537] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"181.117362ms","start":"2026-05-14T21:26:29.814587Z","end":"2026-05-14T21:26:29.995704Z","steps":["trace[1438792537] 'process raft request' (duration: 180.871245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:29.996139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.873027ms","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-05-14T21:26:29.996197Z","caller":"traceutil/trace.go:171","msg":"trace[951136688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2902; }","duration":"163.95096ms","start":"2026-05-14T21:26:29.832231Z","end":"2026-05-14T21:26:29.996182Z","steps":["trace[951136688] 'agreement among raft nodes before linearized reading' (duration: 163.798015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:29.995987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.385854ms","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-05-14T21:26:29.996388Z","caller":"traceutil/trace.go:171","msg":"trace[520421344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2902; }","duration":"168.821087ms","start":"2026-05-14T21:26:29.827543Z","end":"2026-05-14T21:26:29.996364Z","steps":["trace[520421344] 'agreement among raft nodes before linearized reading' (duration: 168.229019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:29.996059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.301381ms","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-05-14T21:26:29.996567Z","caller":"traceutil/trace.go:171","msg":"trace[1325898059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2902; }","duration":"164.840937ms","start":"2026-05-14T21:26:29.83171Z","end":"2026-05-14T21:26:29.996551Z","steps":["trace[1325898059] 'agreement among raft nodes before linearized reading' (duration: 164.200788ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:30.116652Z","caller":"traceutil/trace.go:171","msg":"trace[1539603934] transaction","detail":"{read_only:false; response_revision:2904; number_of_response:1; }","duration":"114.190759ms","start":"2026-05-14T21:26:30.002443Z","end":"2026-05-14T21:26:30.116634Z","steps":["trace[1539603934] 'process raft request' (duration: 27.772401ms)","trace[1539603934] 'compare' (duration: 86.131409ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:26:30.117046Z","caller":"traceutil/trace.go:171","msg":"trace[2042667245] transaction","detail":"{read_only:false; response_revision:2905; number_of_response:1; }","duration":"113.834578ms","start":"2026-05-14T21:26:30.003203Z","end":"2026-05-14T21:26:30.117038Z","steps":["trace[2042667245] 'process raft request' (duration: 113.402945ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:30.499602Z","caller":"traceutil/trace.go:171","msg":"trace[1913137487] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"107.201037ms","start":"2026-05-14T21:26:30.392354Z","end":"2026-05-14T21:26:30.499555Z","steps":["trace[1913137487] 'process raft request' (duration: 106.912507ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:31.067746Z","caller":"traceutil/trace.go:171","msg":"trace[2004067128] transaction","detail":"{read_only:false; response_revision:2913; number_of_response:1; }","duration":"124.368076ms","start":"2026-05-14T21:26:30.943353Z","end":"2026-05-14T21:26:31.067721Z","steps":["trace[2004067128] 'process raft request' (duration: 120.51791ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:32.319316Z","caller":"traceutil/trace.go:171","msg":"trace[1734466527] transaction","detail":"{read_only:false; response_revision:2919; number_of_response:1; }","duration":"116.924217ms","start":"2026-05-14T21:26:32.202357Z","end":"2026-05-14T21:26:32.319281Z","steps":["trace[1734466527] 'process raft request' (duration: 63.034961ms)","trace[1734466527] 'compare' (duration: 53.766682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:26:36.545868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10857226697887841990,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T21:26:36.968587Z","caller":"traceutil/trace.go:171","msg":"trace[369065771] transaction","detail":"{read_only:false; response_revision:2935; number_of_response:1; }","duration":"930.241227ms","start":"2026-05-14T21:26:36.038314Z","end":"2026-05-14T21:26:36.968556Z","steps":["trace[369065771] 'process raft request' (duration: 930.017051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:36.969111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.0383Z","time spent":"930.366071ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:26:37.507277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.858772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:26:37.507909Z","caller":"traceutil/trace.go:171","msg":"trace[1904250084] transaction","detail":"{read_only:false; response_revision:2937; number_of_response:1; }","duration":"1.176673307s","start":"2026-05-14T21:26:36.331203Z","end":"2026-05-14T21:26:37.507876Z","steps":["trace[1904250084] 'process raft request' (duration: 1.176329727s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:37.508143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.331177Z","time spent":"1.176864213s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:26:37.50815Z","caller":"traceutil/trace.go:171","msg":"trace[645830749] transaction","detail":"{read_only:false; response_revision:2938; number_of_response:1; }","duration":"905.289883ms","start":"2026-05-14T21:26:36.602763Z","end":"2026-05-14T21:26:37.508053Z","steps":["trace[645830749] 'process raft request' (duration: 904.955434ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:37.508283Z","caller":"traceutil/trace.go:171","msg":"trace[1566626774] transaction","detail":"{read_only:false; response_revision:2939; number_of_response:1; }","duration":"689.410622ms","start":"2026-05-14T21:26:36.818858Z","end":"2026-05-14T21:26:37.508269Z","steps":["trace[1566626774] 'process raft request' (duration: 689.031231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:37.508295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.602744Z","time spent":"905.484668ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:26:37.508338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.818838Z","time spent":"689.472844ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T21:26:37.508154Z","caller":"traceutil/trace.go:171","msg":"trace[1470645631] linearizableReadLoop","detail":"{readStateIndex:3050; appliedIndex:3047; }","duration":"1.462815525s","start":"2026-05-14T21:26:36.045183Z","end":"2026-05-14T21:26:37.507998Z","steps":["trace[1470645631] 'read index received' (duration: 923.115778ms)","trace[1470645631] 'applied index is now lower than readState.Index' (duration: 539.698227ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:26:37.508534Z","caller":"traceutil/trace.go:171","msg":"trace[1331013355] transaction","detail":"{read_only:false; response_revision:2936; number_of_response:1; }","duration":"1.354301383s","start":"2026-05-14T21:26:36.153324Z","end":"2026-05-14T21:26:37.507625Z","steps":["trace[1331013355] 'process raft request' (duration: 1.194989768s)","trace[1331013355] 'compare' (duration: 158.718858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:26:37.508616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.153302Z","time spent":"1.355277232s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T21:26:37.508648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463432554s","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-05-14T21:26:37.508736Z","caller":"traceutil/trace.go:171","msg":"trace[1735777242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2939; }","duration":"1.463555378s","start":"2026-05-14T21:26:36.04516Z","end":"2026-05-14T21:26:37.508716Z","steps":["trace[1735777242] 'agreement among raft nodes before linearized reading' (duration: 1.463267198s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:37.508789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.045146Z","time spent":"1.463620999s","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T21:26:37.508813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.585216ms","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-05-14T21:26:37.50887Z","caller":"traceutil/trace.go:171","msg":"trace[314079523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2939; }","duration":"418.661639ms","start":"2026-05-14T21:26:37.090178Z","end":"2026-05-14T21:26:37.50884Z","steps":["trace[314079523] 'agreement among raft nodes before linearized reading' (duration: 418.532905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:37.508902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:37.09016Z","time spent":"418.732841ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T21:26:37.509229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333731358s","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-05-14T21:26:37.510477Z","caller":"traceutil/trace.go:171","msg":"trace[592319999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2939; }","duration":"1.335015345s","start":"2026-05-14T21:26:36.175445Z","end":"2026-05-14T21:26:37.510461Z","steps":["trace[592319999] 'agreement among raft nodes before linearized reading' (duration: 1.333255514s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:37.510528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.175425Z","time spent":"1.335090037s","remote":"127.0.0.1:51566","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-05-14T21:26:37.509227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.890492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-14T21:26:37.509247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331835462s","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-05-14T21:26:37.51061Z","caller":"traceutil/trace.go:171","msg":"trace[333792546] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2939; }","duration":"956.318644ms","start":"2026-05-14T21:26:36.554262Z","end":"2026-05-14T21:26:37.510581Z","steps":["trace[333792546] 'agreement among raft nodes before linearized reading' (duration: 954.737018ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:37.510652Z","caller":"traceutil/trace.go:171","msg":"trace[1271774421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2939; }","duration":"1.333273144s","start":"2026-05-14T21:26:36.177361Z","end":"2026-05-14T21:26:37.510634Z","steps":["trace[1271774421] 'agreement among raft nodes before linearized reading' (duration: 1.33175504s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:37.510672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.554243Z","time spent":"956.411366ms","remote":"127.0.0.1:51480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-14T21:26:37.510754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T21:26:36.177348Z","time spent":"1.333358196s","remote":"127.0.0.1:51566","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":"info","ts":"2026-05-14T21:26:37.622615Z","caller":"traceutil/trace.go:171","msg":"trace[773255172] transaction","detail":"{read_only:false; response_revision:2940; number_of_response:1; }","duration":"108.442074ms","start":"2026-05-14T21:26:37.514144Z","end":"2026-05-14T21:26:37.622586Z","steps":["trace[773255172] 'process raft request' (duration: 104.296843ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:37.62287Z","caller":"traceutil/trace.go:171","msg":"trace[723854880] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"107.382834ms","start":"2026-05-14T21:26:37.515476Z","end":"2026-05-14T21:26:37.622859Z","steps":["trace[723854880] 'process raft request' (duration: 107.066295ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:37.622877Z","caller":"traceutil/trace.go:171","msg":"trace[1223004403] linearizableReadLoop","detail":"{readStateIndex:3056; appliedIndex:3052; }","duration":"106.980782ms","start":"2026-05-14T21:26:37.515879Z","end":"2026-05-14T21:26:37.62286Z","steps":["trace[1223004403] 'read index received' (duration: 102.572043ms)","trace[1223004403] 'applied index is now lower than readState.Index' (duration: 4.407819ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:26:37.622946Z","caller":"traceutil/trace.go:171","msg":"trace[499897426] transaction","detail":"{read_only:false; response_revision:2943; number_of_response:1; }","duration":"107.400404ms","start":"2026-05-14T21:26:37.515538Z","end":"2026-05-14T21:26:37.622939Z","steps":["trace[499897426] 'process raft request' (duration: 107.207659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:26:37.623079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.180968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-05-14T21:26:37.623132Z","caller":"traceutil/trace.go:171","msg":"trace[801681434] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2943; }","duration":"107.270301ms","start":"2026-05-14T21:26:37.515848Z","end":"2026-05-14T21:26:37.623118Z","steps":["trace[801681434] 'agreement among raft nodes before linearized reading' (duration: 107.071015ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:37.622607Z","caller":"traceutil/trace.go:171","msg":"trace[1605491956] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"107.877628ms","start":"2026-05-14T21:26:37.514699Z","end":"2026-05-14T21:26:37.622577Z","steps":["trace[1605491956] 'process raft request' (duration: 107.800386ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:26:59.261503Z","caller":"traceutil/trace.go:171","msg":"trace[608919630] transaction","detail":"{read_only:false; response_revision:3051; number_of_response:1; }","duration":"120.205721ms","start":"2026-05-14T21:26:59.14125Z","end":"2026-05-14T21:26:59.261456Z","steps":["trace[608919630] 'process raft request' (duration: 119.983495ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:27:09.817866Z","caller":"traceutil/trace.go:171","msg":"trace[1743311231] transaction","detail":"{read_only:false; response_revision:3105; number_of_response:1; }","duration":"167.029412ms","start":"2026-05-14T21:27:09.650814Z","end":"2026-05-14T21:27:09.817843Z","steps":["trace[1743311231] 'process raft request' (duration: 166.96486ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:27:09.81789Z","caller":"traceutil/trace.go:171","msg":"trace[1289540715] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"167.33332ms","start":"2026-05-14T21:27:09.650541Z","end":"2026-05-14T21:27:09.817874Z","steps":["trace[1289540715] 'process raft request' (duration: 97.393838ms)","trace[1289540715] 'compare' (duration: 69.726646ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:27:09.818233Z","caller":"traceutil/trace.go:171","msg":"trace[486103278] transaction","detail":"{read_only:false; response_revision:3106; number_of_response:1; }","duration":"165.581036ms","start":"2026-05-14T21:27:09.652609Z","end":"2026-05-14T21:27:09.81819Z","steps":["trace[486103278] 'process raft request' (duration: 165.207206ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:27:26.098973Z","caller":"traceutil/trace.go:171","msg":"trace[1021042468] linearizableReadLoop","detail":"{readStateIndex:3317; appliedIndex:3316; }","duration":"117.429374ms","start":"2026-05-14T21:27:25.981513Z","end":"2026-05-14T21:27:26.098942Z","steps":["trace[1021042468] 'read index received' (duration: 86.712719ms)","trace[1021042468] 'applied index is now lower than readState.Index' (duration: 30.715825ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:27:26.099235Z","caller":"traceutil/trace.go:171","msg":"trace[1306098852] transaction","detail":"{read_only:false; response_revision:3193; number_of_response:1; }","duration":"158.318115ms","start":"2026-05-14T21:27:25.9409Z","end":"2026-05-14T21:27:26.099218Z","steps":["trace[1306098852] 'process raft request' (duration: 127.280212ms)","trace[1306098852] 'compare' (duration: 30.637594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:27:26.099552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.021529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:27:26.100193Z","caller":"traceutil/trace.go:171","msg":"trace[2120948094] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:3193; }","duration":"118.102851ms","start":"2026-05-14T21:27:25.981502Z","end":"2026-05-14T21:27:26.099604Z","steps":["trace[2120948094] 'agreement among raft nodes before linearized reading' (duration: 117.990898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:27:26.100197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.274549ms","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-05-14T21:27:26.100296Z","caller":"traceutil/trace.go:171","msg":"trace[2110914510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3193; }","duration":"111.396392ms","start":"2026-05-14T21:27:25.988869Z","end":"2026-05-14T21:27:26.100266Z","steps":["trace[2110914510] 'agreement among raft nodes before linearized reading' (duration: 110.701176ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:27:26.37015Z","caller":"traceutil/trace.go:171","msg":"trace[428052238] transaction","detail":"{read_only:false; response_revision:3198; number_of_response:1; }","duration":"134.941556ms","start":"2026-05-14T21:27:26.235182Z","end":"2026-05-14T21:27:26.370124Z","steps":["trace[428052238] 'process raft request' (duration: 52.540531ms)","trace[428052238] 'compare' (duration: 82.305942ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:28:02.547572Z","caller":"traceutil/trace.go:171","msg":"trace[1421745990] transaction","detail":"{read_only:false; response_revision:3386; number_of_response:1; }","duration":"126.742045ms","start":"2026-05-14T21:28:02.420809Z","end":"2026-05-14T21:28:02.547552Z","steps":["trace[1421745990] 'process raft request' (duration: 126.569462ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:28:30.737964Z","caller":"traceutil/trace.go:171","msg":"trace[1529968741] transaction","detail":"{read_only:false; response_revision:3528; number_of_response:1; }","duration":"133.752679ms","start":"2026-05-14T21:28:30.604183Z","end":"2026-05-14T21:28:30.737935Z","steps":["trace[1529968741] 'process raft request' (duration: 36.876974ms)","trace[1529968741] 'compare' (duration: 96.507898ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:29:08.19063Z","caller":"traceutil/trace.go:171","msg":"trace[1097757718] transaction","detail":"{read_only:false; response_revision:3813; number_of_response:1; }","duration":"144.124682ms","start":"2026-05-14T21:29:08.046482Z","end":"2026-05-14T21:29:08.190606Z","steps":["trace[1097757718] 'process raft request' (duration: 143.981649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:38.353835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.490821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T21:30:38.353934Z","caller":"traceutil/trace.go:171","msg":"trace[1348486320] linearizableReadLoop","detail":"{readStateIndex:5107; appliedIndex:5106; }","duration":"244.023532ms","start":"2026-05-14T21:30:38.109896Z","end":"2026-05-14T21:30:38.353919Z","steps":["trace[1348486320] 'read index received' (duration: 137.285488ms)","trace[1348486320] 'applied index is now lower than readState.Index' (duration: 106.736884ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:30:38.354136Z","caller":"traceutil/trace.go:171","msg":"trace[1101350793] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"282.848203ms","start":"2026-05-14T21:30:38.071242Z","end":"2026-05-14T21:30:38.354091Z","steps":["trace[1101350793] 'process raft request' (duration: 176.012898ms)","trace[1101350793] 'compare' (duration: 106.381009ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:30:38.354702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.399257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:30:38.354775Z","caller":"traceutil/trace.go:171","msg":"trace[197451533] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4915; }","duration":"244.921985ms","start":"2026-05-14T21:30:38.109841Z","end":"2026-05-14T21:30:38.354763Z","steps":["trace[197451533] 'agreement among raft nodes before linearized reading' (duration: 244.341587ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.51443Z","caller":"traceutil/trace.go:171","msg":"trace[685756943] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5107; }","duration":"154.962948ms","start":"2026-05-14T21:30:38.359447Z","end":"2026-05-14T21:30:38.51441Z","steps":["trace[685756943] 'read index received' (duration: 154.928737ms)","trace[685756943] 'applied index is now lower than readState.Index' (duration: 33.281µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:30:38.51446Z","caller":"traceutil/trace.go:171","msg":"trace[637223124] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"157.429703ms","start":"2026-05-14T21:30:38.357002Z","end":"2026-05-14T21:30:38.514432Z","steps":["trace[637223124] 'process raft request' (duration: 157.259121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:38.514781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.312044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e97b5dac-301e-4058-9500-a2f3296d7fd0\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-14T21:30:38.514842Z","caller":"traceutil/trace.go:171","msg":"trace[875966969] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e97b5dac-301e-4058-9500-a2f3296d7fd0; range_end:; response_count:1; response_revision:4916; }","duration":"155.411925ms","start":"2026-05-14T21:30:38.359413Z","end":"2026-05-14T21:30:38.514825Z","steps":["trace[875966969] 'agreement among raft nodes before linearized reading' (duration: 155.206252ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.544554Z","caller":"traceutil/trace.go:171","msg":"trace[1197804749] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"178.223989ms","start":"2026-05-14T21:30:38.366307Z","end":"2026-05-14T21:30:38.544531Z","steps":["trace[1197804749] 'process raft request' (duration: 178.028997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:38.544861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.136789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T21:30:38.544877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.808788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T21:30:38.544932Z","caller":"traceutil/trace.go:171","msg":"trace[1924523429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4918; }","duration":"164.261571ms","start":"2026-05-14T21:30:38.380654Z","end":"2026-05-14T21:30:38.544915Z","steps":["trace[1924523429] 'agreement among raft nodes before linearized reading' (duration: 164.072118ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.545234Z","caller":"traceutil/trace.go:171","msg":"trace[765170233] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4918; }","duration":"121.94154ms","start":"2026-05-14T21:30:38.422988Z","end":"2026-05-14T21:30:38.54493Z","steps":["trace[765170233] 'agreement among raft nodes before linearized reading' (duration: 121.822868ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.748135Z","caller":"traceutil/trace.go:171","msg":"trace[232696961] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"117.240733ms","start":"2026-05-14T21:30:38.63086Z","end":"2026-05-14T21:30:38.7481Z","steps":["trace[232696961] 'process raft request' (duration: 78.30647ms)","trace[232696961] 'compare' (duration: 38.655648ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:30:38.748443Z","caller":"traceutil/trace.go:171","msg":"trace[717440423] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"117.394495ms","start":"2026-05-14T21:30:38.631035Z","end":"2026-05-14T21:30:38.748429Z","steps":["trace[717440423] 'process raft request' (duration: 116.97855ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.748446Z","caller":"traceutil/trace.go:171","msg":"trace[986325788] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"102.13546ms","start":"2026-05-14T21:30:38.646238Z","end":"2026-05-14T21:30:38.748374Z","steps":["trace[986325788] 'process raft request' (duration: 101.865476ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.748484Z","caller":"traceutil/trace.go:171","msg":"trace[1321880236] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"102.113659ms","start":"2026-05-14T21:30:38.646343Z","end":"2026-05-14T21:30:38.748456Z","steps":["trace[1321880236] 'process raft request' (duration: 101.889266ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.748773Z","caller":"traceutil/trace.go:171","msg":"trace[2001430667] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"101.936437ms","start":"2026-05-14T21:30:38.646816Z","end":"2026-05-14T21:30:38.748753Z","steps":["trace[2001430667] 'process raft request' (duration: 101.45891ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.748844Z","caller":"traceutil/trace.go:171","msg":"trace[155416823] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"101.857156ms","start":"2026-05-14T21:30:38.646967Z","end":"2026-05-14T21:30:38.748824Z","steps":["trace[155416823] 'process raft request' (duration: 101.704304ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.918976Z","caller":"traceutil/trace.go:171","msg":"trace[348234077] linearizableReadLoop","detail":"{readStateIndex:5124; appliedIndex:5122; }","duration":"170.144264ms","start":"2026-05-14T21:30:38.748809Z","end":"2026-05-14T21:30:38.918953Z","steps":["trace[348234077] 'read index received' (duration: 104.263399ms)","trace[348234077] 'applied index is now lower than readState.Index' (duration: 65.879955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:30:38.919108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.698325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:30:38.919148Z","caller":"traceutil/trace.go:171","msg":"trace[1811596226] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4933; }","duration":"235.779697ms","start":"2026-05-14T21:30:38.683355Z","end":"2026-05-14T21:30:38.919135Z","steps":["trace[1811596226] 'agreement among raft nodes before linearized reading' (duration: 235.685976ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.919224Z","caller":"traceutil/trace.go:171","msg":"trace[1603011085] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"246.562129ms","start":"2026-05-14T21:30:38.672637Z","end":"2026-05-14T21:30:38.9192Z","steps":["trace[1603011085] 'process raft request' (duration: 180.530111ms)","trace[1603011085] 'compare' (duration: 65.53084ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:30:38.919251Z","caller":"traceutil/trace.go:171","msg":"trace[1898774801] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"208.135184ms","start":"2026-05-14T21:30:38.711086Z","end":"2026-05-14T21:30:38.919221Z","steps":["trace[1898774801] 'process raft request' (duration: 207.755609ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:38.919487Z","caller":"traceutil/trace.go:171","msg":"trace[87379068] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"164.022007ms","start":"2026-05-14T21:30:38.755285Z","end":"2026-05-14T21:30:38.919307Z","steps":["trace[87379068] 'process raft request' (duration: 163.619092ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:39.103673Z","caller":"traceutil/trace.go:171","msg":"trace[1427996843] linearizableReadLoop","detail":"{readStateIndex:5129; appliedIndex:5126; }","duration":"125.360536ms","start":"2026-05-14T21:30:38.97829Z","end":"2026-05-14T21:30:39.103651Z","steps":["trace[1427996843] 'read index received' (duration: 14.07769ms)","trace[1427996843] 'applied index is now lower than readState.Index' (duration: 111.281816ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:30:39.103881Z","caller":"traceutil/trace.go:171","msg":"trace[1615180159] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"125.367056ms","start":"2026-05-14T21:30:38.978501Z","end":"2026-05-14T21:30:39.103868Z","steps":["trace[1615180159] 'process raft request' (duration: 125.089211ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:39.103966Z","caller":"traceutil/trace.go:171","msg":"trace[177075308] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"126.366471ms","start":"2026-05-14T21:30:38.977554Z","end":"2026-05-14T21:30:39.10392Z","steps":["trace[177075308] 'process raft request' (duration: 80.576422ms)","trace[177075308] 'compare' (duration: 45.318901ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:30:39.104493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.182317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9698"} {"level":"info","ts":"2026-05-14T21:30:39.104544Z","caller":"traceutil/trace.go:171","msg":"trace[1455027103] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4938; }","duration":"126.267078ms","start":"2026-05-14T21:30:38.978265Z","end":"2026-05-14T21:30:39.104532Z","steps":["trace[1455027103] 'agreement among raft nodes before linearized reading' (duration: 126.102656ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:39.231816Z","caller":"traceutil/trace.go:171","msg":"trace[654207543] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"121.38632ms","start":"2026-05-14T21:30:39.110406Z","end":"2026-05-14T21:30:39.231793Z","steps":["trace[654207543] 'process raft request' (duration: 100.221801ms)","trace[654207543] 'compare' (duration: 20.930726ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:30:39.232189Z","caller":"traceutil/trace.go:171","msg":"trace[388081898] linearizableReadLoop","detail":"{readStateIndex:5133; appliedIndex:5130; }","duration":"118.974855ms","start":"2026-05-14T21:30:39.113197Z","end":"2026-05-14T21:30:39.232172Z","steps":["trace[388081898] 'read index received' (duration: 97.450041ms)","trace[388081898] 'applied index is now lower than readState.Index' (duration: 21.524214ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:30:39.23228Z","caller":"traceutil/trace.go:171","msg":"trace[1072389092] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"119.804296ms","start":"2026-05-14T21:30:39.112443Z","end":"2026-05-14T21:30:39.232248Z","steps":["trace[1072389092] 'process raft request' (duration: 119.683905ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:39.232376Z","caller":"traceutil/trace.go:171","msg":"trace[1264961784] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"121.540872ms","start":"2026-05-14T21:30:39.110813Z","end":"2026-05-14T21:30:39.232354Z","steps":["trace[1264961784] 'process raft request' (duration: 120.906863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:39.232466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.186839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-14T21:30:39.232548Z","caller":"traceutil/trace.go:171","msg":"trace[1571542958] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4941; }","duration":"117.292272ms","start":"2026-05-14T21:30:39.115244Z","end":"2026-05-14T21:30:39.232536Z","steps":["trace[1571542958] 'agreement among raft nodes before linearized reading' (duration: 117.132319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:39.232616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.359739ms","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-05-14T21:30:39.232681Z","caller":"traceutil/trace.go:171","msg":"trace[766696001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4941; }","duration":"104.476351ms","start":"2026-05-14T21:30:39.128192Z","end":"2026-05-14T21:30:39.232669Z","steps":["trace[766696001] 'agreement among raft nodes before linearized reading' (duration: 104.254757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:39.232873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.671456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-05-14T21:30:39.232909Z","caller":"traceutil/trace.go:171","msg":"trace[329590511] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4941; }","duration":"119.738526ms","start":"2026-05-14T21:30:39.11316Z","end":"2026-05-14T21:30:39.232899Z","steps":["trace[329590511] 'agreement among raft nodes before linearized reading' (duration: 119.083526ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:42.874188Z","caller":"traceutil/trace.go:171","msg":"trace[87439554] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"111.802226ms","start":"2026-05-14T21:30:42.76235Z","end":"2026-05-14T21:30:42.874152Z","steps":["trace[87439554] 'process raft request' (duration: 111.268329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:42.874536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.222535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:30:42.874579Z","caller":"traceutil/trace.go:171","msg":"trace[1983951239] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5016; }","duration":"110.330667ms","start":"2026-05-14T21:30:42.764237Z","end":"2026-05-14T21:30:42.874567Z","steps":["trace[1983951239] 'agreement among raft nodes before linearized reading' (duration: 110.233985ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:42.874291Z","caller":"traceutil/trace.go:171","msg":"trace[905517421] linearizableReadLoop","detail":"{readStateIndex:5209; appliedIndex:5209; }","duration":"109.966501ms","start":"2026-05-14T21:30:42.764291Z","end":"2026-05-14T21:30:42.874257Z","steps":["trace[905517421] 'read index received' (duration: 109.94404ms)","trace[905517421] 'applied index is now lower than readState.Index' (duration: 21.281µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T21:30:42.879848Z","caller":"traceutil/trace.go:171","msg":"trace[1103095601] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"115.409688ms","start":"2026-05-14T21:30:42.764408Z","end":"2026-05-14T21:30:42.879818Z","steps":["trace[1103095601] 'process raft request' (duration: 115.302717ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:42.88018Z","caller":"traceutil/trace.go:171","msg":"trace[1092389377] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"115.045923ms","start":"2026-05-14T21:30:42.765114Z","end":"2026-05-14T21:30:42.88016Z","steps":["trace[1092389377] 'process raft request' (duration: 114.655137ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:42.880475Z","caller":"traceutil/trace.go:171","msg":"trace[1542945227] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"115.466999ms","start":"2026-05-14T21:30:42.7644Z","end":"2026-05-14T21:30:42.879867Z","steps":["trace[1542945227] 'process raft request' (duration: 115.135364ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:47.670333Z","caller":"traceutil/trace.go:171","msg":"trace[715510189] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"225.136644ms","start":"2026-05-14T21:30:47.445176Z","end":"2026-05-14T21:30:47.670313Z","steps":["trace[715510189] 'process raft request' (duration: 224.998422ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:47.671407Z","caller":"traceutil/trace.go:171","msg":"trace[918079794] linearizableReadLoop","detail":"{readStateIndex:5310; appliedIndex:5308; }","duration":"190.052452ms","start":"2026-05-14T21:30:47.481332Z","end":"2026-05-14T21:30:47.671385Z","steps":["trace[918079794] 'read index received' (duration: 188.778473ms)","trace[918079794] 'applied index is now lower than readState.Index' (duration: 1.273269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T21:30:47.671547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.207604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T21:30:47.671539Z","caller":"traceutil/trace.go:171","msg":"trace[2085853915] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"226.245449ms","start":"2026-05-14T21:30:47.445255Z","end":"2026-05-14T21:30:47.671501Z","steps":["trace[2085853915] 'process raft request' (duration: 226.039296ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:47.671593Z","caller":"traceutil/trace.go:171","msg":"trace[1735747137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5113; }","duration":"190.298615ms","start":"2026-05-14T21:30:47.481284Z","end":"2026-05-14T21:30:47.671582Z","steps":["trace[1735747137] 'agreement among raft nodes before linearized reading' (duration: 190.221614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:47.671726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.944606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-14T21:30:47.67177Z","caller":"traceutil/trace.go:171","msg":"trace[1305444493] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5113; }","duration":"189.040157ms","start":"2026-05-14T21:30:47.482716Z","end":"2026-05-14T21:30:47.671756Z","steps":["trace[1305444493] 'agreement among raft nodes before linearized reading' (duration: 188.905735ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:52.60196Z","caller":"traceutil/trace.go:171","msg":"trace[483464185] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"117.240751ms","start":"2026-05-14T21:30:52.484686Z","end":"2026-05-14T21:30:52.601927Z","steps":["trace[483464185] 'process raft request' (duration: 116.904807ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:52.739543Z","caller":"traceutil/trace.go:171","msg":"trace[167735334] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"131.062823ms","start":"2026-05-14T21:30:52.608457Z","end":"2026-05-14T21:30:52.73952Z","steps":["trace[167735334] 'process raft request' (duration: 121.266027ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:52.739576Z","caller":"traceutil/trace.go:171","msg":"trace[1324348009] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"130.946941ms","start":"2026-05-14T21:30:52.608601Z","end":"2026-05-14T21:30:52.739548Z","steps":["trace[1324348009] 'process raft request' (duration: 130.794669ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:30:52.752519Z","caller":"traceutil/trace.go:171","msg":"trace[583841172] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"136.114423ms","start":"2026-05-14T21:30:52.616374Z","end":"2026-05-14T21:30:52.752488Z","steps":["trace[583841172] 'process raft request' (duration: 135.678047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:30:53.193777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.422538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-14T21:30:53.193832Z","caller":"traceutil/trace.go:171","msg":"trace[1760990973] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5162; }","duration":"106.52959ms","start":"2026-05-14T21:30:53.087287Z","end":"2026-05-14T21:30:53.193816Z","steps":["trace[1760990973] 'range keys from in-memory index tree' (duration: 106.275286ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T21:31:17.088505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2837} {"level":"info","ts":"2026-05-14T21:31:17.157787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2837,"took":"67.937976ms","hash":998636527,"current-db-size-bytes":51609600,"current-db-size":"52 MB","current-db-size-in-use-bytes":27389952,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-14T21:31:17.157843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":998636527,"revision":2837,"compact-revision":-1} {"level":"info","ts":"2026-05-14T21:31:28.290437Z","caller":"traceutil/trace.go:171","msg":"trace[1948390096] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"164.845825ms","start":"2026-05-14T21:31:28.125562Z","end":"2026-05-14T21:31:28.290408Z","steps":["trace[1948390096] 'process raft request' (duration: 164.683623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T21:34:25.362162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.164632ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-14T21:36:17.094401Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5321} {"level":"info","ts":"2026-05-14T21:36:17.197425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5321,"took":"101.726192ms","hash":2379247768,"current-db-size-bytes":51609600,"current-db-size":"52 MB","current-db-size-in-use-bytes":18640896,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-05-14T21:36:17.197516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2379247768,"revision":5321,"compact-revision":2837}