{"level":"warn","ts":"2026-09-22T03:38:31.529827Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:38:31.529985Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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://162.253.55.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-09-22T03:38:31.530065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:38:31.530074Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-09-22T03:38:31.530096Z","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-09-22T03:38:31.530752Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-09-22T03:38:31.53083Z","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://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-09-22T03:38:31.535351Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.310518ms"} {"level":"info","ts":"2026-09-22T03:38:31.547972Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-09-22T03:38:31.54806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T03:38:31.548096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-09-22T03:38:31.548111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T03:38:31.548121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-09-22T03:38:31.548184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-09-22T03:38:31.556632Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T03:38:31.557991Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T03:38:31.559104Z","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-09-22T03:38:31.560903Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T03:38:31.561368Z","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-09-22T03:38:31.561443Z","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-09-22T03:38:31.561456Z","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-09-22T03:38:31.56177Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T03:38:31.562539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-09-22T03:38:31.562644Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-09-22T03:38:31.563915Z","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-09-22T03:38:31.563988Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-09-22T03:38:31.564013Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-09-22T03:38:31.564149Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T03:38:31.564173Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T03:38:32.448653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T03:38:32.448717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T03:38:32.448754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-09-22T03:38:32.448775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-09-22T03:38:32.448804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-09-22T03:38:32.448825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-09-22T03:38:32.448839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-09-22T03:38:32.449758Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T03:38:32.449783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:38:32.449875Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:38:32.45004Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:38:32.450099Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T03:38:32.450142Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T03:38:32.451008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:38:32.451934Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:38:32.452036Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:38:32.452392Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T03:38:32.453103Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-09-22T03:39:04.077245Z","caller":"traceutil/trace.go:171","msg":"trace[1137883676] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"107.839563ms","start":"2026-09-22T03:39:03.969351Z","end":"2026-09-22T03:39:04.077191Z","steps":["trace[1137883676] 'process raft request' (duration: 90.200628ms)","trace[1137883676] 'compare' (duration: 17.405693ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:39:16.11577Z","caller":"traceutil/trace.go:171","msg":"trace[1863248468] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"189.391947ms","start":"2026-09-22T03:39:15.926344Z","end":"2026-09-22T03:39:16.115735Z","steps":["trace[1863248468] 'process raft request' (duration: 188.747858ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:39:16.347232Z","caller":"traceutil/trace.go:171","msg":"trace[614306420] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"170.05531ms","start":"2026-09-22T03:39:16.177145Z","end":"2026-09-22T03:39:16.3472Z","steps":["trace[614306420] 'process raft request' (duration: 151.375422ms)","trace[614306420] 'compare' (duration: 18.526903ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:39:16.382271Z","caller":"traceutil/trace.go:171","msg":"trace[1841006465] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"197.819683ms","start":"2026-09-22T03:39:16.184429Z","end":"2026-09-22T03:39:16.382249Z","steps":["trace[1841006465] 'process raft request' (duration: 197.773922ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:39:16.382278Z","caller":"traceutil/trace.go:171","msg":"trace[864422672] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"203.011878ms","start":"2026-09-22T03:39:16.179238Z","end":"2026-09-22T03:39:16.38225Z","steps":["trace[864422672] 'process raft request' (duration: 202.783501ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:39:16.382319Z","caller":"traceutil/trace.go:171","msg":"trace[9425911] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"203.017049ms","start":"2026-09-22T03:39:16.179284Z","end":"2026-09-22T03:39:16.382302Z","steps":["trace[9425911] 'process raft request' (duration: 202.883775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:39:16.78195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.570765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:39:16.7821Z","caller":"traceutil/trace.go:171","msg":"trace[313341456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"197.750721ms","start":"2026-09-22T03:39:16.584304Z","end":"2026-09-22T03:39:16.782055Z","steps":["trace[313341456] 'range keys from in-memory index tree' (duration: 197.38891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:39:16.782451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.133743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nqbsz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-22T03:39:16.782657Z","caller":"traceutil/trace.go:171","msg":"trace[662219126] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nqbsz; range_end:; response_count:1; response_revision:638; }","duration":"333.399271ms","start":"2026-09-22T03:39:16.449223Z","end":"2026-09-22T03:39:16.782622Z","steps":["trace[662219126] 'range keys from in-memory index tree' (duration: 332.734362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:39:16.782864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:39:16.449208Z","time spent":"333.637699ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-nqbsz\" "} {"level":"info","ts":"2026-09-22T03:39:16.911789Z","caller":"traceutil/trace.go:171","msg":"trace[1290954157] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"110.644284ms","start":"2026-09-22T03:39:16.801116Z","end":"2026-09-22T03:39:16.91176Z","steps":["trace[1290954157] 'process raft request' (duration: 110.097227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:39:32.797518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.079024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tsmzf\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-22T03:39:32.797601Z","caller":"traceutil/trace.go:171","msg":"trace[384474100] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tsmzf; range_end:; response_count:1; response_revision:805; }","duration":"251.202208ms","start":"2026-09-22T03:39:32.546378Z","end":"2026-09-22T03:39:32.797581Z","steps":["trace[384474100] 'range keys from in-memory index tree' (duration: 250.933571ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:26.224626Z","caller":"traceutil/trace.go:171","msg":"trace[378692296] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"140.045084ms","start":"2026-09-22T03:40:26.084556Z","end":"2026-09-22T03:40:26.224601Z","steps":["trace[378692296] 'process raft request' (duration: 139.903352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:50.96356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.274824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:40:50.964092Z","caller":"traceutil/trace.go:171","msg":"trace[798998998] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"323.368602ms","start":"2026-09-22T03:40:50.640628Z","end":"2026-09-22T03:40:50.963997Z","steps":["trace[798998998] 'process raft request' (duration: 60.041572ms)","trace[798998998] 'compare' (duration: 261.699977ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:40:50.964443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:40:50.640603Z","time spent":"323.602725ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:40:51.043264Z","caller":"traceutil/trace.go:171","msg":"trace[278487909] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1949; }","duration":"276.45965ms","start":"2026-09-22T03:40:50.766774Z","end":"2026-09-22T03:40:51.043233Z","steps":["trace[278487909] 'read index received' (duration: 188.265108ms)","trace[278487909] 'applied index is now lower than readState.Index' (duration: 88.192852ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:40:51.043574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.785516ms","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-09-22T03:40:51.043665Z","caller":"traceutil/trace.go:171","msg":"trace[1425837894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1882; }","duration":"276.890247ms","start":"2026-09-22T03:40:50.766757Z","end":"2026-09-22T03:40:51.043647Z","steps":["trace[1425837894] 'agreement among raft nodes before linearized reading' (duration: 276.572543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:51.043718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.042618ms","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-09-22T03:40:51.043779Z","caller":"traceutil/trace.go:171","msg":"trace[487002430] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1882; }","duration":"105.13125ms","start":"2026-09-22T03:40:50.938626Z","end":"2026-09-22T03:40:51.043757Z","steps":["trace[487002430] 'agreement among raft nodes before linearized reading' (duration: 105.013328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:51.043604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.437672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e852b129-ea5c-4214-aafa-158f55d47a6d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-22T03:40:51.04389Z","caller":"traceutil/trace.go:171","msg":"trace[107191899] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e852b129-ea5c-4214-aafa-158f55d47a6d; range_end:; response_count:1; response_revision:1882; }","duration":"127.751086ms","start":"2026-09-22T03:40:50.91611Z","end":"2026-09-22T03:40:51.043861Z","steps":["trace[107191899] 'agreement among raft nodes before linearized reading' (duration: 127.368981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:51.043942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.587391ms","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-09-22T03:40:51.043985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.573126ms","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-09-22T03:40:51.044069Z","caller":"traceutil/trace.go:171","msg":"trace[529425988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1882; }","duration":"185.697139ms","start":"2026-09-22T03:40:50.858352Z","end":"2026-09-22T03:40:51.044049Z","steps":["trace[529425988] 'agreement among raft nodes before linearized reading' (duration: 185.513696ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:51.044008Z","caller":"traceutil/trace.go:171","msg":"trace[701450213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1882; }","duration":"161.748624ms","start":"2026-09-22T03:40:50.882242Z","end":"2026-09-22T03:40:51.043991Z","steps":["trace[701450213] 'agreement among raft nodes before linearized reading' (duration: 161.516961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:51.043987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.003097ms","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-09-22T03:40:51.044324Z","caller":"traceutil/trace.go:171","msg":"trace[410073274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1882; }","duration":"202.468963ms","start":"2026-09-22T03:40:50.841838Z","end":"2026-09-22T03:40:51.044307Z","steps":["trace[410073274] 'agreement among raft nodes before linearized reading' (duration: 201.584191ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:51.150493Z","caller":"traceutil/trace.go:171","msg":"trace[678154797] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"101.88266ms","start":"2026-09-22T03:40:51.048585Z","end":"2026-09-22T03:40:51.150468Z","steps":["trace[678154797] 'process raft request' (duration: 101.719428ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:51.313256Z","caller":"traceutil/trace.go:171","msg":"trace[178047648] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"264.607957ms","start":"2026-09-22T03:40:51.048628Z","end":"2026-09-22T03:40:51.313236Z","steps":["trace[178047648] 'process raft request' (duration: 210.660246ms)","trace[178047648] 'compare' (duration: 53.594927ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:40:51.313287Z","caller":"traceutil/trace.go:171","msg":"trace[1692410705] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"261.395273ms","start":"2026-09-22T03:40:51.051875Z","end":"2026-09-22T03:40:51.31327Z","steps":["trace[1692410705] 'process raft request' (duration: 261.341962ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:51.313374Z","caller":"traceutil/trace.go:171","msg":"trace[1221906116] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"263.926338ms","start":"2026-09-22T03:40:51.049423Z","end":"2026-09-22T03:40:51.313349Z","steps":["trace[1221906116] 'process raft request' (duration: 263.736135ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:51.313473Z","caller":"traceutil/trace.go:171","msg":"trace[1719685740] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"264.81242ms","start":"2026-09-22T03:40:51.048646Z","end":"2026-09-22T03:40:51.313458Z","steps":["trace[1719685740] 'process raft request' (duration: 264.447415ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:41:03.521197Z","caller":"traceutil/trace.go:171","msg":"trace[742367716] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"134.148603ms","start":"2026-09-22T03:41:03.387013Z","end":"2026-09-22T03:41:03.521162Z","steps":["trace[742367716] 'process raft request' (duration: 104.362423ms)","trace[742367716] 'compare' (duration: 29.548667ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:41:03.521671Z","caller":"traceutil/trace.go:171","msg":"trace[1532618513] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"134.601969ms","start":"2026-09-22T03:41:03.387031Z","end":"2026-09-22T03:41:03.521633Z","steps":["trace[1532618513] 'process raft request' (duration: 134.063943ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:41:06.395243Z","caller":"traceutil/trace.go:171","msg":"trace[722051532] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"128.135342ms","start":"2026-09-22T03:41:06.267071Z","end":"2026-09-22T03:41:06.395206Z","steps":["trace[722051532] 'process raft request' (duration: 127.853168ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:41:30.952926Z","caller":"traceutil/trace.go:171","msg":"trace[1982046118] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"309.555639ms","start":"2026-09-22T03:41:30.643349Z","end":"2026-09-22T03:41:30.952905Z","steps":["trace[1982046118] 'process raft request' (duration: 309.350635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:41:30.953121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:41:30.643321Z","time spent":"309.73832ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:41:31.072771Z","caller":"traceutil/trace.go:171","msg":"trace[466529973] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"309.043809ms","start":"2026-09-22T03:41:30.763689Z","end":"2026-09-22T03:41:31.072733Z","steps":["trace[466529973] 'process raft request' (duration: 284.42683ms)","trace[466529973] 'compare' (duration: 24.323016ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:41:31.073099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:41:30.763659Z","time spent":"309.366342ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:41:31.218669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.98743ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-22T03:41:31.218749Z","caller":"traceutil/trace.go:171","msg":"trace[1262612138] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2228; }","duration":"123.663527ms","start":"2026-09-22T03:41:31.095073Z","end":"2026-09-22T03:41:31.218736Z","steps":["trace[1262612138] 'read index received' (duration: 57.381µs)","trace[1262612138] 'applied index is now lower than readState.Index' (duration: 123.605206ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:41:31.218841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.759518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:41:31.218861Z","caller":"traceutil/trace.go:171","msg":"trace[2078373666] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2151; }","duration":"123.841938ms","start":"2026-09-22T03:41:31.095013Z","end":"2026-09-22T03:41:31.218855Z","steps":["trace[2078373666] 'agreement among raft nodes before linearized reading' (duration: 123.784927ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:41:31.368648Z","caller":"traceutil/trace.go:171","msg":"trace[1242311412] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"143.192457ms","start":"2026-09-22T03:41:31.225434Z","end":"2026-09-22T03:41:31.368626Z","steps":["trace[1242311412] 'process raft request' (duration: 142.950514ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:42:30.069933Z","caller":"traceutil/trace.go:171","msg":"trace[1788318641] linearizableReadLoop","detail":"{readStateIndex:2664; appliedIndex:2663; }","duration":"150.524661ms","start":"2026-09-22T03:42:29.91938Z","end":"2026-09-22T03:42:30.069905Z","steps":["trace[1788318641] 'read index received' (duration: 150.239528ms)","trace[1788318641] 'applied index is now lower than readState.Index' (duration: 284.033µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:42:30.070491Z","caller":"traceutil/trace.go:171","msg":"trace[346310311] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"182.919054ms","start":"2026-09-22T03:42:29.887528Z","end":"2026-09-22T03:42:30.070447Z","steps":["trace[346310311] 'process raft request' (duration: 182.215257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:42:30.070502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.414038ms","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-09-22T03:42:30.070507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.497574ms","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-09-22T03:42:30.070581Z","caller":"traceutil/trace.go:171","msg":"trace[2011604913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2573; }","duration":"146.539569ms","start":"2026-09-22T03:42:29.924028Z","end":"2026-09-22T03:42:30.070567Z","steps":["trace[2011604913] 'agreement among raft nodes before linearized reading' (duration: 146.320787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:42:30.070579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.144327ms","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-09-22T03:42:30.070602Z","caller":"traceutil/trace.go:171","msg":"trace[930670965] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2573; }","duration":"132.657335ms","start":"2026-09-22T03:42:29.937925Z","end":"2026-09-22T03:42:30.070582Z","steps":["trace[930670965] 'agreement among raft nodes before linearized reading' (duration: 132.481954ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:42:30.070637Z","caller":"traceutil/trace.go:171","msg":"trace[1243526269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2573; }","duration":"151.269048ms","start":"2026-09-22T03:42:29.91935Z","end":"2026-09-22T03:42:30.070619Z","steps":["trace[1243526269] 'agreement among raft nodes before linearized reading' (duration: 151.046546ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:43:04.494473Z","caller":"traceutil/trace.go:171","msg":"trace[2025649207] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"137.4709ms","start":"2026-09-22T03:43:04.356975Z","end":"2026-09-22T03:43:04.494446Z","steps":["trace[2025649207] 'process raft request' (duration: 137.315488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:43:04.755916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.299134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:43:04.756008Z","caller":"traceutil/trace.go:171","msg":"trace[2017219547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2764; }","duration":"171.441905ms","start":"2026-09-22T03:43:04.584543Z","end":"2026-09-22T03:43:04.755985Z","steps":["trace[2017219547] 'range keys from in-memory index tree' (duration: 171.213053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:28.786166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.173288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:28.786741Z","caller":"traceutil/trace.go:171","msg":"trace[1041343692] transaction","detail":"{read_only:false; response_revision:3595; number_of_response:1; }","duration":"205.944623ms","start":"2026-09-22T03:45:28.580749Z","end":"2026-09-22T03:45:28.786693Z","steps":["trace[1041343692] 'process raft request' (duration: 97.160764ms)","trace[1041343692] 'compare' (duration: 108.032676ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:29.376278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.027707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:29.376466Z","caller":"traceutil/trace.go:171","msg":"trace[1809771786] linearizableReadLoop","detail":"{readStateIndex:3733; appliedIndex:3731; }","duration":"314.357158ms","start":"2026-09-22T03:45:29.06209Z","end":"2026-09-22T03:45:29.376447Z","steps":["trace[1809771786] 'read index received' (duration: 197.960964ms)","trace[1809771786] 'applied index is now lower than readState.Index' (duration: 116.395084ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:29.376577Z","caller":"traceutil/trace.go:171","msg":"trace[1935827877] transaction","detail":"{read_only:false; response_revision:3600; number_of_response:1; }","duration":"359.491094ms","start":"2026-09-22T03:45:29.017073Z","end":"2026-09-22T03:45:29.376563Z","steps":["trace[1935827877] 'process raft request' (duration: 359.301899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:29.376692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:29.01706Z","time spent":"359.582845ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:45:29.376852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.100564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:29.376899Z","caller":"traceutil/trace.go:171","msg":"trace[1205572653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3600; }","duration":"247.185685ms","start":"2026-09-22T03:45:29.1297Z","end":"2026-09-22T03:45:29.376885Z","steps":["trace[1205572653] 'agreement among raft nodes before linearized reading' (duration: 247.111854ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:29.377085Z","caller":"traceutil/trace.go:171","msg":"trace[819574221] transaction","detail":"{read_only:false; response_revision:3599; number_of_response:1; }","duration":"361.912918ms","start":"2026-09-22T03:45:29.014804Z","end":"2026-09-22T03:45:29.376717Z","steps":["trace[819574221] 'process raft request' (duration: 245.369561ms)","trace[819574221] 'compare' (duration: 115.890145ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:29.377286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:29.014789Z","time spent":"362.385048ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:45:29.376986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.804696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:29.378022Z","caller":"traceutil/trace.go:171","msg":"trace[1093132445] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:3600; }","duration":"315.994629ms","start":"2026-09-22T03:45:29.062004Z","end":"2026-09-22T03:45:29.377999Z","steps":["trace[1093132445] 'agreement among raft nodes before linearized reading' (duration: 314.811837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:29.378425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:29.06199Z","time spent":"316.415826ms","remote":"127.0.0.1:52962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-09-22T03:45:29.585699Z","caller":"traceutil/trace.go:171","msg":"trace[1264440987] transaction","detail":"{read_only:false; response_revision:3602; number_of_response:1; }","duration":"128.790753ms","start":"2026-09-22T03:45:29.456847Z","end":"2026-09-22T03:45:29.585637Z","steps":["trace[1264440987] 'process raft request' (duration: 57.424113ms)","trace[1264440987] 'compare' (duration: 71.049755ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:29.867208Z","caller":"traceutil/trace.go:171","msg":"trace[2040984931] transaction","detail":"{read_only:false; response_revision:3603; number_of_response:1; }","duration":"191.288731ms","start":"2026-09-22T03:45:29.675876Z","end":"2026-09-22T03:45:29.867165Z","steps":["trace[2040984931] 'process raft request' (duration: 131.363912ms)","trace[2040984931] 'compare' (duration: 59.248176ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:29.940086Z","caller":"traceutil/trace.go:171","msg":"trace[540946862] transaction","detail":"{read_only:false; response_revision:3604; number_of_response:1; }","duration":"114.947067ms","start":"2026-09-22T03:45:29.825113Z","end":"2026-09-22T03:45:29.94006Z","steps":["trace[540946862] 'process raft request' (duration: 114.825455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:31.647859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.495943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:31.648162Z","caller":"traceutil/trace.go:171","msg":"trace[190721517] transaction","detail":"{read_only:false; response_revision:3612; number_of_response:1; }","duration":"264.259582ms","start":"2026-09-22T03:45:31.383882Z","end":"2026-09-22T03:45:31.648142Z","steps":["trace[190721517] 'process raft request' (duration: 109.378093ms)","trace[190721517] 'compare' (duration: 154.126536ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:32.110919Z","caller":"traceutil/trace.go:171","msg":"trace[1003247711] transaction","detail":"{read_only:false; response_revision:3613; number_of_response:1; }","duration":"165.0399ms","start":"2026-09-22T03:45:31.945837Z","end":"2026-09-22T03:45:32.110877Z","steps":["trace[1003247711] 'process raft request' (duration: 164.831587ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:32.131858Z","caller":"traceutil/trace.go:171","msg":"trace[364261552] linearizableReadLoop","detail":"{readStateIndex:3748; appliedIndex:3746; }","duration":"141.674879ms","start":"2026-09-22T03:45:31.990168Z","end":"2026-09-22T03:45:32.131843Z","steps":["trace[364261552] 'read index received' (duration: 120.430289ms)","trace[364261552] 'applied index is now lower than readState.Index' (duration: 21.24399ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:32.13204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.855093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T03:45:32.132076Z","caller":"traceutil/trace.go:171","msg":"trace[951219154] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3614; }","duration":"141.945324ms","start":"2026-09-22T03:45:31.990124Z","end":"2026-09-22T03:45:32.13207Z","steps":["trace[951219154] 'agreement among raft nodes before linearized reading' (duration: 141.802141ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:32.13215Z","caller":"traceutil/trace.go:171","msg":"trace[1021372172] transaction","detail":"{read_only:false; response_revision:3614; number_of_response:1; }","duration":"185.460646ms","start":"2026-09-22T03:45:31.946474Z","end":"2026-09-22T03:45:32.131934Z","steps":["trace[1021372172] 'process raft request' (duration: 185.280772ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:32.834491Z","caller":"traceutil/trace.go:171","msg":"trace[92921139] transaction","detail":"{read_only:false; response_revision:3615; number_of_response:1; }","duration":"141.082159ms","start":"2026-09-22T03:45:32.693382Z","end":"2026-09-22T03:45:32.834465Z","steps":["trace[92921139] 'process raft request' (duration: 140.874915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:33.372902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.960783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:33.37304Z","caller":"traceutil/trace.go:171","msg":"trace[12571966] linearizableReadLoop","detail":"{readStateIndex:3751; appliedIndex:3750; }","duration":"314.087497ms","start":"2026-09-22T03:45:33.058931Z","end":"2026-09-22T03:45:33.373019Z","steps":["trace[12571966] 'read index received' (duration: 39.850349ms)","trace[12571966] 'applied index is now lower than readState.Index' (duration: 274.235407ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:33.373066Z","caller":"traceutil/trace.go:171","msg":"trace[2115960743] transaction","detail":"{read_only:false; response_revision:3617; number_of_response:1; }","duration":"319.839302ms","start":"2026-09-22T03:45:33.053197Z","end":"2026-09-22T03:45:33.373036Z","steps":["trace[2115960743] 'process raft request' (duration: 45.587804ms)","trace[2115960743] 'compare' (duration: 273.565146ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:33.373196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:33.053171Z","time spent":"319.952693ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:45:33.37327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.329851ms","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-09-22T03:45:33.373325Z","caller":"traceutil/trace.go:171","msg":"trace[662801551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3617; }","duration":"314.386242ms","start":"2026-09-22T03:45:33.058925Z","end":"2026-09-22T03:45:33.373311Z","steps":["trace[662801551] 'agreement among raft nodes before linearized reading' (duration: 314.200148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:33.373373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:33.058882Z","time spent":"314.480004ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T03:45:33.373561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.225253ms","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-09-22T03:45:33.373629Z","caller":"traceutil/trace.go:171","msg":"trace[305778542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3617; }","duration":"288.304965ms","start":"2026-09-22T03:45:33.085288Z","end":"2026-09-22T03:45:33.373593Z","steps":["trace[305778542] 'agreement among raft nodes before linearized reading' (duration: 288.181873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:33.707117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.064742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:33.707241Z","caller":"traceutil/trace.go:171","msg":"trace[1924476633] transaction","detail":"{read_only:false; response_revision:3618; number_of_response:1; }","duration":"330.079409ms","start":"2026-09-22T03:45:33.377138Z","end":"2026-09-22T03:45:33.707218Z","steps":["trace[1924476633] 'process raft request' (duration: 107.796452ms)","trace[1924476633] 'compare' (duration: 221.94769ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:33.707317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:33.377114Z","time spent":"330.16297ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:45:33.72245Z","caller":"traceutil/trace.go:171","msg":"trace[2106053400] linearizableReadLoop","detail":"{readStateIndex:3754; appliedIndex:3752; }","duration":"138.781539ms","start":"2026-09-22T03:45:33.58364Z","end":"2026-09-22T03:45:33.722422Z","steps":["trace[2106053400] 'read index received' (duration: 138.528665ms)","trace[2106053400] 'applied index is now lower than readState.Index' (duration: 251.714µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:33.722473Z","caller":"traceutil/trace.go:171","msg":"trace[154144393] transaction","detail":"{read_only:false; response_revision:3620; number_of_response:1; }","duration":"313.598558ms","start":"2026-09-22T03:45:33.408853Z","end":"2026-09-22T03:45:33.722451Z","steps":["trace[154144393] 'process raft request' (duration: 313.489956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:33.722636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.950443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:33.722822Z","caller":"traceutil/trace.go:171","msg":"trace[991273067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3620; }","duration":"139.231548ms","start":"2026-09-22T03:45:33.583571Z","end":"2026-09-22T03:45:33.722803Z","steps":["trace[991273067] 'agreement among raft nodes before linearized reading' (duration: 138.950143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:33.722728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:33.408837Z","time spent":"313.72572ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:45:33.723039Z","caller":"traceutil/trace.go:171","msg":"trace[29295572] transaction","detail":"{read_only:false; response_revision:3619; number_of_response:1; }","duration":"343.521055ms","start":"2026-09-22T03:45:33.379501Z","end":"2026-09-22T03:45:33.723022Z","steps":["trace[29295572] 'process raft request' (duration: 342.69079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:33.723169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:33.379479Z","time spent":"343.648267ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:45:33.794243Z","caller":"traceutil/trace.go:171","msg":"trace[1107768183] transaction","detail":"{read_only:false; response_revision:3621; number_of_response:1; }","duration":"140.751416ms","start":"2026-09-22T03:45:33.653469Z","end":"2026-09-22T03:45:33.794221Z","steps":["trace[1107768183] 'process raft request' (duration: 140.415849ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:34.020362Z","caller":"traceutil/trace.go:171","msg":"trace[1712608592] transaction","detail":"{read_only:false; response_revision:3622; number_of_response:1; }","duration":"115.631214ms","start":"2026-09-22T03:45:33.90467Z","end":"2026-09-22T03:45:34.020301Z","steps":["trace[1712608592] 'process raft request' (duration: 67.899182ms)","trace[1712608592] 'compare' (duration: 47.448237ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:34.426816Z","caller":"traceutil/trace.go:171","msg":"trace[1654741618] transaction","detail":"{read_only:false; response_revision:3623; number_of_response:1; }","duration":"310.77512ms","start":"2026-09-22T03:45:34.116014Z","end":"2026-09-22T03:45:34.426789Z","steps":["trace[1654741618] 'process raft request' (duration: 250.976829ms)","trace[1654741618] 'compare' (duration: 59.228031ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:34.427135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:34.115989Z","time spent":"310.979803ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:45:34.444957Z","caller":"traceutil/trace.go:171","msg":"trace[989411585] transaction","detail":"{read_only:false; response_revision:3624; number_of_response:1; }","duration":"308.58838ms","start":"2026-09-22T03:45:34.136343Z","end":"2026-09-22T03:45:34.444931Z","steps":["trace[989411585] 'process raft request' (duration: 308.402296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:34.445281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:34.136318Z","time spent":"308.707842ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:45:34.963162Z","caller":"traceutil/trace.go:171","msg":"trace[1593771260] linearizableReadLoop","detail":"{readStateIndex:3759; appliedIndex:3758; }","duration":"124.860679ms","start":"2026-09-22T03:45:34.83828Z","end":"2026-09-22T03:45:34.96314Z","steps":["trace[1593771260] 'read index received' (duration: 124.745256ms)","trace[1593771260] 'applied index is now lower than readState.Index' (duration: 114.003µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:34.963452Z","caller":"traceutil/trace.go:171","msg":"trace[1771230761] transaction","detail":"{read_only:false; response_revision:3625; number_of_response:1; }","duration":"154.894005ms","start":"2026-09-22T03:45:34.808463Z","end":"2026-09-22T03:45:34.963357Z","steps":["trace[1771230761] 'process raft request' (duration: 154.540569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:34.963462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.162284ms","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-09-22T03:45:34.963727Z","caller":"traceutil/trace.go:171","msg":"trace[323133708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3625; }","duration":"125.479059ms","start":"2026-09-22T03:45:34.838234Z","end":"2026-09-22T03:45:34.963713Z","steps":["trace[323133708] 'agreement among raft nodes before linearized reading' (duration: 124.98938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:35.221856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.262822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:35.221941Z","caller":"traceutil/trace.go:171","msg":"trace[135421561] linearizableReadLoop","detail":"{readStateIndex:3760; appliedIndex:3759; }","duration":"205.729002ms","start":"2026-09-22T03:45:35.016198Z","end":"2026-09-22T03:45:35.221927Z","steps":["trace[135421561] 'read index received' (duration: 32.248806ms)","trace[135421561] 'applied index is now lower than readState.Index' (duration: 173.478736ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:35.222176Z","caller":"traceutil/trace.go:171","msg":"trace[683703600] transaction","detail":"{read_only:false; response_revision:3626; number_of_response:1; }","duration":"253.90435ms","start":"2026-09-22T03:45:34.968253Z","end":"2026-09-22T03:45:35.222158Z","steps":["trace[683703600] 'process raft request' (duration: 80.264521ms)","trace[683703600] 'compare' (duration: 173.11089ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:35.222083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.880795ms","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-09-22T03:45:35.222858Z","caller":"traceutil/trace.go:171","msg":"trace[1524086534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3626; }","duration":"206.537407ms","start":"2026-09-22T03:45:35.016164Z","end":"2026-09-22T03:45:35.222701Z","steps":["trace[1524086534] 'agreement among raft nodes before linearized reading' (duration: 205.799904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:35.820062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.099631ms","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-09-22T03:45:35.820114Z","caller":"traceutil/trace.go:171","msg":"trace[619104499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3627; }","duration":"443.199854ms","start":"2026-09-22T03:45:35.376903Z","end":"2026-09-22T03:45:35.820103Z","steps":["trace[619104499] 'range keys from in-memory index tree' (duration: 442.950308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:35.820138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:35.37688Z","time spent":"443.251244ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T03:45:35.820142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.902818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-22T03:45:35.820179Z","caller":"traceutil/trace.go:171","msg":"trace[1553166485] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:3627; }","duration":"281.96756ms","start":"2026-09-22T03:45:35.538197Z","end":"2026-09-22T03:45:35.820165Z","steps":["trace[1553166485] 'range keys from in-memory index tree' (duration: 281.738526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:35.820119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.313288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T03:45:35.820062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.24782ms","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-09-22T03:45:35.82026Z","caller":"traceutil/trace.go:171","msg":"trace[99926220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3627; }","duration":"108.494274ms","start":"2026-09-22T03:45:35.711761Z","end":"2026-09-22T03:45:35.820255Z","steps":["trace[99926220] 'range keys from in-memory index tree' (duration: 108.129898ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:35.820266Z","caller":"traceutil/trace.go:171","msg":"trace[718369936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3627; }","duration":"237.508211ms","start":"2026-09-22T03:45:35.582741Z","end":"2026-09-22T03:45:35.820249Z","steps":["trace[718369936] 'range keys from in-memory index tree' (duration: 237.198175ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:35.915737Z","caller":"traceutil/trace.go:171","msg":"trace[942779064] linearizableReadLoop","detail":"{readStateIndex:3762; appliedIndex:3761; }","duration":"187.371409ms","start":"2026-09-22T03:45:35.728345Z","end":"2026-09-22T03:45:35.915717Z","steps":["trace[942779064] 'read index received' (duration: 187.187746ms)","trace[942779064] 'applied index is now lower than readState.Index' (duration: 181.073µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:35.9159Z","caller":"traceutil/trace.go:171","msg":"trace[630256005] transaction","detail":"{read_only:false; response_revision:3628; number_of_response:1; }","duration":"187.812737ms","start":"2026-09-22T03:45:35.728049Z","end":"2026-09-22T03:45:35.915862Z","steps":["trace[630256005] 'process raft request' (duration: 187.510781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:35.916637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.277005ms","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-09-22T03:45:35.916664Z","caller":"traceutil/trace.go:171","msg":"trace[265257906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3628; }","duration":"188.343816ms","start":"2026-09-22T03:45:35.728313Z","end":"2026-09-22T03:45:35.916657Z","steps":["trace[265257906] 'agreement among raft nodes before linearized reading' (duration: 187.48374ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:35.994572Z","caller":"traceutil/trace.go:171","msg":"trace[1906047291] transaction","detail":"{read_only:false; response_revision:3629; number_of_response:1; }","duration":"195.434095ms","start":"2026-09-22T03:45:35.799073Z","end":"2026-09-22T03:45:35.994507Z","steps":["trace[1906047291] 'process raft request' (duration: 149.431179ms)","trace[1906047291] 'compare' (duration: 45.839843ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:35.994941Z","caller":"traceutil/trace.go:171","msg":"trace[671196119] transaction","detail":"{read_only:false; response_revision:3630; number_of_response:1; }","duration":"170.255288ms","start":"2026-09-22T03:45:35.82467Z","end":"2026-09-22T03:45:35.994925Z","steps":["trace[671196119] 'process raft request' (duration: 170.152296ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:35.995022Z","caller":"traceutil/trace.go:171","msg":"trace[250542234] transaction","detail":"{read_only:false; response_revision:3631; number_of_response:1; }","duration":"170.329799ms","start":"2026-09-22T03:45:35.824677Z","end":"2026-09-22T03:45:35.995007Z","steps":["trace[250542234] 'process raft request' (duration: 170.217287ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:36.571429Z","caller":"traceutil/trace.go:171","msg":"trace[1520273426] transaction","detail":"{read_only:false; response_revision:3634; number_of_response:1; }","duration":"139.564271ms","start":"2026-09-22T03:45:36.431841Z","end":"2026-09-22T03:45:36.571406Z","steps":["trace[1520273426] 'process raft request' (duration: 139.281666ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:36.576289Z","caller":"traceutil/trace.go:171","msg":"trace[1177091875] transaction","detail":"{read_only:false; response_revision:3635; number_of_response:1; }","duration":"126.973634ms","start":"2026-09-22T03:45:36.449277Z","end":"2026-09-22T03:45:36.576251Z","steps":["trace[1177091875] 'process raft request' (duration: 126.634798ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:37.710865Z","caller":"traceutil/trace.go:171","msg":"trace[237095465] transaction","detail":"{read_only:false; response_revision:3636; number_of_response:1; }","duration":"480.140056ms","start":"2026-09-22T03:45:37.230696Z","end":"2026-09-22T03:45:37.710836Z","steps":["trace[237095465] 'process raft request' (duration: 479.945543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:37.711055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.057371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T03:45:37.711102Z","caller":"traceutil/trace.go:171","msg":"trace[1480002165] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3636; }","duration":"440.143933ms","start":"2026-09-22T03:45:37.270947Z","end":"2026-09-22T03:45:37.711091Z","steps":["trace[1480002165] 'agreement among raft nodes before linearized reading' (duration: 439.97933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:37.711133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:37.270931Z","time spent":"440.194113ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-22T03:45:37.71123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:37.230677Z","time spent":"480.264298ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:45:37.710812Z","caller":"traceutil/trace.go:171","msg":"trace[2108746272] linearizableReadLoop","detail":"{readStateIndex:3771; appliedIndex:3770; }","duration":"439.800476ms","start":"2026-09-22T03:45:37.270985Z","end":"2026-09-22T03:45:37.710786Z","steps":["trace[2108746272] 'read index received' (duration: 439.687384ms)","trace[2108746272] 'applied index is now lower than readState.Index' (duration: 111.902µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:37.995472Z","caller":"traceutil/trace.go:171","msg":"trace[1950844867] linearizableReadLoop","detail":"{readStateIndex:3772; appliedIndex:3771; }","duration":"283.957446ms","start":"2026-09-22T03:45:37.711493Z","end":"2026-09-22T03:45:37.995451Z","steps":["trace[1950844867] 'read index received' (duration: 217.668318ms)","trace[1950844867] 'applied index is now lower than readState.Index' (duration: 66.288298ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:37.995518Z","caller":"traceutil/trace.go:171","msg":"trace[729969805] transaction","detail":"{read_only:false; response_revision:3637; number_of_response:1; }","duration":"624.034379ms","start":"2026-09-22T03:45:37.371457Z","end":"2026-09-22T03:45:37.995491Z","steps":["trace[729969805] 'process raft request' (duration: 557.69365ms)","trace[729969805] 'compare' (duration: 66.095245ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:37.995631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.03022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T03:45:37.995671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:37.371433Z","time spent":"624.134812ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:45:37.995689Z","caller":"traceutil/trace.go:171","msg":"trace[1875279483] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:3637; }","duration":"576.150122ms","start":"2026-09-22T03:45:37.419529Z","end":"2026-09-22T03:45:37.995679Z","steps":["trace[1875279483] 'agreement among raft nodes before linearized reading' (duration: 576.008199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:37.995876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.462307ms","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-09-22T03:45:37.995899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:37.419511Z","time spent":"576.314225ms","remote":"127.0.0.1:53144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-09-22T03:45:37.995947Z","caller":"traceutil/trace.go:171","msg":"trace[1997170181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3637; }","duration":"692.571099ms","start":"2026-09-22T03:45:37.303358Z","end":"2026-09-22T03:45:37.995929Z","steps":["trace[1997170181] 'agreement among raft nodes before linearized reading' (duration: 692.360015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:37.995988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:37.303337Z","time spent":"692.64227ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-22T03:45:38.022957Z","caller":"traceutil/trace.go:171","msg":"trace[1142710342] transaction","detail":"{read_only:false; response_revision:3638; number_of_response:1; }","duration":"101.124438ms","start":"2026-09-22T03:45:37.921809Z","end":"2026-09-22T03:45:38.022934Z","steps":["trace[1142710342] 'process raft request' (duration: 100.928165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:38.023078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.618594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T03:45:38.023141Z","caller":"traceutil/trace.go:171","msg":"trace[1447356595] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3638; }","duration":"307.727436ms","start":"2026-09-22T03:45:37.715397Z","end":"2026-09-22T03:45:38.023125Z","steps":["trace[1447356595] 'agreement among raft nodes before linearized reading' (duration: 307.541873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:38.023193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:37.715361Z","time spent":"307.820488ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-22T03:45:38.315549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.455278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:38.31585Z","caller":"traceutil/trace.go:171","msg":"trace[219568516] linearizableReadLoop","detail":"{readStateIndex:3778; appliedIndex:3775; }","duration":"188.164205ms","start":"2026-09-22T03:45:38.127647Z","end":"2026-09-22T03:45:38.315811Z","steps":["trace[219568516] 'read index received' (duration: 149.286944ms)","trace[219568516] 'applied index is now lower than readState.Index' (duration: 38.876281ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:38.315967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.317678ms","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-09-22T03:45:38.316004Z","caller":"traceutil/trace.go:171","msg":"trace[1455860547] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3643; }","duration":"188.408099ms","start":"2026-09-22T03:45:38.12759Z","end":"2026-09-22T03:45:38.315998Z","steps":["trace[1455860547] 'agreement among raft nodes before linearized reading' (duration: 188.295777ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:38.316061Z","caller":"traceutil/trace.go:171","msg":"trace[747713436] transaction","detail":"{read_only:false; response_revision:3642; number_of_response:1; }","duration":"247.17297ms","start":"2026-09-22T03:45:38.068813Z","end":"2026-09-22T03:45:38.315986Z","steps":["trace[747713436] 'process raft request' (duration: 246.896045ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:38.316268Z","caller":"traceutil/trace.go:171","msg":"trace[2015326773] transaction","detail":"{read_only:false; response_revision:3643; number_of_response:1; }","duration":"247.023546ms","start":"2026-09-22T03:45:38.069233Z","end":"2026-09-22T03:45:38.316257Z","steps":["trace[2015326773] 'process raft request' (duration: 246.549968ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:38.315984Z","caller":"traceutil/trace.go:171","msg":"trace[1639732408] transaction","detail":"{read_only:false; response_revision:3641; number_of_response:1; }","duration":"247.258272ms","start":"2026-09-22T03:45:38.068575Z","end":"2026-09-22T03:45:38.315833Z","steps":["trace[1639732408] 'process raft request' (duration: 32.456736ms)","trace[1639732408] 'compare' (duration: 214.356977ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:39.095046Z","caller":"traceutil/trace.go:171","msg":"trace[345671975] linearizableReadLoop","detail":"{readStateIndex:3780; appliedIndex:3778; }","duration":"509.468956ms","start":"2026-09-22T03:45:38.585548Z","end":"2026-09-22T03:45:39.095017Z","steps":["trace[345671975] 'read index received' (duration: 35.404819ms)","trace[345671975] 'applied index is now lower than readState.Index' (duration: 474.062677ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:39.095288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.729821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:39.095362Z","caller":"traceutil/trace.go:171","msg":"trace[456449003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3645; }","duration":"509.838843ms","start":"2026-09-22T03:45:38.585506Z","end":"2026-09-22T03:45:39.095345Z","steps":["trace[456449003] 'agreement among raft nodes before linearized reading' (duration: 509.723931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:39.095396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:38.585494Z","time spent":"509.894984ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T03:45:39.095165Z","caller":"traceutil/trace.go:171","msg":"trace[2053921453] transaction","detail":"{read_only:false; response_revision:3645; number_of_response:1; }","duration":"514.540158ms","start":"2026-09-22T03:45:38.580581Z","end":"2026-09-22T03:45:39.095121Z","steps":["trace[2053921453] 'process raft request' (duration: 483.271836ms)","trace[2053921453] 'compare' (duration: 30.772943ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:39.095508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.305025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/monitoring\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:39.095791Z","caller":"traceutil/trace.go:171","msg":"trace[1888828951] range","detail":"{range_begin:/registry/namespaces/monitoring; range_end:; response_count:0; response_revision:3645; }","duration":"115.61337ms","start":"2026-09-22T03:45:38.98015Z","end":"2026-09-22T03:45:39.095764Z","steps":["trace[1888828951] 'agreement among raft nodes before linearized reading' (duration: 115.150642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:39.095994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:38.580562Z","time spent":"514.992666ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:45:39.338469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.612146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:39.338611Z","caller":"traceutil/trace.go:171","msg":"trace[1199645989] linearizableReadLoop","detail":"{readStateIndex:3781; appliedIndex:3780; }","duration":"103.170766ms","start":"2026-09-22T03:45:39.235407Z","end":"2026-09-22T03:45:39.338577Z","steps":["trace[1199645989] 'read index received' (duration: 53.701µs)","trace[1199645989] 'applied index is now lower than readState.Index' (duration: 103.115725ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:39.338702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.287618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:39.338728Z","caller":"traceutil/trace.go:171","msg":"trace[37859170] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:3646; }","duration":"103.35899ms","start":"2026-09-22T03:45:39.235359Z","end":"2026-09-22T03:45:39.338718Z","steps":["trace[37859170] 'agreement among raft nodes before linearized reading' (duration: 103.278348ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:39.338714Z","caller":"traceutil/trace.go:171","msg":"trace[1610243826] transaction","detail":"{read_only:false; response_revision:3646; number_of_response:1; }","duration":"234.470309ms","start":"2026-09-22T03:45:39.104214Z","end":"2026-09-22T03:45:39.338685Z","steps":["trace[1610243826] 'process raft request' (duration: 91.578178ms)","trace[1610243826] 'compare' (duration: 142.449923ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:39.571357Z","caller":"traceutil/trace.go:171","msg":"trace[1953287840] linearizableReadLoop","detail":"{readStateIndex:3783; appliedIndex:3781; }","duration":"227.38102ms","start":"2026-09-22T03:45:39.343956Z","end":"2026-09-22T03:45:39.571337Z","steps":["trace[1953287840] 'read index received' (duration: 49.656183ms)","trace[1953287840] 'applied index is now lower than readState.Index' (duration: 177.723937ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:39.571443Z","caller":"traceutil/trace.go:171","msg":"trace[1525048302] transaction","detail":"{read_only:false; response_revision:3648; number_of_response:1; }","duration":"228.434ms","start":"2026-09-22T03:45:39.342969Z","end":"2026-09-22T03:45:39.571403Z","steps":["trace[1525048302] 'process raft request' (duration: 228.254396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:39.571523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.557593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-etcd-client-cert\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:39.571558Z","caller":"traceutil/trace.go:171","msg":"trace[1686835533] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-etcd-client-cert; range_end:; response_count:0; response_revision:3648; }","duration":"227.636435ms","start":"2026-09-22T03:45:39.34391Z","end":"2026-09-22T03:45:39.571546Z","steps":["trace[1686835533] 'agreement among raft nodes before linearized reading' (duration: 227.551224ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:39.892303Z","caller":"traceutil/trace.go:171","msg":"trace[381567937] linearizableReadLoop","detail":"{readStateIndex:3785; appliedIndex:3784; }","duration":"176.497275ms","start":"2026-09-22T03:45:39.71578Z","end":"2026-09-22T03:45:39.892277Z","steps":["trace[381567937] 'read index received' (duration: 129.50346ms)","trace[381567937] 'applied index is now lower than readState.Index' (duration: 46.992775ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:39.892501Z","caller":"traceutil/trace.go:171","msg":"trace[1032891792] transaction","detail":"{read_only:false; response_revision:3650; number_of_response:1; }","duration":"315.18935ms","start":"2026-09-22T03:45:39.577287Z","end":"2026-09-22T03:45:39.892477Z","steps":["trace[1032891792] 'process raft request' (duration: 268.051573ms)","trace[1032891792] 'compare' (duration: 46.552927ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:39.892741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.887232ms","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-09-22T03:45:39.892832Z","caller":"traceutil/trace.go:171","msg":"trace[787817024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3650; }","duration":"177.087726ms","start":"2026-09-22T03:45:39.715729Z","end":"2026-09-22T03:45:39.892816Z","steps":["trace[787817024] 'agreement among raft nodes before linearized reading' (duration: 176.634647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:39.893683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:39.577274Z","time spent":"315.455936ms","remote":"127.0.0.1:47484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T03:45:40.238321Z","caller":"traceutil/trace.go:171","msg":"trace[723686500] transaction","detail":"{read_only:false; response_revision:3653; number_of_response:1; }","duration":"167.73775ms","start":"2026-09-22T03:45:40.070561Z","end":"2026-09-22T03:45:40.238299Z","steps":["trace[723686500] 'process raft request' (duration: 167.688149ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:40.238669Z","caller":"traceutil/trace.go:171","msg":"trace[1647428235] linearizableReadLoop","detail":"{readStateIndex:3787; appliedIndex:3786; }","duration":"199.403527ms","start":"2026-09-22T03:45:40.039254Z","end":"2026-09-22T03:45:40.238657Z","steps":["trace[1647428235] 'read index received' (duration: 169.586312ms)","trace[1647428235] 'applied index is now lower than readState.Index' (duration: 29.815875ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:40.238392Z","caller":"traceutil/trace.go:171","msg":"trace[775086200] transaction","detail":"{read_only:false; response_revision:3652; number_of_response:1; }","duration":"210.177281ms","start":"2026-09-22T03:45:40.028186Z","end":"2026-09-22T03:45:40.238363Z","steps":["trace[775086200] 'process raft request' (duration: 180.64011ms)","trace[775086200] 'compare' (duration: 29.298477ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:40.238981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.715943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T03:45:40.239019Z","caller":"traceutil/trace.go:171","msg":"trace[1253616000] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3653; }","duration":"199.771964ms","start":"2026-09-22T03:45:40.039238Z","end":"2026-09-22T03:45:40.23901Z","steps":["trace[1253616000] 'agreement among raft nodes before linearized reading' (duration: 199.499989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:40.238991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.833068ms","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-09-22T03:45:40.239072Z","caller":"traceutil/trace.go:171","msg":"trace[1967349255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3653; }","duration":"168.964561ms","start":"2026-09-22T03:45:40.070086Z","end":"2026-09-22T03:45:40.23905Z","steps":["trace[1967349255] 'agreement among raft nodes before linearized reading' (duration: 168.692666ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:40.495126Z","caller":"traceutil/trace.go:171","msg":"trace[298592982] transaction","detail":"{read_only:false; response_revision:3657; number_of_response:1; }","duration":"170.461308ms","start":"2026-09-22T03:45:40.32464Z","end":"2026-09-22T03:45:40.495102Z","steps":["trace[298592982] 'process raft request' (duration: 170.420197ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:40.495179Z","caller":"traceutil/trace.go:171","msg":"trace[350012020] transaction","detail":"{read_only:false; response_revision:3655; number_of_response:1; }","duration":"172.623457ms","start":"2026-09-22T03:45:40.322523Z","end":"2026-09-22T03:45:40.495147Z","steps":["trace[350012020] 'process raft request' (duration: 108.929035ms)","trace[350012020] 'compare' (duration: 63.435587ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:40.49545Z","caller":"traceutil/trace.go:171","msg":"trace[1883612470] transaction","detail":"{read_only:false; response_revision:3656; number_of_response:1; }","duration":"171.785693ms","start":"2026-09-22T03:45:40.323651Z","end":"2026-09-22T03:45:40.495437Z","steps":["trace[1883612470] 'process raft request' (duration: 171.368535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:41.099582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.310939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-22T03:45:41.099973Z","caller":"traceutil/trace.go:171","msg":"trace[683608610] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3658; }","duration":"297.745376ms","start":"2026-09-22T03:45:40.802201Z","end":"2026-09-22T03:45:41.099946Z","steps":["trace[683608610] 'range keys from in-memory index tree' (duration: 297.086945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:41.099619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.887599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:41.100372Z","caller":"traceutil/trace.go:171","msg":"trace[1454870171] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:0; response_revision:3658; }","duration":"176.684784ms","start":"2026-09-22T03:45:40.92365Z","end":"2026-09-22T03:45:41.100334Z","steps":["trace[1454870171] 'range keys from in-memory index tree' (duration: 175.823368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:41.630918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.404946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:45:41.631038Z","caller":"traceutil/trace.go:171","msg":"trace[1509677426] linearizableReadLoop","detail":"{readStateIndex:3799; appliedIndex:3798; }","duration":"343.328044ms","start":"2026-09-22T03:45:41.287693Z","end":"2026-09-22T03:45:41.631021Z","steps":["trace[1509677426] 'read index received' (duration: 185.625452ms)","trace[1509677426] 'applied index is now lower than readState.Index' (duration: 157.701232ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:41.631192Z","caller":"traceutil/trace.go:171","msg":"trace[1689993690] transaction","detail":"{read_only:false; response_revision:3663; number_of_response:1; }","duration":"344.770598ms","start":"2026-09-22T03:45:41.286402Z","end":"2026-09-22T03:45:41.631173Z","steps":["trace[1689993690] 'process raft request' (duration: 186.977385ms)","trace[1689993690] 'compare' (duration: 157.268704ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:41.631331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:41.286379Z","time spent":"344.882041ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:45:41.631547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.834662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-09-22T03:45:41.631642Z","caller":"traceutil/trace.go:171","msg":"trace[639708721] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3663; }","duration":"343.974064ms","start":"2026-09-22T03:45:41.287649Z","end":"2026-09-22T03:45:41.631623Z","steps":["trace[639708721] 'agreement among raft nodes before linearized reading' (duration: 343.76412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:41.63176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:41.287631Z","time spent":"344.116437ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":1252,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"info","ts":"2026-09-22T03:45:42.109554Z","caller":"traceutil/trace.go:171","msg":"trace[811971040] linearizableReadLoop","detail":"{readStateIndex:3800; appliedIndex:3799; }","duration":"140.656001ms","start":"2026-09-22T03:45:41.968875Z","end":"2026-09-22T03:45:42.109531Z","steps":["trace[811971040] 'read index received' (duration: 140.444807ms)","trace[811971040] 'applied index is now lower than readState.Index' (duration: 210.364µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:42.109742Z","caller":"traceutil/trace.go:171","msg":"trace[1336075508] transaction","detail":"{read_only:false; response_revision:3664; number_of_response:1; }","duration":"165.348403ms","start":"2026-09-22T03:45:41.944237Z","end":"2026-09-22T03:45:42.109586Z","steps":["trace[1336075508] 'process raft request' (duration: 165.150089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:42.109915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.031138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-09-22T03:45:42.110048Z","caller":"traceutil/trace.go:171","msg":"trace[1159269219] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3664; }","duration":"141.129919ms","start":"2026-09-22T03:45:41.968816Z","end":"2026-09-22T03:45:42.109946Z","steps":["trace[1159269219] 'agreement among raft nodes before linearized reading' (duration: 140.819804ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:42.545101Z","caller":"traceutil/trace.go:171","msg":"trace[326427627] linearizableReadLoop","detail":"{readStateIndex:3802; appliedIndex:3801; }","duration":"264.5542ms","start":"2026-09-22T03:45:42.280523Z","end":"2026-09-22T03:45:42.545078Z","steps":["trace[326427627] 'read index received' (duration: 201.76489ms)","trace[326427627] 'applied index is now lower than readState.Index' (duration: 62.78831ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:42.545361Z","caller":"traceutil/trace.go:171","msg":"trace[843526557] transaction","detail":"{read_only:false; response_revision:3666; number_of_response:1; }","duration":"300.763227ms","start":"2026-09-22T03:45:42.244583Z","end":"2026-09-22T03:45:42.545346Z","steps":["trace[843526557] 'process raft request' (duration: 237.684332ms)","trace[843526557] 'compare' (duration: 62.497884ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:42.545458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:42.244552Z","time spent":"300.837777ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:45:42.545954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.622712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"warn","ts":"2026-09-22T03:45:42.545952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.415116ms","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-09-22T03:45:42.545999Z","caller":"traceutil/trace.go:171","msg":"trace[1513977418] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3666; }","duration":"111.714054ms","start":"2026-09-22T03:45:42.434275Z","end":"2026-09-22T03:45:42.545989Z","steps":["trace[1513977418] 'agreement among raft nodes before linearized reading' (duration: 111.260025ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:42.546022Z","caller":"traceutil/trace.go:171","msg":"trace[998734770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3666; }","duration":"265.532378ms","start":"2026-09-22T03:45:42.280471Z","end":"2026-09-22T03:45:42.546003Z","steps":["trace[998734770] 'agreement among raft nodes before linearized reading' (duration: 265.11541ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:42.798772Z","caller":"traceutil/trace.go:171","msg":"trace[1311023239] transaction","detail":"{read_only:false; response_revision:3670; number_of_response:1; }","duration":"248.429422ms","start":"2026-09-22T03:45:42.550312Z","end":"2026-09-22T03:45:42.798741Z","steps":["trace[1311023239] 'process raft request' (duration: 248.364671ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:42.798829Z","caller":"traceutil/trace.go:171","msg":"trace[998132636] transaction","detail":"{read_only:false; response_revision:3668; number_of_response:1; }","duration":"248.730278ms","start":"2026-09-22T03:45:42.550063Z","end":"2026-09-22T03:45:42.798793Z","steps":["trace[998132636] 'process raft request' (duration: 248.125048ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:42.799078Z","caller":"traceutil/trace.go:171","msg":"trace[672946695] transaction","detail":"{read_only:false; response_revision:3669; number_of_response:1; }","duration":"248.655807ms","start":"2026-09-22T03:45:42.550268Z","end":"2026-09-22T03:45:42.798923Z","steps":["trace[672946695] 'process raft request' (duration: 248.3079ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:42.868698Z","caller":"traceutil/trace.go:171","msg":"trace[1152395584] transaction","detail":"{read_only:false; response_revision:3671; number_of_response:1; }","duration":"202.80753ms","start":"2026-09-22T03:45:42.665874Z","end":"2026-09-22T03:45:42.868682Z","steps":["trace[1152395584] 'process raft request' (duration: 202.692098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:43.050956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.665885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:43.051052Z","caller":"traceutil/trace.go:171","msg":"trace[904001852] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:3671; }","duration":"132.788767ms","start":"2026-09-22T03:45:42.918247Z","end":"2026-09-22T03:45:43.051036Z","steps":["trace[904001852] 'count revisions from in-memory index tree' (duration: 132.570983ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:44.208073Z","caller":"traceutil/trace.go:171","msg":"trace[1131138850] transaction","detail":"{read_only:false; response_revision:3673; number_of_response:1; }","duration":"103.914085ms","start":"2026-09-22T03:45:44.104116Z","end":"2026-09-22T03:45:44.20803Z","steps":["trace[1131138850] 'process raft request' (duration: 103.763222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:44.710232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.426248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-09-22T03:45:44.710301Z","caller":"traceutil/trace.go:171","msg":"trace[1989542422] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3675; }","duration":"208.556361ms","start":"2026-09-22T03:45:44.501727Z","end":"2026-09-22T03:45:44.710283Z","steps":["trace[1989542422] 'range keys from in-memory index tree' (duration: 208.218365ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:44.710639Z","caller":"traceutil/trace.go:171","msg":"trace[575261949] linearizableReadLoop","detail":"{readStateIndex:3812; appliedIndex:3811; }","duration":"117.267951ms","start":"2026-09-22T03:45:44.593355Z","end":"2026-09-22T03:45:44.710623Z","steps":["trace[575261949] 'read index received' (duration: 69.742327ms)","trace[575261949] 'applied index is now lower than readState.Index' (duration: 47.524494ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:44.710726Z","caller":"traceutil/trace.go:171","msg":"trace[546212834] transaction","detail":"{read_only:false; response_revision:3676; number_of_response:1; }","duration":"160.221623ms","start":"2026-09-22T03:45:44.550493Z","end":"2026-09-22T03:45:44.710715Z","steps":["trace[546212834] 'process raft request' (duration: 112.605978ms)","trace[546212834] 'compare' (duration: 47.216308ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:44.710791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.437964ms","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-09-22T03:45:44.710825Z","caller":"traceutil/trace.go:171","msg":"trace[1110555612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3676; }","duration":"117.519135ms","start":"2026-09-22T03:45:44.593297Z","end":"2026-09-22T03:45:44.710816Z","steps":["trace[1110555612] 'agreement among raft nodes before linearized reading' (duration: 117.387673ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:41.906427Z","caller":"traceutil/trace.go:171","msg":"trace[1526961875] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"145.445829ms","start":"2026-09-22T03:47:41.760961Z","end":"2026-09-22T03:47:41.906407Z","steps":["trace[1526961875] 'process raft request' (duration: 145.297807ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:42.141569Z","caller":"traceutil/trace.go:171","msg":"trace[793143158] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"142.169341ms","start":"2026-09-22T03:47:41.999342Z","end":"2026-09-22T03:47:42.141511Z","steps":["trace[793143158] 'process raft request' (duration: 121.272056ms)","trace[793143158] 'compare' (duration: 20.780403ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:42.418934Z","caller":"traceutil/trace.go:171","msg":"trace[467455478] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5264; }","duration":"105.19649ms","start":"2026-09-22T03:47:42.313705Z","end":"2026-09-22T03:47:42.418901Z","steps":["trace[467455478] 'read index received' (duration: 103.77716ms)","trace[467455478] 'applied index is now lower than readState.Index' (duration: 1.41517ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:42.419067Z","caller":"traceutil/trace.go:171","msg":"trace[1345856958] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"177.011331ms","start":"2026-09-22T03:47:42.24204Z","end":"2026-09-22T03:47:42.419051Z","steps":["trace[1345856958] 'process raft request' (duration: 175.418378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:42.41929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.183642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:47:42.419366Z","caller":"traceutil/trace.go:171","msg":"trace[1150342256] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5078; }","duration":"103.310344ms","start":"2026-09-22T03:47:42.316036Z","end":"2026-09-22T03:47:42.419346Z","steps":["trace[1150342256] 'agreement among raft nodes before linearized reading' (duration: 103.160672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:42.419499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.785658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T03:47:42.419564Z","caller":"traceutil/trace.go:171","msg":"trace[183047226] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5078; }","duration":"105.89033ms","start":"2026-09-22T03:47:42.313652Z","end":"2026-09-22T03:47:42.419542Z","steps":["trace[183047226] 'agreement among raft nodes before linearized reading' (duration: 105.761199ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:47.630963Z","caller":"traceutil/trace.go:171","msg":"trace[247360173] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"100.695037ms","start":"2026-09-22T03:47:47.530252Z","end":"2026-09-22T03:47:47.630947Z","steps":["trace[247360173] 'process raft request' (duration: 100.371343ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:47.63098Z","caller":"traceutil/trace.go:171","msg":"trace[1671717517] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"103.541977ms","start":"2026-09-22T03:47:47.52742Z","end":"2026-09-22T03:47:47.630962Z","steps":["trace[1671717517] 'process raft request' (duration: 103.165742ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:47.630803Z","caller":"traceutil/trace.go:171","msg":"trace[1460136049] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"103.386745ms","start":"2026-09-22T03:47:47.527372Z","end":"2026-09-22T03:47:47.630758Z","steps":["trace[1460136049] 'process raft request' (duration: 103.132051ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:47.631298Z","caller":"traceutil/trace.go:171","msg":"trace[702567910] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"103.954293ms","start":"2026-09-22T03:47:47.527326Z","end":"2026-09-22T03:47:47.63128Z","steps":["trace[702567910] 'process raft request' (duration: 62.585606ms)","trace[702567910] 'compare' (duration: 40.472494ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:49.961258Z","caller":"traceutil/trace.go:171","msg":"trace[1485375302] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"322.986123ms","start":"2026-09-22T03:47:49.638247Z","end":"2026-09-22T03:47:49.961233Z","steps":["trace[1485375302] 'process raft request' (duration: 322.80293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:49.961419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:49.63823Z","time spent":"323.100364ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:47:50.477963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490026220755167675,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T03:47:50.524609Z","caller":"traceutil/trace.go:171","msg":"trace[797584454] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5329; }","duration":"547.696296ms","start":"2026-09-22T03:47:49.976891Z","end":"2026-09-22T03:47:50.524587Z","steps":["trace[797584454] 'read index received' (duration: 531.4959ms)","trace[797584454] 'applied index is now lower than readState.Index' (duration: 16.199516ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:50.524871Z","caller":"traceutil/trace.go:171","msg":"trace[228912582] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"885.482016ms","start":"2026-09-22T03:47:49.639373Z","end":"2026-09-22T03:47:50.524855Z","steps":["trace[228912582] 'process raft request' (duration: 869.100477ms)","trace[228912582] 'compare' (duration: 15.489946ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:47:50.524968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:49.639356Z","time spent":"885.548316ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:47:50.52512Z","caller":"traceutil/trace.go:171","msg":"trace[252442694] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"753.776879ms","start":"2026-09-22T03:47:49.771333Z","end":"2026-09-22T03:47:50.52511Z","steps":["trace[252442694] 'process raft request' (duration: 753.190111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:50.52517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:49.771309Z","time spent":"753.830449ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:47:50.525314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.408146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T03:47:50.525377Z","caller":"traceutil/trace.go:171","msg":"trace[1798669688] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5143; }","duration":"548.517036ms","start":"2026-09-22T03:47:49.976846Z","end":"2026-09-22T03:47:50.525363Z","steps":["trace[1798669688] 'agreement among raft nodes before linearized reading' (duration: 548.389195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:50.525422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:49.976826Z","time spent":"548.586429ms","remote":"127.0.0.1:47884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":40,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-09-22T03:47:50.525552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.389884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-22T03:47:50.525654Z","caller":"traceutil/trace.go:171","msg":"trace[333494079] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5143; }","duration":"476.535266ms","start":"2026-09-22T03:47:50.049091Z","end":"2026-09-22T03:47:50.525626Z","steps":["trace[333494079] 'agreement among raft nodes before linearized reading' (duration: 476.253171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:50.525715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:50.049072Z","time spent":"476.632047ms","remote":"127.0.0.1:47540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-22T03:47:51.082278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.599899ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-22T03:47:51.226262Z","caller":"traceutil/trace.go:171","msg":"trace[1141980469] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5332; }","duration":"143.847901ms","start":"2026-09-22T03:47:51.082384Z","end":"2026-09-22T03:47:51.226232Z","steps":["trace[1141980469] 'read index received' (duration: 143.648378ms)","trace[1141980469] 'applied index is now lower than readState.Index' (duration: 197.823µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:51.226297Z","caller":"traceutil/trace.go:171","msg":"trace[81191882] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"176.496054ms","start":"2026-09-22T03:47:51.049772Z","end":"2026-09-22T03:47:51.226268Z","steps":["trace[81191882] 'process raft request' (duration: 176.22566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:51.226609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.752054ms","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-09-22T03:47:51.226642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.915886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T03:47:51.226703Z","caller":"traceutil/trace.go:171","msg":"trace[1760647007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5144; }","duration":"152.921897ms","start":"2026-09-22T03:47:51.073761Z","end":"2026-09-22T03:47:51.226683Z","steps":["trace[1760647007] 'agreement among raft nodes before linearized reading' (duration: 152.605782ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:51.226712Z","caller":"traceutil/trace.go:171","msg":"trace[349388925] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5144; }","duration":"140.025937ms","start":"2026-09-22T03:47:51.086667Z","end":"2026-09-22T03:47:51.226693Z","steps":["trace[349388925] 'agreement among raft nodes before linearized reading' (duration: 139.779333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:51.72212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.328696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:47:51.722377Z","caller":"traceutil/trace.go:171","msg":"trace[1323865144] linearizableReadLoop","detail":"{readStateIndex:5335; appliedIndex:5333; }","duration":"424.21849ms","start":"2026-09-22T03:47:51.29814Z","end":"2026-09-22T03:47:51.722359Z","steps":["trace[1323865144] 'read index received' (duration: 44.57286ms)","trace[1323865144] 'applied index is now lower than readState.Index' (duration: 379.64461ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:51.722472Z","caller":"traceutil/trace.go:171","msg":"trace[1879280187] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"491.740358ms","start":"2026-09-22T03:47:51.23072Z","end":"2026-09-22T03:47:51.722461Z","steps":["trace[1879280187] 'process raft request' (duration: 111.963276ms)","trace[1879280187] 'compare' (duration: 378.998222ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:47:51.722638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:51.2307Z","time spent":"491.896511ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:47:51.723013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.848538ms","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-09-22T03:47:51.723071Z","caller":"traceutil/trace.go:171","msg":"trace[2054019721] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"479.169473ms","start":"2026-09-22T03:47:51.243873Z","end":"2026-09-22T03:47:51.723042Z","steps":["trace[2054019721] 'process raft request' (duration: 478.366712ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:51.723103Z","caller":"traceutil/trace.go:171","msg":"trace[1151534949] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5146; }","duration":"424.985711ms","start":"2026-09-22T03:47:51.2981Z","end":"2026-09-22T03:47:51.723085Z","steps":["trace[1151534949] 'agreement among raft nodes before linearized reading' (duration: 424.757117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:51.723154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:51.298083Z","time spent":"425.056271ms","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-22T03:47:51.723474Z","caller":"traceutil/trace.go:171","msg":"trace[723290192] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"355.672436ms","start":"2026-09-22T03:47:51.36746Z","end":"2026-09-22T03:47:51.723132Z","steps":["trace[723290192] 'process raft request' (duration: 354.868355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:51.723533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.7094ms","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-09-22T03:47:51.723655Z","caller":"traceutil/trace.go:171","msg":"trace[1258510071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5147; }","duration":"269.869532ms","start":"2026-09-22T03:47:51.45377Z","end":"2026-09-22T03:47:51.723639Z","steps":["trace[1258510071] 'agreement among raft nodes before linearized reading' (duration: 269.362045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:51.723722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:51.243853Z","time spent":"479.272004ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:47:51.723785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.752625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T03:47:51.723869Z","caller":"traceutil/trace.go:171","msg":"trace[31501563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5147; }","duration":"278.889908ms","start":"2026-09-22T03:47:51.444962Z","end":"2026-09-22T03:47:51.723852Z","steps":["trace[31501563] 'agreement among raft nodes before linearized reading' (duration: 278.153998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:51.724083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:51.367432Z","time spent":"356.138882ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:47:52.357108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.318504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:47:52.357299Z","caller":"traceutil/trace.go:171","msg":"trace[1681916836] linearizableReadLoop","detail":"{readStateIndex:5338; appliedIndex:5336; }","duration":"626.399754ms","start":"2026-09-22T03:47:51.730885Z","end":"2026-09-22T03:47:52.357285Z","steps":["trace[1681916836] 'read index received' (duration: 142.729535ms)","trace[1681916836] 'applied index is now lower than readState.Index' (duration: 483.669379ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:52.35738Z","caller":"traceutil/trace.go:171","msg":"trace[898020438] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"627.897315ms","start":"2026-09-22T03:47:51.729472Z","end":"2026-09-22T03:47:52.35737Z","steps":["trace[898020438] 'process raft request' (duration: 144.260817ms)","trace[898020438] 'compare' (duration: 482.696135ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:47:52.357432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:51.729452Z","time spent":"627.948805ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:47:52.357604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.689937ms","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-09-22T03:47:52.357639Z","caller":"traceutil/trace.go:171","msg":"trace[1625475801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5149; }","duration":"390.765998ms","start":"2026-09-22T03:47:51.966865Z","end":"2026-09-22T03:47:52.357631Z","steps":["trace[1625475801] 'agreement among raft nodes before linearized reading' (duration: 390.664567ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:52.357678Z","caller":"traceutil/trace.go:171","msg":"trace[910518146] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"627.947337ms","start":"2026-09-22T03:47:51.729717Z","end":"2026-09-22T03:47:52.357665Z","steps":["trace[910518146] 'process raft request' (duration: 627.50551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:52.357771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:51.729686Z","time spent":"628.049608ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:47:52.357686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:51.966844Z","time spent":"390.813179ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-22T03:47:52.3576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.706149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T03:47:52.357915Z","caller":"traceutil/trace.go:171","msg":"trace[960787359] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5149; }","duration":"627.058293ms","start":"2026-09-22T03:47:51.730846Z","end":"2026-09-22T03:47:52.357905Z","steps":["trace[960787359] 'agreement among raft nodes before linearized reading' (duration: 626.577986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:52.357942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:51.730783Z","time spent":"627.151275ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-22T03:48:32.473325Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2911} {"level":"info","ts":"2026-09-22T03:48:32.577738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2911,"took":"102.432911ms","hash":1366537818,"current-db-size-bytes":51802112,"current-db-size":"52 MB","current-db-size-in-use-bytes":28385280,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T03:48:32.577793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1366537818,"revision":2911,"compact-revision":-1} {"level":"info","ts":"2026-09-22T03:51:37.90714Z","caller":"traceutil/trace.go:171","msg":"trace[1582299367] linearizableReadLoop","detail":"{readStateIndex:7000; appliedIndex:6998; }","duration":"139.879374ms","start":"2026-09-22T03:51:37.767238Z","end":"2026-09-22T03:51:37.907117Z","steps":["trace[1582299367] 'read index received' (duration: 24.515975ms)","trace[1582299367] 'applied index is now lower than readState.Index' (duration: 115.362439ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:51:37.907364Z","caller":"traceutil/trace.go:171","msg":"trace[1374196863] transaction","detail":"{read_only:false; response_revision:6758; number_of_response:1; }","duration":"143.648509ms","start":"2026-09-22T03:51:37.763698Z","end":"2026-09-22T03:51:37.907347Z","steps":["trace[1374196863] 'process raft request' (duration: 126.475983ms)","trace[1374196863] 'compare' (duration: 16.859243ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:51:37.907736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.464071ms","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-09-22T03:51:37.907859Z","caller":"traceutil/trace.go:171","msg":"trace[1525806838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6758; }","duration":"140.636864ms","start":"2026-09-22T03:51:37.767195Z","end":"2026-09-22T03:51:37.907832Z","steps":["trace[1525806838] 'agreement among raft nodes before linearized reading' (duration: 140.315659ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:51:38.756044Z","caller":"traceutil/trace.go:171","msg":"trace[1601794618] transaction","detail":"{read_only:false; response_revision:6768; number_of_response:1; }","duration":"117.870079ms","start":"2026-09-22T03:51:38.638143Z","end":"2026-09-22T03:51:38.756013Z","steps":["trace[1601794618] 'process raft request' (duration: 117.656116ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:52:00.260561Z","caller":"traceutil/trace.go:171","msg":"trace[1511991228] linearizableReadLoop","detail":"{readStateIndex:7179; appliedIndex:7178; }","duration":"158.79824ms","start":"2026-09-22T03:52:00.101738Z","end":"2026-09-22T03:52:00.260536Z","steps":["trace[1511991228] 'read index received' (duration: 85.372592ms)","trace[1511991228] 'applied index is now lower than readState.Index' (duration: 73.424378ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:52:00.260724Z","caller":"traceutil/trace.go:171","msg":"trace[557473011] transaction","detail":"{read_only:false; response_revision:6930; number_of_response:1; }","duration":"173.316067ms","start":"2026-09-22T03:52:00.087394Z","end":"2026-09-22T03:52:00.26071Z","steps":["trace[557473011] 'process raft request' (duration: 99.779105ms)","trace[557473011] 'compare' (duration: 73.232242ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:52:00.261135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.376838ms","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-09-22T03:52:00.26121Z","caller":"traceutil/trace.go:171","msg":"trace[220539210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6930; }","duration":"159.488362ms","start":"2026-09-22T03:52:00.101705Z","end":"2026-09-22T03:52:00.261194Z","steps":["trace[220539210] 'agreement among raft nodes before linearized reading' (duration: 159.220854ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:52:00.356386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.607757ms","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-09-22T03:52:00.356461Z","caller":"traceutil/trace.go:171","msg":"trace[786375819] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:6931; }","duration":"188.746091ms","start":"2026-09-22T03:52:00.167693Z","end":"2026-09-22T03:52:00.35644Z","steps":["trace[786375819] 'agreement among raft nodes before linearized reading' (duration: 188.591517ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:52:00.356419Z","caller":"traceutil/trace.go:171","msg":"trace[2076251916] transaction","detail":"{read_only:false; response_revision:6931; number_of_response:1; }","duration":"151.094403ms","start":"2026-09-22T03:52:00.205274Z","end":"2026-09-22T03:52:00.356368Z","steps":["trace[2076251916] 'process raft request' (duration: 150.886417ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:53:32.483519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5478} {"level":"info","ts":"2026-09-22T03:53:32.594714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5478,"took":"109.63085ms","hash":3640370131,"current-db-size-bytes":51802112,"current-db-size":"52 MB","current-db-size-in-use-bytes":18509824,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-22T03:53:32.594796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3640370131,"revision":5478,"compact-revision":2911}