{"level":"warn","ts":"2026-05-29T05:01:01.299656Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:01:01.299766Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T05:01:01.299871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:01:01.299899Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-29T05:01:01.299926Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T05:01:01.300658Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-29T05:01:01.300788Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T05:01:01.305292Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.13056ms"} {"level":"info","ts":"2026-05-29T05:01:01.313514Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-29T05:01:01.313603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T05:01:01.313627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-29T05:01:01.31364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T05:01:01.313656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-29T05:01:01.313694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-29T05:01:01.323285Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T05:01:01.325641Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T05:01:01.327911Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T05:01:01.330304Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T05:01:01.335952Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T05:01:01.336544Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-29T05:01:01.336626Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-29T05:01:01.336722Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:01:01.336864Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:01:01.336883Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:01:01.336806Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T05:01:01.341131Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T05:01:01.341177Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T05:01:01.344031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-29T05:01:01.344115Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-29T05:01:01.514289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T05:01:01.514338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T05:01:01.514362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-29T05:01:01.514378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-29T05:01:01.514383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-29T05:01:01.514395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-29T05:01:01.514402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-29T05:01:01.516539Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T05:01:01.516577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:01:01.516847Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:01:01.516996Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:01:01.51834Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T05:01:01.518996Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T05:01:01.519032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T05:01:01.519216Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:01:01.519297Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:01:01.519372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:01:01.520286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-29T05:01:14.598515Z","caller":"traceutil/trace.go:171","msg":"trace[793168051] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"208.463896ms","start":"2026-05-29T05:01:14.39001Z","end":"2026-05-29T05:01:14.598473Z","steps":["trace[793168051] 'process raft request' (duration: 208.085027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.084822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.555202ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:01:21.084981Z","caller":"traceutil/trace.go:171","msg":"trace[1831322397] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"994.580235ms","start":"2026-05-29T05:01:20.090379Z","end":"2026-05-29T05:01:21.084959Z","steps":["trace[1831322397] 'read index received' (duration: 166.673516ms)","trace[1831322397] 'applied index is now lower than readState.Index' (duration: 827.904519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:01:21.085135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.739838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:01:21.085191Z","caller":"traceutil/trace.go:171","msg":"trace[1485241517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:381; }","duration":"994.83814ms","start":"2026-05-29T05:01:20.090339Z","end":"2026-05-29T05:01:21.085178Z","steps":["trace[1485241517] 'agreement among raft nodes before linearized reading' (duration: 994.742338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.085237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:20.09032Z","time spent":"994.905953ms","remote":"127.0.0.1:57716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:01:21.085561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.455765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:01:21.085604Z","caller":"traceutil/trace.go:171","msg":"trace[917475640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:381; }","duration":"455.497835ms","start":"2026-05-29T05:01:20.630095Z","end":"2026-05-29T05:01:21.085592Z","steps":["trace[917475640] 'agreement among raft nodes before linearized reading' (duration: 455.422173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.085645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:20.63005Z","time spent":"455.582067ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:01:21.537785Z","caller":"traceutil/trace.go:171","msg":"trace[720266268] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"444.177156ms","start":"2026-05-29T05:01:21.093589Z","end":"2026-05-29T05:01:21.537766Z","steps":["trace[720266268] 'read index received' (duration: 443.944151ms)","trace[720266268] 'applied index is now lower than readState.Index' (duration: 232.085µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:01:21.538042Z","caller":"traceutil/trace.go:171","msg":"trace[1509298165] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"448.545992ms","start":"2026-05-29T05:01:21.08948Z","end":"2026-05-29T05:01:21.538026Z","steps":["trace[1509298165] 'process raft request' (duration: 448.145543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.53864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:21.08945Z","time spent":"448.629083ms","remote":"127.0.0.1:58006","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-05-29T05:01:21.538854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.261609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:01:21.538903Z","caller":"traceutil/trace.go:171","msg":"trace[343022444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"445.319461ms","start":"2026-05-29T05:01:21.093563Z","end":"2026-05-29T05:01:21.538882Z","steps":["trace[343022444] 'agreement among raft nodes before linearized reading' (duration: 445.26279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.538931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:21.093551Z","time spent":"445.373832ms","remote":"127.0.0.1:57716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:01:21.539095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.561648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:01:21.539131Z","caller":"traceutil/trace.go:171","msg":"trace[775735828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:382; }","duration":"347.630049ms","start":"2026-05-29T05:01:21.191493Z","end":"2026-05-29T05:01:21.539123Z","steps":["trace[775735828] 'agreement among raft nodes before linearized reading' (duration: 347.573338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.539157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:21.191471Z","time spent":"347.680011ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:01:21.539344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.542175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T05:01:21.539378Z","caller":"traceutil/trace.go:171","msg":"trace[1566524305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:382; }","duration":"231.604886ms","start":"2026-05-29T05:01:21.307765Z","end":"2026-05-29T05:01:21.53937Z","steps":["trace[1566524305] 'agreement among raft nodes before linearized reading' (duration: 231.549474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.53954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.19502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:01:21.539576Z","caller":"traceutil/trace.go:171","msg":"trace[1116617260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:382; }","duration":"347.247111ms","start":"2026-05-29T05:01:21.192318Z","end":"2026-05-29T05:01:21.539565Z","steps":["trace[1116617260] 'agreement among raft nodes before linearized reading' (duration: 347.19004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.539602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:21.19231Z","time spent":"347.286491ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:01:21.983406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.594077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:01:21.983583Z","caller":"traceutil/trace.go:171","msg":"trace[1694714927] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"439.79642ms","start":"2026-05-29T05:01:21.543763Z","end":"2026-05-29T05:01:21.98356Z","steps":["trace[1694714927] 'process raft request' (duration: 264.987419ms)","trace[1694714927] 'compare' (duration: 174.509194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:01:21.983654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:21.543748Z","time spent":"439.875831ms","remote":"127.0.0.1:58006","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-05-29T05:01:21.983772Z","caller":"traceutil/trace.go:171","msg":"trace[537423574] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"439.912312ms","start":"2026-05-29T05:01:21.54385Z","end":"2026-05-29T05:01:21.983763Z","steps":["trace[537423574] 'process raft request' (duration: 439.646497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:21.983817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:21.543843Z","time spent":"439.949343ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:01:39.740007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.444466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-29T05:01:39.740102Z","caller":"traceutil/trace.go:171","msg":"trace[1629288282] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:558; }","duration":"204.573308ms","start":"2026-05-29T05:01:39.535509Z","end":"2026-05-29T05:01:39.740083Z","steps":["trace[1629288282] 'range keys from in-memory index tree' (duration: 204.268071ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:01:40.232432Z","caller":"traceutil/trace.go:171","msg":"trace[1553253565] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"124.492645ms","start":"2026-05-29T05:01:40.107895Z","end":"2026-05-29T05:01:40.232388Z","steps":["trace[1553253565] 'read index received' (duration: 119.661295ms)","trace[1553253565] 'applied index is now lower than readState.Index' (duration: 4.82894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:01:40.23265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.719929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:01:40.232773Z","caller":"traceutil/trace.go:171","msg":"trace[711366929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"124.888153ms","start":"2026-05-29T05:01:40.107857Z","end":"2026-05-29T05:01:40.232745Z","steps":["trace[711366929] 'agreement among raft nodes before linearized reading' (duration: 124.653159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:40.233239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.684751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T05:01:40.233347Z","caller":"traceutil/trace.go:171","msg":"trace[725280625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"107.861305ms","start":"2026-05-29T05:01:40.125471Z","end":"2026-05-29T05:01:40.233332Z","steps":["trace[725280625] 'agreement among raft nodes before linearized reading' (duration: 107.507138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:40.233529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.609443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-05-29T05:01:40.233768Z","caller":"traceutil/trace.go:171","msg":"trace[505303217] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:564; }","duration":"120.701464ms","start":"2026-05-29T05:01:40.112878Z","end":"2026-05-29T05:01:40.23358Z","steps":["trace[505303217] 'agreement among raft nodes before linearized reading' (duration: 120.085163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:43.204801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.215032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:01:43.204966Z","caller":"traceutil/trace.go:171","msg":"trace[313389986] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:578; }","duration":"122.444327ms","start":"2026-05-29T05:01:43.082499Z","end":"2026-05-29T05:01:43.204943Z","steps":["trace[313389986] 'range keys from in-memory index tree' (duration: 122.13915ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:01:46.412489Z","caller":"traceutil/trace.go:171","msg":"trace[1157914465] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"131.854306ms","start":"2026-05-29T05:01:46.280599Z","end":"2026-05-29T05:01:46.412453Z","steps":["trace[1157914465] 'process raft request' (duration: 98.627931ms)","trace[1157914465] 'compare' (duration: 32.988811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:01:46.779147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.662717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:01:46.779317Z","caller":"traceutil/trace.go:171","msg":"trace[1300362180] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"347.129621ms","start":"2026-05-29T05:01:46.432155Z","end":"2026-05-29T05:01:46.779285Z","steps":["trace[1300362180] 'process raft request' (duration: 207.173999ms)","trace[1300362180] 'compare' (duration: 139.502734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:01:46.779388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:46.43213Z","time spent":"347.226563ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:01:46.971808Z","caller":"traceutil/trace.go:171","msg":"trace[2019718989] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"100.074196ms","start":"2026-05-29T05:01:46.871702Z","end":"2026-05-29T05:01:46.971776Z","steps":["trace[2019718989] 'process raft request' (duration: 98.917826ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:01:48.15816Z","caller":"traceutil/trace.go:171","msg":"trace[1368044665] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"292.377041ms","start":"2026-05-29T05:01:47.865752Z","end":"2026-05-29T05:01:48.158129Z","steps":["trace[1368044665] 'read index received' (duration: 292.146446ms)","trace[1368044665] 'applied index is now lower than readState.Index' (duration: 229.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:01:48.158305Z","caller":"traceutil/trace.go:171","msg":"trace[1159007145] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"358.721313ms","start":"2026-05-29T05:01:47.799531Z","end":"2026-05-29T05:01:48.158253Z","steps":["trace[1159007145] 'process raft request' (duration: 358.431718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:48.158406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.631356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T05:01:48.158449Z","caller":"traceutil/trace.go:171","msg":"trace[1685136296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"292.699728ms","start":"2026-05-29T05:01:47.865737Z","end":"2026-05-29T05:01:48.158436Z","steps":["trace[1685136296] 'agreement among raft nodes before linearized reading' (duration: 292.507314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:48.158562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:47.799497Z","time spent":"358.923786ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:01:55.113469Z","caller":"traceutil/trace.go:171","msg":"trace[31009179] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"108.454239ms","start":"2026-05-29T05:01:55.00498Z","end":"2026-05-29T05:01:55.113435Z","steps":["trace[31009179] 'process raft request' (duration: 107.364841ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:02:00.560526Z","caller":"traceutil/trace.go:171","msg":"trace[1925299341] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"320.635403ms","start":"2026-05-29T05:02:00.239856Z","end":"2026-05-29T05:02:00.560491Z","steps":["trace[1925299341] 'read index received' (duration: 320.361289ms)","trace[1925299341] 'applied index is now lower than readState.Index' (duration: 272.094µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:02:00.560565Z","caller":"traceutil/trace.go:171","msg":"trace[1351530902] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"331.557332ms","start":"2026-05-29T05:02:00.228977Z","end":"2026-05-29T05:02:00.560535Z","steps":["trace[1351530902] 'process raft request' (duration: 331.272457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:02:00.560862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:02:00.228947Z","time spent":"331.812616ms","remote":"127.0.0.1:58006","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-05-29T05:02:00.560962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.033919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:02:00.561087Z","caller":"traceutil/trace.go:171","msg":"trace[199072782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"321.256823ms","start":"2026-05-29T05:02:00.239803Z","end":"2026-05-29T05:02:00.56106Z","steps":["trace[199072782] 'agreement among raft nodes before linearized reading' (duration: 320.860387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:02:00.561096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.674559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T05:02:00.561158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:02:00.239785Z","time spent":"321.358965ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:02:00.561237Z","caller":"traceutil/trace.go:171","msg":"trace[1878998153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"161.891212ms","start":"2026-05-29T05:02:00.399296Z","end":"2026-05-29T05:02:00.561187Z","steps":["trace[1878998153] 'agreement among raft nodes before linearized reading' (duration: 161.535267ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:02:03.676415Z","caller":"traceutil/trace.go:171","msg":"trace[418923571] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"186.894744ms","start":"2026-05-29T05:02:03.489501Z","end":"2026-05-29T05:02:03.676396Z","steps":["trace[418923571] 'process raft request' (duration: 186.792612ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:02:34.807295Z","caller":"traceutil/trace.go:171","msg":"trace[1650558133] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"495.031586ms","start":"2026-05-29T05:02:34.312213Z","end":"2026-05-29T05:02:34.807245Z","steps":["trace[1650558133] 'process raft request' (duration: 494.698762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:02:34.807574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:02:34.312185Z","time spent":"495.248749ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:02:34.941768Z","caller":"traceutil/trace.go:171","msg":"trace[2022014107] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1212; }","duration":"255.847046ms","start":"2026-05-29T05:02:34.685857Z","end":"2026-05-29T05:02:34.941704Z","steps":["trace[2022014107] 'read index received' (duration: 121.241257ms)","trace[2022014107] 'applied index is now lower than readState.Index' (duration: 134.604009ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:02:34.941911Z","caller":"traceutil/trace.go:171","msg":"trace[1945783461] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"458.674892ms","start":"2026-05-29T05:02:34.4832Z","end":"2026-05-29T05:02:34.941875Z","steps":["trace[1945783461] 'process raft request' (duration: 456.398321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:02:34.94209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.217361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-29T05:02:34.942119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:02:34.483157Z","time spent":"458.845125ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:02:34.942142Z","caller":"traceutil/trace.go:171","msg":"trace[617824727] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:1173; }","duration":"256.335353ms","start":"2026-05-29T05:02:34.685794Z","end":"2026-05-29T05:02:34.942129Z","steps":["trace[617824727] 'agreement among raft nodes before linearized reading' (duration: 256.14579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:02:34.942288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.75883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:02:34.942366Z","caller":"traceutil/trace.go:171","msg":"trace[1376642107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"125.867681ms","start":"2026-05-29T05:02:34.81648Z","end":"2026-05-29T05:02:34.942348Z","steps":["trace[1376642107] 'agreement among raft nodes before linearized reading' (duration: 125.704369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:02:34.942442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.481073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:02:34.942697Z","caller":"traceutil/trace.go:171","msg":"trace[1770427429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1173; }","duration":"133.659145ms","start":"2026-05-29T05:02:34.808887Z","end":"2026-05-29T05:02:34.942546Z","steps":["trace[1770427429] 'agreement among raft nodes before linearized reading' (duration: 133.26667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:02:34.942636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.086337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T05:02:34.942815Z","caller":"traceutil/trace.go:171","msg":"trace[831763787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"130.28396ms","start":"2026-05-29T05:02:34.812517Z","end":"2026-05-29T05:02:34.942801Z","steps":["trace[831763787] 'agreement among raft nodes before linearized reading' (duration: 129.54768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:02:34.943017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.748551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T05:02:34.943093Z","caller":"traceutil/trace.go:171","msg":"trace[2084863086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"134.836682ms","start":"2026-05-29T05:02:34.808239Z","end":"2026-05-29T05:02:34.943075Z","steps":["trace[2084863086] 'agreement among raft nodes before linearized reading' (duration: 133.715587ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:19.224255Z","caller":"traceutil/trace.go:171","msg":"trace[672435377] linearizableReadLoop","detail":"{readStateIndex:1830; appliedIndex:1829; }","duration":"133.882317ms","start":"2026-05-29T05:03:19.090348Z","end":"2026-05-29T05:03:19.22423Z","steps":["trace[672435377] 'read index received' (duration: 133.735604ms)","trace[672435377] 'applied index is now lower than readState.Index' (duration: 145.533µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:03:19.224318Z","caller":"traceutil/trace.go:171","msg":"trace[1712809353] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"176.188339ms","start":"2026-05-29T05:03:19.0481Z","end":"2026-05-29T05:03:19.224288Z","steps":["trace[1712809353] 'process raft request' (duration: 175.968065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:19.224516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.15031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:03:19.224648Z","caller":"traceutil/trace.go:171","msg":"trace[930500265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1750; }","duration":"134.333322ms","start":"2026-05-29T05:03:19.090281Z","end":"2026-05-29T05:03:19.224614Z","steps":["trace[930500265] 'agreement among raft nodes before linearized reading' (duration: 134.065999ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:19.22648Z","caller":"traceutil/trace.go:171","msg":"trace[61315215] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"134.1544ms","start":"2026-05-29T05:03:19.092304Z","end":"2026-05-29T05:03:19.226459Z","steps":["trace[61315215] 'process raft request' (duration: 133.702075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:26.184194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.797487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10630"} {"level":"info","ts":"2026-05-29T05:03:26.184378Z","caller":"traceutil/trace.go:171","msg":"trace[1537294848] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1858; }","duration":"264.038729ms","start":"2026-05-29T05:03:25.920318Z","end":"2026-05-29T05:03:26.184357Z","steps":["trace[1537294848] 'range keys from in-memory index tree' (duration: 263.578464ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:26.505054Z","caller":"traceutil/trace.go:171","msg":"trace[1517632472] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"242.599433ms","start":"2026-05-29T05:03:26.262426Z","end":"2026-05-29T05:03:26.505026Z","steps":["trace[1517632472] 'process raft request' (duration: 242.310818ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:26.505523Z","caller":"traceutil/trace.go:171","msg":"trace[1789661349] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1941; }","duration":"242.823845ms","start":"2026-05-29T05:03:26.262688Z","end":"2026-05-29T05:03:26.505511Z","steps":["trace[1789661349] 'read index received' (duration: 14.623547ms)","trace[1789661349] 'applied index is now lower than readState.Index' (duration: 228.199238ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:03:26.505531Z","caller":"traceutil/trace.go:171","msg":"trace[24892224] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"242.677443ms","start":"2026-05-29T05:03:26.262823Z","end":"2026-05-29T05:03:26.5055Z","steps":["trace[24892224] 'process raft request' (duration: 242.162847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:26.505757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.047477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e64c1e17-d8c0-444c-8fb5-7a129d52cd34\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-29T05:03:26.505817Z","caller":"traceutil/trace.go:171","msg":"trace[1342557557] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e64c1e17-d8c0-444c-8fb5-7a129d52cd34; range_end:; response_count:1; response_revision:1863; }","duration":"243.150078ms","start":"2026-05-29T05:03:26.262654Z","end":"2026-05-29T05:03:26.505804Z","steps":["trace[1342557557] 'agreement among raft nodes before linearized reading' (duration: 242.911576ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:26.505773Z","caller":"traceutil/trace.go:171","msg":"trace[1253540866] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"242.807255ms","start":"2026-05-29T05:03:26.262943Z","end":"2026-05-29T05:03:26.505751Z","steps":["trace[1253540866] 'process raft request' (duration: 242.321479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:27.499967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.77114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:03:27.500057Z","caller":"traceutil/trace.go:171","msg":"trace[600585159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"408.948932ms","start":"2026-05-29T05:03:27.091089Z","end":"2026-05-29T05:03:27.500038Z","steps":["trace[600585159] 'range keys from in-memory index tree' (duration: 408.606638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:27.500102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:03:27.091051Z","time spent":"409.039683ms","remote":"127.0.0.1:57716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:03:27.500152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.136154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e64c1e17-d8c0-444c-8fb5-7a129d52cd34\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-29T05:03:27.500335Z","caller":"traceutil/trace.go:171","msg":"trace[259296005] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e64c1e17-d8c0-444c-8fb5-7a129d52cd34; range_end:; response_count:1; response_revision:1866; }","duration":"684.374877ms","start":"2026-05-29T05:03:26.815944Z","end":"2026-05-29T05:03:27.500319Z","steps":["trace[259296005] 'range keys from in-memory index tree' (duration: 684.011893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:27.500351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.501506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:03:27.500521Z","caller":"traceutil/trace.go:171","msg":"trace[666146337] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"392.90728ms","start":"2026-05-29T05:03:27.107558Z","end":"2026-05-29T05:03:27.500465Z","steps":["trace[666146337] 'process raft request' (duration: 199.182351ms)","trace[666146337] 'compare' (duration: 193.201763ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:03:27.500589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:03:27.107517Z","time spent":"393.0367ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:03:27.500393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:03:26.815915Z","time spent":"684.455047ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e64c1e17-d8c0-444c-8fb5-7a129d52cd34\" "} {"level":"info","ts":"2026-05-29T05:03:27.505774Z","caller":"traceutil/trace.go:171","msg":"trace[882948448] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1948; }","duration":"245.254056ms","start":"2026-05-29T05:03:27.260503Z","end":"2026-05-29T05:03:27.505757Z","steps":["trace[882948448] 'read index received' (duration: 46.255237ms)","trace[882948448] 'applied index is now lower than readState.Index' (duration: 198.997829ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:03:27.505834Z","caller":"traceutil/trace.go:171","msg":"trace[903133885] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"246.848694ms","start":"2026-05-29T05:03:27.258971Z","end":"2026-05-29T05:03:27.50582Z","steps":["trace[903133885] 'process raft request' (duration: 246.711842ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:27.505842Z","caller":"traceutil/trace.go:171","msg":"trace[559406719] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"251.590669ms","start":"2026-05-29T05:03:27.254212Z","end":"2026-05-29T05:03:27.505802Z","steps":["trace[559406719] 'process raft request' (duration: 251.340286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:27.506059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.54617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T05:03:27.506139Z","caller":"traceutil/trace.go:171","msg":"trace[1729429052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1869; }","duration":"245.699541ms","start":"2026-05-29T05:03:27.260417Z","end":"2026-05-29T05:03:27.506117Z","steps":["trace[1729429052] 'agreement among raft nodes before linearized reading' (duration: 245.444248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:27.506127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.455171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T05:03:27.506188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.960787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T05:03:27.506221Z","caller":"traceutil/trace.go:171","msg":"trace[2061298419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1869; }","duration":"226.022297ms","start":"2026-05-29T05:03:27.280185Z","end":"2026-05-29T05:03:27.506207Z","steps":["trace[2061298419] 'agreement among raft nodes before linearized reading' (duration: 225.885206ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:27.506219Z","caller":"traceutil/trace.go:171","msg":"trace[1591960564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1869; }","duration":"225.561972ms","start":"2026-05-29T05:03:27.280636Z","end":"2026-05-29T05:03:27.506198Z","steps":["trace[1591960564] 'agreement among raft nodes before linearized reading' (duration: 225.328849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:27.506205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.253013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T05:03:27.506322Z","caller":"traceutil/trace.go:171","msg":"trace[1089757368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1869; }","duration":"221.420295ms","start":"2026-05-29T05:03:27.284872Z","end":"2026-05-29T05:03:27.506293Z","steps":["trace[1089757368] 'agreement among raft nodes before linearized reading' (duration: 221.064211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:27.506408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.865072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T05:03:27.506473Z","caller":"traceutil/trace.go:171","msg":"trace[194241771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1869; }","duration":"231.004293ms","start":"2026-05-29T05:03:27.275458Z","end":"2026-05-29T05:03:27.506462Z","steps":["trace[194241771] 'agreement among raft nodes before linearized reading' (duration: 230.417636ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.094519Z","caller":"traceutil/trace.go:171","msg":"trace[540145348] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"234.453709ms","start":"2026-05-29T05:03:38.860019Z","end":"2026-05-29T05:03:39.094472Z","steps":["trace[540145348] 'process raft request' (duration: 234.196707ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.532271Z","caller":"traceutil/trace.go:171","msg":"trace[891605872] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2049; }","duration":"550.901953ms","start":"2026-05-29T05:03:38.981345Z","end":"2026-05-29T05:03:39.532247Z","steps":["trace[891605872] 'read index received' (duration: 112.914636ms)","trace[891605872] 'applied index is now lower than readState.Index' (duration: 437.986227ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:03:39.532372Z","caller":"traceutil/trace.go:171","msg":"trace[694610051] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"614.656282ms","start":"2026-05-29T05:03:38.917662Z","end":"2026-05-29T05:03:39.532318Z","steps":["trace[694610051] 'process raft request' (duration: 614.406219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:39.532467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.081315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:03:39.532653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.641751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T05:03:39.532641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.82652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:03:39.532688Z","caller":"traceutil/trace.go:171","msg":"trace[1005335222] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1966; }","duration":"192.722422ms","start":"2026-05-29T05:03:39.339957Z","end":"2026-05-29T05:03:39.532679Z","steps":["trace[1005335222] 'agreement among raft nodes before linearized reading' (duration: 192.61315ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.532732Z","caller":"traceutil/trace.go:171","msg":"trace[1711295313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1966; }","duration":"441.972692ms","start":"2026-05-29T05:03:39.09074Z","end":"2026-05-29T05:03:39.532712Z","steps":["trace[1711295313] 'agreement among raft nodes before linearized reading' (duration: 441.8008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:39.532778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:03:39.090723Z","time spent":"442.047703ms","remote":"127.0.0.1:57716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:03:39.532796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.33324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11980"} {"level":"info","ts":"2026-05-29T05:03:39.532879Z","caller":"traceutil/trace.go:171","msg":"trace[340030438] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1966; }","duration":"438.482352ms","start":"2026-05-29T05:03:39.09438Z","end":"2026-05-29T05:03:39.532862Z","steps":["trace[340030438] 'agreement among raft nodes before linearized reading' (duration: 438.197969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:39.532955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:03:39.094358Z","time spent":"438.584543ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12003,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-29T05:03:39.53299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:03:38.91764Z","time spent":"614.858534ms","remote":"127.0.0.1:57894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:03:39.532522Z","caller":"traceutil/trace.go:171","msg":"trace[1331653775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1966; }","duration":"551.238497ms","start":"2026-05-29T05:03:38.981268Z","end":"2026-05-29T05:03:39.532506Z","steps":["trace[1331653775] 'agreement among raft nodes before linearized reading' (duration: 551.122366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:39.53322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:03:38.981253Z","time spent":"551.944464ms","remote":"127.0.0.1:52036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:03:39.679019Z","caller":"traceutil/trace.go:171","msg":"trace[285984475] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"135.01337ms","start":"2026-05-29T05:03:39.543991Z","end":"2026-05-29T05:03:39.679005Z","steps":["trace[285984475] 'process raft request' (duration: 134.814348ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.678967Z","caller":"traceutil/trace.go:171","msg":"trace[1795958729] linearizableReadLoop","detail":"{readStateIndex:2053; appliedIndex:2052; }","duration":"134.00742ms","start":"2026-05-29T05:03:39.544939Z","end":"2026-05-29T05:03:39.678947Z","steps":["trace[1795958729] 'read index received' (duration: 35.832229ms)","trace[1795958729] 'applied index is now lower than readState.Index' (duration: 98.174361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:03:39.687016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.719908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:03:39.68708Z","caller":"traceutil/trace.go:171","msg":"trace[1002277433] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1968; }","duration":"142.17658ms","start":"2026-05-29T05:03:39.544889Z","end":"2026-05-29T05:03:39.687066Z","steps":["trace[1002277433] 'agreement among raft nodes before linearized reading' (duration: 134.282382ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.703086Z","caller":"traceutil/trace.go:171","msg":"trace[2022120239] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"119.873663ms","start":"2026-05-29T05:03:39.582874Z","end":"2026-05-29T05:03:39.702747Z","steps":["trace[2022120239] 'process raft request' (duration: 119.64224ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.880699Z","caller":"traceutil/trace.go:171","msg":"trace[1212876685] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"171.93937ms","start":"2026-05-29T05:03:39.708732Z","end":"2026-05-29T05:03:39.880671Z","steps":["trace[1212876685] 'process raft request' (duration: 171.846639ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.881346Z","caller":"traceutil/trace.go:171","msg":"trace[323014743] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2055; }","duration":"133.351142ms","start":"2026-05-29T05:03:39.747966Z","end":"2026-05-29T05:03:39.881317Z","steps":["trace[323014743] 'read index received' (duration: 57.63858ms)","trace[323014743] 'applied index is now lower than readState.Index' (duration: 75.709852ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:03:39.88115Z","caller":"traceutil/trace.go:171","msg":"trace[1429463692] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"172.433876ms","start":"2026-05-29T05:03:39.708654Z","end":"2026-05-29T05:03:39.881087Z","steps":["trace[1429463692] 'process raft request' (duration: 96.939147ms)","trace[1429463692] 'compare' (duration: 74.856532ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:03:39.881173Z","caller":"traceutil/trace.go:171","msg":"trace[1675522607] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"172.050632ms","start":"2026-05-29T05:03:39.709078Z","end":"2026-05-29T05:03:39.881128Z","steps":["trace[1675522607] 'process raft request' (duration: 171.658618ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.881329Z","caller":"traceutil/trace.go:171","msg":"trace[1692557489] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"171.149163ms","start":"2026-05-29T05:03:39.710158Z","end":"2026-05-29T05:03:39.881307Z","steps":["trace[1692557489] 'process raft request' (duration: 170.652857ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:03:39.881806Z","caller":"traceutil/trace.go:171","msg":"trace[535694140] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"173.011283ms","start":"2026-05-29T05:03:39.70877Z","end":"2026-05-29T05:03:39.881782Z","steps":["trace[535694140] 'process raft request' (duration: 171.85787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:03:39.909625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.633606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-29T05:03:39.909695Z","caller":"traceutil/trace.go:171","msg":"trace[1854923068] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1975; }","duration":"161.748197ms","start":"2026-05-29T05:03:39.747924Z","end":"2026-05-29T05:03:39.909672Z","steps":["trace[1854923068] 'agreement among raft nodes before linearized reading' (duration: 133.493873ms)","trace[1854923068] 'range keys from in-memory index tree' (duration: 28.031372ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:03:40.257562Z","caller":"traceutil/trace.go:171","msg":"trace[805036354] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"228.512455ms","start":"2026-05-29T05:03:40.029015Z","end":"2026-05-29T05:03:40.257527Z","steps":["trace[805036354] 'process raft request' (duration: 228.212241ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:04:16.556182Z","caller":"traceutil/trace.go:171","msg":"trace[124703990] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"414.256518ms","start":"2026-05-29T05:04:16.141901Z","end":"2026-05-29T05:04:16.556158Z","steps":["trace[124703990] 'process raft request' (duration: 414.112297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:16.556337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:16.141885Z","time spent":"414.375109ms","remote":"127.0.0.1:58006","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-05-29T05:04:16.562174Z","caller":"traceutil/trace.go:171","msg":"trace[465016222] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2275; }","duration":"343.477006ms","start":"2026-05-29T05:04:16.21868Z","end":"2026-05-29T05:04:16.562157Z","steps":["trace[465016222] 'read index received' (duration: 337.27715ms)","trace[465016222] 'applied index is now lower than readState.Index' (duration: 6.199066ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:04:16.562347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.653159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-29T05:04:16.562397Z","caller":"traceutil/trace.go:171","msg":"trace[1357241228] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2183; }","duration":"343.730589ms","start":"2026-05-29T05:04:16.218648Z","end":"2026-05-29T05:04:16.562378Z","steps":["trace[1357241228] 'agreement among raft nodes before linearized reading' (duration: 343.574838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:16.562436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:16.218632Z","time spent":"343.79563ms","remote":"127.0.0.1:53094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-29T05:04:16.562336Z","caller":"traceutil/trace.go:171","msg":"trace[1538410439] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"414.797065ms","start":"2026-05-29T05:04:16.14745Z","end":"2026-05-29T05:04:16.562248Z","steps":["trace[1538410439] 'process raft request' (duration: 414.610433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:16.562762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:16.14744Z","time spent":"415.158978ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:04:17.855899Z","caller":"traceutil/trace.go:171","msg":"trace[1520775714] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2277; }","duration":"279.839554ms","start":"2026-05-29T05:04:17.576038Z","end":"2026-05-29T05:04:17.855877Z","steps":["trace[1520775714] 'read index received' (duration: 279.737053ms)","trace[1520775714] 'applied index is now lower than readState.Index' (duration: 100.801µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:04:17.855963Z","caller":"traceutil/trace.go:171","msg":"trace[996160029] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"308.748438ms","start":"2026-05-29T05:04:17.547184Z","end":"2026-05-29T05:04:17.855933Z","steps":["trace[996160029] 'process raft request' (duration: 308.505355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:17.856128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.056556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-29T05:04:17.856124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:17.547145Z","time spent":"308.889449ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:04:17.856168Z","caller":"traceutil/trace.go:171","msg":"trace[2081936143] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2184; }","duration":"280.165538ms","start":"2026-05-29T05:04:17.57599Z","end":"2026-05-29T05:04:17.856155Z","steps":["trace[2081936143] 'agreement among raft nodes before linearized reading' (duration: 279.984646ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:04:18.167362Z","caller":"traceutil/trace.go:171","msg":"trace[338694136] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"319.352837ms","start":"2026-05-29T05:04:17.847977Z","end":"2026-05-29T05:04:18.16733Z","steps":["trace[338694136] 'process raft request' (duration: 318.977603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:18.167539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:17.847946Z","time spent":"319.502538ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:04:18.167643Z","caller":"traceutil/trace.go:171","msg":"trace[570043136] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"317.432148ms","start":"2026-05-29T05:04:17.850186Z","end":"2026-05-29T05:04:18.167618Z","steps":["trace[570043136] 'process raft request' (duration: 316.948112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:18.167795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:17.850163Z","time spent":"317.562569ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:04:18.167936Z","caller":"traceutil/trace.go:171","msg":"trace[1887569665] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"316.216654ms","start":"2026-05-29T05:04:17.851705Z","end":"2026-05-29T05:04:18.167921Z","steps":["trace[1887569665] 'process raft request' (duration: 315.8085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:18.16817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:17.851686Z","time spent":"316.388726ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:04:18.168239Z","caller":"traceutil/trace.go:171","msg":"trace[1112954102] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2278; }","duration":"232.628617ms","start":"2026-05-29T05:04:17.935573Z","end":"2026-05-29T05:04:18.168202Z","steps":["trace[1112954102] 'read index received' (duration: 231.252582ms)","trace[1112954102] 'applied index is now lower than readState.Index' (duration: 1.374295ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:04:18.168339Z","caller":"traceutil/trace.go:171","msg":"trace[1358759203] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"234.979222ms","start":"2026-05-29T05:04:17.933344Z","end":"2026-05-29T05:04:18.168323Z","steps":["trace[1358759203] 'process raft request' (duration: 234.691409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:18.168484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.947016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:04:18.168555Z","caller":"traceutil/trace.go:171","msg":"trace[1246877463] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2188; }","duration":"159.016646ms","start":"2026-05-29T05:04:18.009524Z","end":"2026-05-29T05:04:18.16854Z","steps":["trace[1246877463] 'agreement among raft nodes before linearized reading' (duration: 158.918295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:18.168566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.95487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:04:18.168613Z","caller":"traceutil/trace.go:171","msg":"trace[41921488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2188; }","duration":"233.058592ms","start":"2026-05-29T05:04:17.935539Z","end":"2026-05-29T05:04:18.168598Z","steps":["trace[41921488] 'agreement among raft nodes before linearized reading' (duration: 232.82221ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:04:58.714166Z","caller":"traceutil/trace.go:171","msg":"trace[1649019498] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"389.001616ms","start":"2026-05-29T05:04:58.325136Z","end":"2026-05-29T05:04:58.714138Z","steps":["trace[1649019498] 'process raft request' (duration: 388.84037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:58.714331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:58.325102Z","time spent":"389.146441ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:04:58.725133Z","caller":"traceutil/trace.go:171","msg":"trace[1408300930] transaction","detail":"{read_only:false; response_revision:2511; number_of_response:1; }","duration":"391.498336ms","start":"2026-05-29T05:04:58.333604Z","end":"2026-05-29T05:04:58.725102Z","steps":["trace[1408300930] 'process raft request' (duration: 391.286509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:58.725327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:58.333577Z","time spent":"391.63845ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:04:58.725547Z","caller":"traceutil/trace.go:171","msg":"trace[76925661] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"391.313661ms","start":"2026-05-29T05:04:58.334204Z","end":"2026-05-29T05:04:58.725517Z","steps":["trace[76925661] 'process raft request' (duration: 390.852055ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:04:58.725623Z","caller":"traceutil/trace.go:171","msg":"trace[22404039] linearizableReadLoop","detail":"{readStateIndex:2615; appliedIndex:2612; }","duration":"319.414178ms","start":"2026-05-29T05:04:58.40617Z","end":"2026-05-29T05:04:58.725584Z","steps":["trace[22404039] 'read index received' (duration: 307.875295ms)","trace[22404039] 'applied index is now lower than readState.Index' (duration: 11.537053ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:04:58.725713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:58.334197Z","time spent":"391.424744ms","remote":"127.0.0.1:58006","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-05-29T05:04:58.725901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.731739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-29T05:04:58.725959Z","caller":"traceutil/trace.go:171","msg":"trace[330798018] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2513; }","duration":"319.830692ms","start":"2026-05-29T05:04:58.406109Z","end":"2026-05-29T05:04:58.72594Z","steps":["trace[330798018] 'agreement among raft nodes before linearized reading' (duration: 319.681657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:58.725982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.488092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:04:58.726021Z","caller":"traceutil/trace.go:171","msg":"trace[48123459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2513; }","duration":"306.540713ms","start":"2026-05-29T05:04:58.41947Z","end":"2026-05-29T05:04:58.726011Z","steps":["trace[48123459] 'agreement among raft nodes before linearized reading' (duration: 306.378118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:58.726019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.543434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T05:04:58.726048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:58.419462Z","time spent":"306.580915ms","remote":"127.0.0.1:58006","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-05-29T05:04:58.726065Z","caller":"traceutil/trace.go:171","msg":"trace[1740197947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2513; }","duration":"315.624046ms","start":"2026-05-29T05:04:58.410429Z","end":"2026-05-29T05:04:58.726053Z","steps":["trace[1740197947] 'agreement among raft nodes before linearized reading' (duration: 315.381198ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:04:58.726068Z","caller":"traceutil/trace.go:171","msg":"trace[1258151351] transaction","detail":"{read_only:false; response_revision:2513; number_of_response:1; }","duration":"117.822206ms","start":"2026-05-29T05:04:58.608235Z","end":"2026-05-29T05:04:58.726057Z","steps":["trace[1258151351] 'process raft request' (duration: 117.167825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:58.72607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.675549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T05:04:58.726122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:58.410352Z","time spent":"315.761371ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:04:58.726135Z","caller":"traceutil/trace.go:171","msg":"trace[1820842320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2513; }","duration":"311.768492ms","start":"2026-05-29T05:04:58.414356Z","end":"2026-05-29T05:04:58.726125Z","steps":["trace[1820842320] 'agreement among raft nodes before linearized reading' (duration: 311.638128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:04:58.725992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:58.406084Z","time spent":"319.900594ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-29T05:04:58.726169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:04:58.414336Z","time spent":"311.823054ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:05:30.243167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.070146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:05:30.243248Z","caller":"traceutil/trace.go:171","msg":"trace[1111771763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2686; }","duration":"152.21669ms","start":"2026-05-29T05:05:30.091018Z","end":"2026-05-29T05:05:30.243234Z","steps":["trace[1111771763] 'agreement among raft nodes before linearized reading' (duration: 152.044145ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:05:30.243021Z","caller":"traceutil/trace.go:171","msg":"trace[2083442740] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2796; }","duration":"151.917181ms","start":"2026-05-29T05:05:30.091072Z","end":"2026-05-29T05:05:30.24299Z","steps":["trace[2083442740] 'read index received' (duration: 54.714647ms)","trace[2083442740] 'applied index is now lower than readState.Index' (duration: 97.173233ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:05:36.234435Z","caller":"traceutil/trace.go:171","msg":"trace[434908416] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"212.250737ms","start":"2026-05-29T05:05:36.02213Z","end":"2026-05-29T05:05:36.234381Z","steps":["trace[434908416] 'process raft request' (duration: 211.943109ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:01.857324Z","caller":"traceutil/trace.go:171","msg":"trace[196421144] transaction","detail":"{read_only:false; response_revision:3568; number_of_response:1; }","duration":"169.021889ms","start":"2026-05-29T05:08:01.688281Z","end":"2026-05-29T05:08:01.857303Z","steps":["trace[196421144] 'process raft request' (duration: 106.622491ms)","trace[196421144] 'compare' (duration: 62.230355ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:01.857479Z","caller":"traceutil/trace.go:171","msg":"trace[1057987688] linearizableReadLoop","detail":"{readStateIndex:3714; appliedIndex:3712; }","duration":"157.397687ms","start":"2026-05-29T05:08:01.700073Z","end":"2026-05-29T05:08:01.85747Z","steps":["trace[1057987688] 'read index received' (duration: 94.848577ms)","trace[1057987688] 'applied index is now lower than readState.Index' (duration: 62.54789ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:01.85758Z","caller":"traceutil/trace.go:171","msg":"trace[1815350318] transaction","detail":"{read_only:false; response_revision:3569; number_of_response:1; }","duration":"169.157221ms","start":"2026-05-29T05:08:01.688417Z","end":"2026-05-29T05:08:01.857574Z","steps":["trace[1815350318] 'process raft request' (duration: 168.855986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:01.857838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.645428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-05-29T05:08:01.857924Z","caller":"traceutil/trace.go:171","msg":"trace[1755145070] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3569; }","duration":"111.740449ms","start":"2026-05-29T05:08:01.746162Z","end":"2026-05-29T05:08:01.857902Z","steps":["trace[1755145070] 'agreement among raft nodes before linearized reading' (duration: 111.534306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:01.85863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.528736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:08:01.858775Z","caller":"traceutil/trace.go:171","msg":"trace[1294994585] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3569; }","duration":"158.74475ms","start":"2026-05-29T05:08:01.70001Z","end":"2026-05-29T05:08:01.858754Z","steps":["trace[1294994585] 'agreement among raft nodes before linearized reading' (duration: 157.907996ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:39.098439Z","caller":"traceutil/trace.go:171","msg":"trace[909771374] transaction","detail":"{read_only:false; response_revision:3815; number_of_response:1; }","duration":"126.822358ms","start":"2026-05-29T05:08:38.971587Z","end":"2026-05-29T05:08:39.098409Z","steps":["trace[909771374] 'process raft request' (duration: 88.593294ms)","trace[909771374] 'compare' (duration: 36.966863ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:28.973725Z","caller":"traceutil/trace.go:171","msg":"trace[1715638967] transaction","detail":"{read_only:false; response_revision:4452; number_of_response:1; }","duration":"168.135212ms","start":"2026-05-29T05:09:28.805546Z","end":"2026-05-29T05:09:28.973681Z","steps":["trace[1715638967] 'process raft request' (duration: 165.129668ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:28.977944Z","caller":"traceutil/trace.go:171","msg":"trace[320119980] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"172.06058ms","start":"2026-05-29T05:09:28.805846Z","end":"2026-05-29T05:09:28.977907Z","steps":["trace[320119980] 'process raft request' (duration: 171.284939ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:28.978425Z","caller":"traceutil/trace.go:171","msg":"trace[87061010] transaction","detail":"{read_only:false; response_revision:4454; number_of_response:1; }","duration":"172.446267ms","start":"2026-05-29T05:09:28.805963Z","end":"2026-05-29T05:09:28.97841Z","steps":["trace[87061010] 'process raft request' (duration: 171.839168ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:28.97845Z","caller":"traceutil/trace.go:171","msg":"trace[867498551] transaction","detail":"{read_only:false; response_revision:4455; number_of_response:1; }","duration":"143.26187ms","start":"2026-05-29T05:09:28.835167Z","end":"2026-05-29T05:09:28.978429Z","steps":["trace[867498551] 'process raft request' (duration: 142.724062ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:28.978316Z","caller":"traceutil/trace.go:171","msg":"trace[1472948555] linearizableReadLoop","detail":"{readStateIndex:4636; appliedIndex:4632; }","duration":"102.478361ms","start":"2026-05-29T05:09:28.875815Z","end":"2026-05-29T05:09:28.978293Z","steps":["trace[1472948555] 'read index received' (duration: 95.159972ms)","trace[1472948555] 'applied index is now lower than readState.Index' (duration: 7.317219ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:28.981243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.351073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/grafana-tls-vcrlk\" ","response":"range_response_count:1 size:2444"} {"level":"info","ts":"2026-05-29T05:09:28.981287Z","caller":"traceutil/trace.go:171","msg":"trace[1481200422] range","detail":"{range_begin:/registry/secrets/monitoring/grafana-tls-vcrlk; range_end:; response_count:1; response_revision:4456; }","duration":"105.514715ms","start":"2026-05-29T05:09:28.875759Z","end":"2026-05-29T05:09:28.981274Z","steps":["trace[1481200422] 'agreement among raft nodes before linearized reading' (duration: 103.540277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:30.262939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.156045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:09:30.263012Z","caller":"traceutil/trace.go:171","msg":"trace[956350140] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4470; }","duration":"237.300137ms","start":"2026-05-29T05:09:30.025697Z","end":"2026-05-29T05:09:30.262997Z","steps":["trace[956350140] 'count revisions from in-memory index tree' (duration: 237.033763ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:30.263562Z","caller":"traceutil/trace.go:171","msg":"trace[1404766397] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"211.973339ms","start":"2026-05-29T05:09:30.051553Z","end":"2026-05-29T05:09:30.263527Z","steps":["trace[1404766397] 'process raft request' (duration: 210.308804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:30.800032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.063039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-05-29T05:09:30.800186Z","caller":"traceutil/trace.go:171","msg":"trace[1982130535] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4511; }","duration":"112.314363ms","start":"2026-05-29T05:09:30.687842Z","end":"2026-05-29T05:09:30.800156Z","steps":["trace[1982130535] 'range keys from in-memory index tree' (duration: 111.678403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:30.80099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.786516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954238"} {"level":"info","ts":"2026-05-29T05:09:30.801078Z","caller":"traceutil/trace.go:171","msg":"trace[1985334043] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4511; }","duration":"111.954068ms","start":"2026-05-29T05:09:30.689105Z","end":"2026-05-29T05:09:30.801059Z","steps":["trace[1985334043] 'range keys from in-memory index tree' (duration: 110.503776ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:32.058265Z","caller":"traceutil/trace.go:171","msg":"trace[166219311] linearizableReadLoop","detail":"{readStateIndex:4731; appliedIndex:4730; }","duration":"122.713586ms","start":"2026-05-29T05:09:31.935518Z","end":"2026-05-29T05:09:32.058232Z","steps":["trace[166219311] 'read index received' (duration: 77.783728ms)","trace[166219311] 'applied index is now lower than readState.Index' (duration: 44.927958ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:32.058381Z","caller":"traceutil/trace.go:171","msg":"trace[688415347] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"125.130542ms","start":"2026-05-29T05:09:31.933171Z","end":"2026-05-29T05:09:32.058302Z","steps":["trace[688415347] 'process raft request' (duration: 124.918319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:32.058601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.04702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-29T05:09:32.058669Z","caller":"traceutil/trace.go:171","msg":"trace[1362409394] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4549; }","duration":"123.163523ms","start":"2026-05-29T05:09:31.935484Z","end":"2026-05-29T05:09:32.058647Z","steps":["trace[1362409394] 'agreement among raft nodes before linearized reading' (duration: 122.881779ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:34.311265Z","caller":"traceutil/trace.go:171","msg":"trace[602863258] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"156.670503ms","start":"2026-05-29T05:09:34.154558Z","end":"2026-05-29T05:09:34.311228Z","steps":["trace[602863258] 'process raft request' (duration: 95.481906ms)","trace[602863258] 'compare' (duration: 60.487577ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:35.521867Z","caller":"traceutil/trace.go:171","msg":"trace[744216007] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"191.661059ms","start":"2026-05-29T05:09:35.330178Z","end":"2026-05-29T05:09:35.521839Z","steps":["trace[744216007] 'process raft request' (duration: 191.421405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:38.664263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.121581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-29T05:09:38.664367Z","caller":"traceutil/trace.go:171","msg":"trace[953057034] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4678; }","duration":"256.262763ms","start":"2026-05-29T05:09:38.408082Z","end":"2026-05-29T05:09:38.664345Z","steps":["trace[953057034] 'range keys from in-memory index tree' (duration: 255.890717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:38.664816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.569081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:09:38.665202Z","caller":"traceutil/trace.go:171","msg":"trace[1700610722] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"145.282424ms","start":"2026-05-29T05:09:38.519895Z","end":"2026-05-29T05:09:38.665178Z","steps":["trace[1700610722] 'process raft request' (duration: 145.195583ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:38.665252Z","caller":"traceutil/trace.go:171","msg":"trace[1731284194] linearizableReadLoop","detail":"{readStateIndex:4867; appliedIndex:4866; }","duration":"190.444851ms","start":"2026-05-29T05:09:38.474797Z","end":"2026-05-29T05:09:38.665242Z","steps":["trace[1731284194] 'read index received' (duration: 33.416073ms)","trace[1731284194] 'applied index is now lower than readState.Index' (duration: 157.027688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:38.665983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.442797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-29T05:09:38.666108Z","caller":"traceutil/trace.go:171","msg":"trace[167388080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4680; }","duration":"109.606779ms","start":"2026-05-29T05:09:38.556486Z","end":"2026-05-29T05:09:38.666092Z","steps":["trace[167388080] 'agreement among raft nodes before linearized reading' (duration: 109.370004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:38.666293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.491997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-05-29T05:09:38.66633Z","caller":"traceutil/trace.go:171","msg":"trace[2127632999] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4680; }","duration":"191.586579ms","start":"2026-05-29T05:09:38.474735Z","end":"2026-05-29T05:09:38.666321Z","steps":["trace[2127632999] 'agreement among raft nodes before linearized reading' (duration: 190.537873ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:38.666007Z","caller":"traceutil/trace.go:171","msg":"trace[666988867] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"245.668387ms","start":"2026-05-29T05:09:38.420301Z","end":"2026-05-29T05:09:38.665969Z","steps":["trace[666988867] 'process raft request' (duration: 87.818126ms)","trace[666988867] 'compare' (duration: 156.154165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:38.666548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.63008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-29T05:09:38.666526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.468618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:09:38.666592Z","caller":"traceutil/trace.go:171","msg":"trace[1225049105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4680; }","duration":"107.696381ms","start":"2026-05-29T05:09:38.558882Z","end":"2026-05-29T05:09:38.666579Z","steps":["trace[1225049105] 'agreement among raft nodes before linearized reading' (duration: 107.598889ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:38.666635Z","caller":"traceutil/trace.go:171","msg":"trace[2132297929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4680; }","duration":"101.611821ms","start":"2026-05-29T05:09:38.564997Z","end":"2026-05-29T05:09:38.666609Z","steps":["trace[2132297929] 'agreement among raft nodes before linearized reading' (duration: 101.247716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:39.101754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.375413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:09:39.102244Z","caller":"traceutil/trace.go:171","msg":"trace[1835707688] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"429.083751ms","start":"2026-05-29T05:09:38.673124Z","end":"2026-05-29T05:09:39.102207Z","steps":["trace[1835707688] 'process raft request' (duration: 157.098169ms)","trace[1835707688] 'compare' (duration: 270.286317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:39.102461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.6731Z","time spent":"429.237333ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:09:39.102475Z","caller":"traceutil/trace.go:171","msg":"trace[945975181] linearizableReadLoop","detail":"{readStateIndex:4873; appliedIndex:4869; }","duration":"420.987681ms","start":"2026-05-29T05:09:38.68146Z","end":"2026-05-29T05:09:39.102447Z","steps":["trace[945975181] 'read index received' (duration: 52.975062ms)","trace[945975181] 'applied index is now lower than readState.Index' (duration: 368.010459ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:39.102806Z","caller":"traceutil/trace.go:171","msg":"trace[1106267359] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"428.817217ms","start":"2026-05-29T05:09:38.673972Z","end":"2026-05-29T05:09:39.10279Z","steps":["trace[1106267359] 'process raft request' (duration: 427.928654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:39.103014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.54966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-29T05:09:39.10307Z","caller":"traceutil/trace.go:171","msg":"trace[1320920401] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4685; }","duration":"421.621322ms","start":"2026-05-29T05:09:38.681433Z","end":"2026-05-29T05:09:39.103054Z","steps":["trace[1320920401] 'agreement among raft nodes before linearized reading' (duration: 421.474529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:39.103108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.681422Z","time spent":"421.678523ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-29T05:09:39.103309Z","caller":"traceutil/trace.go:171","msg":"trace[24595099] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"429.145853ms","start":"2026-05-29T05:09:38.674151Z","end":"2026-05-29T05:09:39.103297Z","steps":["trace[24595099] 'process raft request' (duration: 428.122187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:39.103379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.674131Z","time spent":"429.212654ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:09:39.10351Z","caller":"traceutil/trace.go:171","msg":"trace[526917848] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"427.664111ms","start":"2026-05-29T05:09:38.675836Z","end":"2026-05-29T05:09:39.1035Z","steps":["trace[526917848] 'process raft request' (duration: 426.496693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:39.103577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.675826Z","time spent":"427.716421ms","remote":"127.0.0.1:58006","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-05-29T05:09:39.10393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.071358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-29T05:09:39.103968Z","caller":"traceutil/trace.go:171","msg":"trace[662849976] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4685; }","duration":"405.146158ms","start":"2026-05-29T05:09:38.698815Z","end":"2026-05-29T05:09:39.103961Z","steps":["trace[662849976] 'agreement among raft nodes before linearized reading' (duration: 405.039677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:39.103988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.698794Z","time spent":"405.189849ms","remote":"127.0.0.1:57802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1844,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-29T05:09:39.104336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.590055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:09:39.104362Z","caller":"traceutil/trace.go:171","msg":"trace[788127233] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4685; }","duration":"365.642375ms","start":"2026-05-29T05:09:38.738713Z","end":"2026-05-29T05:09:39.104356Z","steps":["trace[788127233] 'agreement among raft nodes before linearized reading' (duration: 365.601034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:39.104382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.738698Z","time spent":"365.676615ms","remote":"127.0.0.1:58172","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":"warn","ts":"2026-05-29T05:09:39.104404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.673949Z","time spent":"428.909808ms","remote":"127.0.0.1:58006","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-05-29T05:09:40.239999Z","caller":"traceutil/trace.go:171","msg":"trace[1319676233] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"205.069079ms","start":"2026-05-29T05:09:40.034893Z","end":"2026-05-29T05:09:40.239962Z","steps":["trace[1319676233] 'process raft request' (duration: 204.749305ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:40.243521Z","caller":"traceutil/trace.go:171","msg":"trace[851114622] linearizableReadLoop","detail":"{readStateIndex:4879; appliedIndex:4877; }","duration":"152.465646ms","start":"2026-05-29T05:09:40.091024Z","end":"2026-05-29T05:09:40.243489Z","steps":["trace[851114622] 'read index received' (duration: 148.67936ms)","trace[851114622] 'applied index is now lower than readState.Index' (duration: 3.784776ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:40.243725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.683849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:09:40.243774Z","caller":"traceutil/trace.go:171","msg":"trace[567833879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4690; }","duration":"152.793481ms","start":"2026-05-29T05:09:40.090965Z","end":"2026-05-29T05:09:40.243759Z","steps":["trace[567833879] 'agreement among raft nodes before linearized reading' (duration: 152.645809ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:40.244199Z","caller":"traceutil/trace.go:171","msg":"trace[1410297083] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"201.883073ms","start":"2026-05-29T05:09:40.042295Z","end":"2026-05-29T05:09:40.244178Z","steps":["trace[1410297083] 'process raft request' (duration: 200.97977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.26416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256879391683914780,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:09:47.3994Z","caller":"traceutil/trace.go:171","msg":"trace[1769260607] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"694.40899ms","start":"2026-05-29T05:09:46.704955Z","end":"2026-05-29T05:09:47.399364Z","steps":["trace[1769260607] 'process raft request' (duration: 694.188527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.399961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:46.704887Z","time spent":"694.960158ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:09:47.437697Z","caller":"traceutil/trace.go:171","msg":"trace[1275596899] linearizableReadLoop","detail":"{readStateIndex:4984; appliedIndex:4982; }","duration":"673.583644ms","start":"2026-05-29T05:09:46.764058Z","end":"2026-05-29T05:09:47.437642Z","steps":["trace[1275596899] 'read index received' (duration: 635.921494ms)","trace[1275596899] 'applied index is now lower than readState.Index' (duration: 37.66086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:47.438211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.595574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:09:47.438249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.154364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:09:47.438369Z","caller":"traceutil/trace.go:171","msg":"trace[288042624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4794; }","duration":"674.334896ms","start":"2026-05-29T05:09:46.764007Z","end":"2026-05-29T05:09:47.438342Z","steps":["trace[288042624] 'agreement among raft nodes before linearized reading' (duration: 674.006521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.438438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:46.76399Z","time spent":"674.427817ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:09:47.438629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.816729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T05:09:47.438733Z","caller":"traceutil/trace.go:171","msg":"trace[1123967213] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"728.891594ms","start":"2026-05-29T05:09:46.709828Z","end":"2026-05-29T05:09:47.438719Z","steps":["trace[1123967213] 'process raft request' (duration: 727.665806ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.438814Z","caller":"traceutil/trace.go:171","msg":"trace[1655500587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4794; }","duration":"288.987842ms","start":"2026-05-29T05:09:47.149737Z","end":"2026-05-29T05:09:47.438724Z","steps":["trace[1655500587] 'agreement among raft nodes before linearized reading' (duration: 288.487915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.438643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.761972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-29T05:09:47.439137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.990804ms","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":"info","ts":"2026-05-29T05:09:47.439202Z","caller":"traceutil/trace.go:171","msg":"trace[1191460436] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4794; }","duration":"255.499293ms","start":"2026-05-29T05:09:47.183628Z","end":"2026-05-29T05:09:47.439127Z","steps":["trace[1191460436] 'agreement among raft nodes before linearized reading' (duration: 254.548759ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.439242Z","caller":"traceutil/trace.go:171","msg":"trace[1936220316] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:4794; }","duration":"207.173037ms","start":"2026-05-29T05:09:47.232041Z","end":"2026-05-29T05:09:47.439214Z","steps":["trace[1936220316] 'agreement among raft nodes before linearized reading' (duration: 206.935604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.439274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.934938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:09:47.439311Z","caller":"traceutil/trace.go:171","msg":"trace[881571296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4794; }","duration":"297.001719ms","start":"2026-05-29T05:09:47.142299Z","end":"2026-05-29T05:09:47.439301Z","steps":["trace[881571296] 'agreement among raft nodes before linearized reading' (duration: 296.900078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.438839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:46.709793Z","time spent":"728.970165ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:09:47.439574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.638241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-29T05:09:47.43963Z","caller":"traceutil/trace.go:171","msg":"trace[1402888068] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4794; }","duration":"252.727011ms","start":"2026-05-29T05:09:47.186873Z","end":"2026-05-29T05:09:47.4396Z","steps":["trace[1402888068] 'agreement among raft nodes before linearized reading' (duration: 252.6279ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.43888Z","caller":"traceutil/trace.go:171","msg":"trace[986193424] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"537.808339ms","start":"2026-05-29T05:09:46.901063Z","end":"2026-05-29T05:09:47.438871Z","steps":["trace[986193424] 'process raft request' (duration: 536.514041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.439759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:46.901037Z","time spent":"538.662491ms","remote":"127.0.0.1:57786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:09:47.439808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.324263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3461d5ca-15c4-4d15-a121-10c3c11ccb22\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-29T05:09:47.439903Z","caller":"traceutil/trace.go:171","msg":"trace[2013501647] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3461d5ca-15c4-4d15-a121-10c3c11ccb22; range_end:; response_count:1; response_revision:4794; }","duration":"310.451785ms","start":"2026-05-29T05:09:47.129421Z","end":"2026-05-29T05:09:47.439873Z","steps":["trace[2013501647] 'agreement among raft nodes before linearized reading' (duration: 310.263982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.439969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.093058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-29T05:09:47.439974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:47.129401Z","time spent":"310.560548ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3461d5ca-15c4-4d15-a121-10c3c11ccb22\" "} {"level":"info","ts":"2026-05-29T05:09:47.440009Z","caller":"traceutil/trace.go:171","msg":"trace[1719145777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4794; }","duration":"266.156228ms","start":"2026-05-29T05:09:47.173843Z","end":"2026-05-29T05:09:47.44Z","steps":["trace[1719145777] 'agreement among raft nodes before linearized reading' (duration: 266.058267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.43998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.956387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:09:47.440171Z","caller":"traceutil/trace.go:171","msg":"trace[2094611122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4794; }","duration":"260.373794ms","start":"2026-05-29T05:09:47.179783Z","end":"2026-05-29T05:09:47.440157Z","steps":["trace[2094611122] 'agreement among raft nodes before linearized reading' (duration: 259.254427ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.438296Z","caller":"traceutil/trace.go:171","msg":"trace[2000884353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4794; }","duration":"346.736286ms","start":"2026-05-29T05:09:47.091527Z","end":"2026-05-29T05:09:47.438263Z","steps":["trace[2000884353] 'agreement among raft nodes before linearized reading' (duration: 346.627565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.440558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:47.091493Z","time spent":"349.04991ms","remote":"127.0.0.1:57716","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-05-29T05:09:47.587014Z","caller":"traceutil/trace.go:171","msg":"trace[63623636] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"140.930889ms","start":"2026-05-29T05:09:47.446032Z","end":"2026-05-29T05:09:47.586963Z","steps":["trace[63623636] 'process raft request' (duration: 140.584104ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.587186Z","caller":"traceutil/trace.go:171","msg":"trace[452635566] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"140.152098ms","start":"2026-05-29T05:09:47.446996Z","end":"2026-05-29T05:09:47.587148Z","steps":["trace[452635566] 'process raft request' (duration: 140.019946ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.587348Z","caller":"traceutil/trace.go:171","msg":"trace[1266445385] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"140.393161ms","start":"2026-05-29T05:09:47.446698Z","end":"2026-05-29T05:09:47.587091Z","steps":["trace[1266445385] 'process raft request' (duration: 140.142367ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.587403Z","caller":"traceutil/trace.go:171","msg":"trace[2059547802] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"139.932675ms","start":"2026-05-29T05:09:47.447446Z","end":"2026-05-29T05:09:47.587379Z","steps":["trace[2059547802] 'process raft request' (duration: 139.638231ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.587437Z","caller":"traceutil/trace.go:171","msg":"trace[2142502859] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"139.023201ms","start":"2026-05-29T05:09:47.448394Z","end":"2026-05-29T05:09:47.587417Z","steps":["trace[2142502859] 'process raft request' (duration: 138.857178ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.587492Z","caller":"traceutil/trace.go:171","msg":"trace[307945060] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"140.770286ms","start":"2026-05-29T05:09:47.44671Z","end":"2026-05-29T05:09:47.58748Z","steps":["trace[307945060] 'process raft request' (duration: 140.213659ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.824294Z","caller":"traceutil/trace.go:171","msg":"trace[1043694634] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"114.905588ms","start":"2026-05-29T05:09:47.709347Z","end":"2026-05-29T05:09:47.824253Z","steps":["trace[1043694634] 'process raft request' (duration: 68.999937ms)","trace[1043694634] 'compare' (duration: 45.537446ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:47.825231Z","caller":"traceutil/trace.go:171","msg":"trace[1440932210] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"114.745336ms","start":"2026-05-29T05:09:47.709774Z","end":"2026-05-29T05:09:47.824519Z","steps":["trace[1440932210] 'process raft request' (duration: 114.31606ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.827075Z","caller":"traceutil/trace.go:171","msg":"trace[1329168670] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"117.185462ms","start":"2026-05-29T05:09:47.709864Z","end":"2026-05-29T05:09:47.827049Z","steps":["trace[1329168670] 'process raft request' (duration: 114.573934ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.828138Z","caller":"traceutil/trace.go:171","msg":"trace[1745627089] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"117.690249ms","start":"2026-05-29T05:09:47.710412Z","end":"2026-05-29T05:09:47.828102Z","steps":["trace[1745627089] 'process raft request' (duration: 117.486296ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:47.828296Z","caller":"traceutil/trace.go:171","msg":"trace[47322783] linearizableReadLoop","detail":"{readStateIndex:4999; appliedIndex:4995; }","duration":"100.715722ms","start":"2026-05-29T05:09:47.727556Z","end":"2026-05-29T05:09:47.828271Z","steps":["trace[47322783] 'read index received' (duration: 50.830873ms)","trace[47322783] 'applied index is now lower than readState.Index' (duration: 49.883609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:47.828596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.011098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-vwnv4\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-05-29T05:09:47.828639Z","caller":"traceutil/trace.go:171","msg":"trace[603578894] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-vwnv4; range_end:; response_count:1; response_revision:4810; }","duration":"119.323763ms","start":"2026-05-29T05:09:47.709301Z","end":"2026-05-29T05:09:47.828625Z","steps":["trace[603578894] 'agreement among raft nodes before linearized reading' (duration: 119.074179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:47.828825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.891526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-05-29T05:09:47.828854Z","caller":"traceutil/trace.go:171","msg":"trace[1742290708] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4810; }","duration":"118.969768ms","start":"2026-05-29T05:09:47.709877Z","end":"2026-05-29T05:09:47.828847Z","steps":["trace[1742290708] 'agreement among raft nodes before linearized reading' (duration: 118.885067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:48.399585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.062514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:09:48.399775Z","caller":"traceutil/trace.go:171","msg":"trace[77334892] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"565.834602ms","start":"2026-05-29T05:09:47.83391Z","end":"2026-05-29T05:09:48.399744Z","steps":["trace[77334892] 'process raft request' (duration: 149.505805ms)","trace[77334892] 'compare' (duration: 415.718059ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:48.400106Z","caller":"traceutil/trace.go:171","msg":"trace[1540365722] linearizableReadLoop","detail":"{readStateIndex:5001; appliedIndex:5000; }","duration":"565.963174ms","start":"2026-05-29T05:09:47.834122Z","end":"2026-05-29T05:09:48.400085Z","steps":["trace[1540365722] 'read index received' (duration: 149.316622ms)","trace[1540365722] 'applied index is now lower than readState.Index' (duration: 416.643812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:48.400388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:47.833882Z","time spent":"565.991514ms","remote":"127.0.0.1:57786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":938,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:09:48.400512Z","caller":"traceutil/trace.go:171","msg":"trace[1853229306] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"562.988781ms","start":"2026-05-29T05:09:47.837507Z","end":"2026-05-29T05:09:48.400496Z","steps":["trace[1853229306] 'process raft request' (duration: 562.23873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:48.400604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:47.837495Z","time spent":"563.056372ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4933,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:09:48.401224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.070701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18b3f11d39db6f0c\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-05-29T05:09:48.401505Z","caller":"traceutil/trace.go:171","msg":"trace[350783913] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18b3f11d39db6f0c; range_end:; response_count:1; response_revision:4812; }","duration":"567.402226ms","start":"2026-05-29T05:09:47.834076Z","end":"2026-05-29T05:09:48.401478Z","steps":["trace[350783913] 'agreement among raft nodes before linearized reading' (duration: 566.935039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:48.402323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:47.834062Z","time spent":"568.247658ms","remote":"127.0.0.1:57786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":763,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18b3f11d39db6f0c\" "} {"level":"info","ts":"2026-05-29T05:09:48.43976Z","caller":"traceutil/trace.go:171","msg":"trace[829942561] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"219.24826ms","start":"2026-05-29T05:09:48.220471Z","end":"2026-05-29T05:09:48.439719Z","steps":["trace[829942561] 'process raft request' (duration: 219.027057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:48.439947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.459102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-05-29T05:09:48.440016Z","caller":"traceutil/trace.go:171","msg":"trace[1555775304] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4813; }","duration":"605.584894ms","start":"2026-05-29T05:09:47.834417Z","end":"2026-05-29T05:09:48.440001Z","steps":["trace[1555775304] 'agreement among raft nodes before linearized reading' (duration: 605.251788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:48.440145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:47.834413Z","time spent":"605.640275ms","remote":"127.0.0.1:58188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9660,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-05-29T05:09:48.440286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.220814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-29T05:09:48.44038Z","caller":"traceutil/trace.go:171","msg":"trace[1398214436] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4813; }","duration":"222.363016ms","start":"2026-05-29T05:09:48.217986Z","end":"2026-05-29T05:09:48.440349Z","steps":["trace[1398214436] 'agreement among raft nodes before linearized reading' (duration: 222.176053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:48.853324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.970623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:09:48.853558Z","caller":"traceutil/trace.go:171","msg":"trace[863240390] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"295.872418ms","start":"2026-05-29T05:09:48.557658Z","end":"2026-05-29T05:09:48.853531Z","steps":["trace[863240390] 'process raft request' (duration: 105.57912ms)","trace[863240390] 'compare' (duration: 189.793291ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:48.853806Z","caller":"traceutil/trace.go:171","msg":"trace[1948864453] linearizableReadLoop","detail":"{readStateIndex:5010; appliedIndex:5009; }","duration":"250.462826ms","start":"2026-05-29T05:09:48.603329Z","end":"2026-05-29T05:09:48.853792Z","steps":["trace[1948864453] 'read index received' (duration: 59.823113ms)","trace[1948864453] 'applied index is now lower than readState.Index' (duration: 190.638403ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:48.854413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.040259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:09:48.854778Z","caller":"traceutil/trace.go:171","msg":"trace[1524655082] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4821; }","duration":"226.440715ms","start":"2026-05-29T05:09:48.628315Z","end":"2026-05-29T05:09:48.854755Z","steps":["trace[1524655082] 'agreement among raft nodes before linearized reading' (duration: 225.981898ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:48.854458Z","caller":"traceutil/trace.go:171","msg":"trace[970172016] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"161.571667ms","start":"2026-05-29T05:09:48.692809Z","end":"2026-05-29T05:09:48.85438Z","steps":["trace[970172016] 'process raft request' (duration: 160.651274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:48.854646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.267598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3461d5ca-15c4-4d15-a121-10c3c11ccb22\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-05-29T05:09:48.855231Z","caller":"traceutil/trace.go:171","msg":"trace[695649534] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3461d5ca-15c4-4d15-a121-10c3c11ccb22; range_end:; response_count:1; response_revision:4821; }","duration":"251.918557ms","start":"2026-05-29T05:09:48.603294Z","end":"2026-05-29T05:09:48.855213Z","steps":["trace[695649534] 'agreement among raft nodes before linearized reading' (duration: 250.621628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:01.860281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256879391683915179,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:10:02.121397Z","caller":"traceutil/trace.go:171","msg":"trace[1792632899] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"817.528578ms","start":"2026-05-29T05:10:01.30381Z","end":"2026-05-29T05:10:02.121338Z","steps":["trace[1792632899] 'process raft request' (duration: 817.312855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.121613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.303782Z","time spent":"817.71242ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:10:02.227166Z","caller":"traceutil/trace.go:171","msg":"trace[548354667] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"761.165386ms","start":"2026-05-29T05:10:01.465966Z","end":"2026-05-29T05:10:02.227131Z","steps":["trace[548354667] 'process raft request' (duration: 725.476772ms)","trace[548354667] 'compare' (duration: 35.348449ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:02.227254Z","caller":"traceutil/trace.go:171","msg":"trace[1534658732] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5164; }","duration":"867.258922ms","start":"2026-05-29T05:10:01.359958Z","end":"2026-05-29T05:10:02.227217Z","steps":["trace[1534658732] 'read index received' (duration: 761.213746ms)","trace[1534658732] 'applied index is now lower than readState.Index' (duration: 106.043156ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:02.227409Z","caller":"traceutil/trace.go:171","msg":"trace[1180083134] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"729.58565ms","start":"2026-05-29T05:10:01.497808Z","end":"2026-05-29T05:10:02.227394Z","steps":["trace[1180083134] 'process raft request' (duration: 729.191225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.227552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.465938Z","time spent":"761.323388ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:10:02.2276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.497794Z","time spent":"729.659181ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:10:02.227659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.671658ms","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-05-29T05:10:02.227774Z","caller":"traceutil/trace.go:171","msg":"trace[1798832032] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4970; }","duration":"867.84042ms","start":"2026-05-29T05:10:01.359909Z","end":"2026-05-29T05:10:02.22775Z","steps":["trace[1798832032] 'agreement among raft nodes before linearized reading' (duration: 867.522626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.227833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.359896Z","time spent":"867.926791ms","remote":"127.0.0.1:57874","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":"info","ts":"2026-05-29T05:10:02.235251Z","caller":"traceutil/trace.go:171","msg":"trace[528338006] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"106.492202ms","start":"2026-05-29T05:10:02.128736Z","end":"2026-05-29T05:10:02.235228Z","steps":["trace[528338006] 'process raft request' (duration: 106.265239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.235356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.755348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:02.235402Z","caller":"traceutil/trace.go:171","msg":"trace[313444723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4974; }","duration":"143.849658ms","start":"2026-05-29T05:10:02.091541Z","end":"2026-05-29T05:10:02.23539Z","steps":["trace[313444723] 'agreement among raft nodes before linearized reading' (duration: 143.751837ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:02.235523Z","caller":"traceutil/trace.go:171","msg":"trace[1868550470] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"106.446831ms","start":"2026-05-29T05:10:02.129056Z","end":"2026-05-29T05:10:02.235503Z","steps":["trace[1868550470] 'process raft request' (duration: 106.121956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.235625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.822961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-29T05:10:02.235737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.892651ms","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-05-29T05:10:02.235743Z","caller":"traceutil/trace.go:171","msg":"trace[1769501192] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4974; }","duration":"485.989173ms","start":"2026-05-29T05:10:01.749737Z","end":"2026-05-29T05:10:02.235726Z","steps":["trace[1769501192] 'agreement among raft nodes before linearized reading' (duration: 485.532347ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:02.235774Z","caller":"traceutil/trace.go:171","msg":"trace[1381062140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4974; }","duration":"644.996343ms","start":"2026-05-29T05:10:01.590768Z","end":"2026-05-29T05:10:02.235764Z","steps":["trace[1381062140] 'agreement among raft nodes before linearized reading' (duration: 644.78293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.235799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.590745Z","time spent":"645.048493ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:10:02.235817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.749726Z","time spent":"486.054044ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-29T05:10:02.235911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.319698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:10:02.235939Z","caller":"traceutil/trace.go:171","msg":"trace[1796448133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4974; }","duration":"461.405159ms","start":"2026-05-29T05:10:01.774525Z","end":"2026-05-29T05:10:02.23593Z","steps":["trace[1796448133] 'agreement among raft nodes before linearized reading' (duration: 461.346958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.235965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.774511Z","time spent":"461.449639ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:10:02.235969Z","caller":"traceutil/trace.go:171","msg":"trace[1057337657] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"107.223642ms","start":"2026-05-29T05:10:02.128732Z","end":"2026-05-29T05:10:02.235956Z","steps":["trace[1057337657] 'process raft request' (duration: 106.36622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.23585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.088699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T05:10:02.236028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.50634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:10:02.236043Z","caller":"traceutil/trace.go:171","msg":"trace[440224003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4974; }","duration":"471.306781ms","start":"2026-05-29T05:10:01.764731Z","end":"2026-05-29T05:10:02.236038Z","steps":["trace[440224003] 'agreement among raft nodes before linearized reading' (duration: 471.034238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.236063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.76472Z","time spent":"471.338663ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:10:02.236076Z","caller":"traceutil/trace.go:171","msg":"trace[760666851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4974; }","duration":"561.582862ms","start":"2026-05-29T05:10:01.674464Z","end":"2026-05-29T05:10:02.236047Z","steps":["trace[760666851] 'agreement among raft nodes before linearized reading' (duration: 561.4456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.236078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.691168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-29T05:10:02.236125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.674452Z","time spent":"561.665443ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T05:10:02.236137Z","caller":"traceutil/trace.go:171","msg":"trace[1618738517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4974; }","duration":"471.788289ms","start":"2026-05-29T05:10:01.764336Z","end":"2026-05-29T05:10:02.236124Z","steps":["trace[1618738517] 'agreement among raft nodes before linearized reading' (duration: 471.517485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.236152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.133709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:02.236178Z","caller":"traceutil/trace.go:171","msg":"trace[1604159000] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4974; }","duration":"562.163619ms","start":"2026-05-29T05:10:01.674008Z","end":"2026-05-29T05:10:02.236171Z","steps":["trace[1604159000] 'agreement among raft nodes before linearized reading' (duration: 562.115019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.236181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.764324Z","time spent":"471.848939ms","remote":"127.0.0.1:58006","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-05-29T05:10:02.236197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.673991Z","time spent":"562.20135ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:10:02.236269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.899041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:10:02.236376Z","caller":"traceutil/trace.go:171","msg":"trace[75584263] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4974; }","duration":"570.046463ms","start":"2026-05-29T05:10:01.666298Z","end":"2026-05-29T05:10:02.236344Z","steps":["trace[75584263] 'agreement among raft nodes before linearized reading' (duration: 569.713298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.236426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.666262Z","time spent":"570.154725ms","remote":"127.0.0.1:47400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":22,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:10:02.236488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.688941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:10:02.236297Z","caller":"traceutil/trace.go:171","msg":"trace[1808907126] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"106.683065ms","start":"2026-05-29T05:10:02.128808Z","end":"2026-05-29T05:10:02.235491Z","steps":["trace[1808907126] 'process raft request' (duration: 106.33766ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:02.236521Z","caller":"traceutil/trace.go:171","msg":"trace[702088465] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4974; }","duration":"507.766036ms","start":"2026-05-29T05:10:01.728746Z","end":"2026-05-29T05:10:02.236512Z","steps":["trace[702088465] 'agreement among raft nodes before linearized reading' (duration: 506.661191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:02.236665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:01.728738Z","time spent":"507.91897ms","remote":"127.0.0.1:39440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":9,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:10:37.54327Z","caller":"traceutil/trace.go:171","msg":"trace[450482364] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"147.893463ms","start":"2026-05-29T05:10:37.395335Z","end":"2026-05-29T05:10:37.543229Z","steps":["trace[450482364] 'process raft request' (duration: 91.059541ms)","trace[450482364] 'compare' (duration: 56.375126ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:37.545539Z","caller":"traceutil/trace.go:171","msg":"trace[1497146983] linearizableReadLoop","detail":"{readStateIndex:5420; appliedIndex:5417; }","duration":"149.653817ms","start":"2026-05-29T05:10:37.395871Z","end":"2026-05-29T05:10:37.545524Z","steps":["trace[1497146983] 'read index received' (duration: 90.428471ms)","trace[1497146983] 'applied index is now lower than readState.Index' (duration: 59.224066ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:37.545661Z","caller":"traceutil/trace.go:171","msg":"trace[861301112] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"149.539376ms","start":"2026-05-29T05:10:37.39611Z","end":"2026-05-29T05:10:37.545649Z","steps":["trace[861301112] 'process raft request' (duration: 149.155411ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.545618Z","caller":"traceutil/trace.go:171","msg":"trace[923532043] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"149.509276ms","start":"2026-05-29T05:10:37.396074Z","end":"2026-05-29T05:10:37.545583Z","steps":["trace[923532043] 'process raft request' (duration: 149.149661ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.545865Z","caller":"traceutil/trace.go:171","msg":"trace[285299182] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"149.706389ms","start":"2026-05-29T05:10:37.396149Z","end":"2026-05-29T05:10:37.545855Z","steps":["trace[285299182] 'process raft request' (duration: 149.148901ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.546008Z","caller":"traceutil/trace.go:171","msg":"trace[2016606747] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"149.77997ms","start":"2026-05-29T05:10:37.396219Z","end":"2026-05-29T05:10:37.545998Z","steps":["trace[2016606747] 'process raft request' (duration: 149.111301ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.546092Z","caller":"traceutil/trace.go:171","msg":"trace[1543184496] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"149.866441ms","start":"2026-05-29T05:10:37.396219Z","end":"2026-05-29T05:10:37.546085Z","steps":["trace[1543184496] 'process raft request' (duration: 149.138921ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.54618Z","caller":"traceutil/trace.go:171","msg":"trace[308000917] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"149.89489ms","start":"2026-05-29T05:10:37.396277Z","end":"2026-05-29T05:10:37.546171Z","steps":["trace[308000917] 'process raft request' (duration: 149.109289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:37.546512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.615521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-29T05:10:37.546557Z","caller":"traceutil/trace.go:171","msg":"trace[491405997] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5224; }","duration":"150.728942ms","start":"2026-05-29T05:10:37.395817Z","end":"2026-05-29T05:10:37.546546Z","steps":["trace[491405997] 'agreement among raft nodes before linearized reading' (duration: 150.5435ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.546573Z","caller":"traceutil/trace.go:171","msg":"trace[758360286] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"150.861464ms","start":"2026-05-29T05:10:37.395696Z","end":"2026-05-29T05:10:37.546557Z","steps":["trace[758360286] 'process raft request' (duration: 149.261763ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.547013Z","caller":"traceutil/trace.go:171","msg":"trace[1454716777] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"151.167408ms","start":"2026-05-29T05:10:37.395798Z","end":"2026-05-29T05:10:37.546966Z","steps":["trace[1454716777] 'process raft request' (duration: 149.285082ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.547311Z","caller":"traceutil/trace.go:171","msg":"trace[258649696] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"151.379661ms","start":"2026-05-29T05:10:37.39592Z","end":"2026-05-29T05:10:37.547299Z","steps":["trace[258649696] 'process raft request' (duration: 149.200742ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.547953Z","caller":"traceutil/trace.go:171","msg":"trace[324199213] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"151.95798ms","start":"2026-05-29T05:10:37.395981Z","end":"2026-05-29T05:10:37.547939Z","steps":["trace[324199213] 'process raft request' (duration: 149.168031ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:37.548237Z","caller":"traceutil/trace.go:171","msg":"trace[1145720732] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"152.137433ms","start":"2026-05-29T05:10:37.396083Z","end":"2026-05-29T05:10:37.548221Z","steps":["trace[1145720732] 'process raft request' (duration: 149.100201ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:40.74203Z","caller":"traceutil/trace.go:171","msg":"trace[1027705840] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"183.282431ms","start":"2026-05-29T05:10:40.558712Z","end":"2026-05-29T05:10:40.741995Z","steps":["trace[1027705840] 'process raft request' (duration: 183.055718ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:40.748195Z","caller":"traceutil/trace.go:171","msg":"trace[887646889] linearizableReadLoop","detail":"{readStateIndex:5527; appliedIndex:5523; }","duration":"171.465676ms","start":"2026-05-29T05:10:40.576693Z","end":"2026-05-29T05:10:40.748159Z","steps":["trace[887646889] 'read index received' (duration: 165.137339ms)","trace[887646889] 'applied index is now lower than readState.Index' (duration: 6.326437ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:40.748688Z","caller":"traceutil/trace.go:171","msg":"trace[1187616050] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"189.234272ms","start":"2026-05-29T05:10:40.559432Z","end":"2026-05-29T05:10:40.748666Z","steps":["trace[1187616050] 'process raft request' (duration: 188.432681ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:40.748991Z","caller":"traceutil/trace.go:171","msg":"trace[951975669] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"189.509006ms","start":"2026-05-29T05:10:40.55947Z","end":"2026-05-29T05:10:40.748979Z","steps":["trace[951975669] 'process raft request' (duration: 188.576584ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:40.749154Z","caller":"traceutil/trace.go:171","msg":"trace[207450950] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"187.916895ms","start":"2026-05-29T05:10:40.561226Z","end":"2026-05-29T05:10:40.749143Z","steps":["trace[207450950] 'process raft request' (duration: 186.86367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:40.749363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.644042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:40.749412Z","caller":"traceutil/trace.go:171","msg":"trace[1637095586] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5320; }","duration":"172.747993ms","start":"2026-05-29T05:10:40.576651Z","end":"2026-05-29T05:10:40.749399Z","steps":["trace[1637095586] 'agreement among raft nodes before linearized reading' (duration: 172.642652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:40.749614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.186937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:40.749653Z","caller":"traceutil/trace.go:171","msg":"trace[1668256231] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5320; }","duration":"172.293738ms","start":"2026-05-29T05:10:40.577348Z","end":"2026-05-29T05:10:40.749641Z","steps":["trace[1668256231] 'agreement among raft nodes before linearized reading' (duration: 172.239358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:40.750271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.886052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-29T05:10:40.750319Z","caller":"traceutil/trace.go:171","msg":"trace[1753633527] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5320; }","duration":"135.974172ms","start":"2026-05-29T05:10:40.61433Z","end":"2026-05-29T05:10:40.750304Z","steps":["trace[1753633527] 'agreement among raft nodes before linearized reading' (duration: 135.857371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:40.750503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.40538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-05-29T05:10:40.750543Z","caller":"traceutil/trace.go:171","msg":"trace[1384109670] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5320; }","duration":"167.45719ms","start":"2026-05-29T05:10:40.583075Z","end":"2026-05-29T05:10:40.750533Z","steps":["trace[1384109670] 'agreement among raft nodes before linearized reading' (duration: 167.354339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:50.905188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.213046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:10:50.905297Z","caller":"traceutil/trace.go:171","msg":"trace[241023616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5376; }","duration":"103.371318ms","start":"2026-05-29T05:10:50.801904Z","end":"2026-05-29T05:10:50.905275Z","steps":["trace[241023616] 'agreement among raft nodes before linearized reading' (duration: 103.088585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:50.905321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.023806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-29T05:10:50.905419Z","caller":"traceutil/trace.go:171","msg":"trace[296884848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5376; }","duration":"101.152157ms","start":"2026-05-29T05:10:50.804246Z","end":"2026-05-29T05:10:50.905398Z","steps":["trace[296884848] 'agreement among raft nodes before linearized reading' (duration: 100.948014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:51.107115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.66914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-05-29T05:10:51.107205Z","caller":"traceutil/trace.go:171","msg":"trace[2052588344] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5380; }","duration":"129.786951ms","start":"2026-05-29T05:10:50.977397Z","end":"2026-05-29T05:10:51.107184Z","steps":["trace[2052588344] 'range keys from in-memory index tree' (duration: 129.412716ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:11:01.844712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-05-29T05:11:01.970422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"124.420336ms","hash":3184390784,"current-db-size-bytes":53186560,"current-db-size":"53 MB","current-db-size-in-use-bytes":28520448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-29T05:11:01.970503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3184390784,"revision":2855,"compact-revision":-1} {"level":"info","ts":"2026-05-29T05:11:05.191808Z","caller":"traceutil/trace.go:171","msg":"trace[188970537] linearizableReadLoop","detail":"{readStateIndex:5691; appliedIndex:5689; }","duration":"112.221064ms","start":"2026-05-29T05:11:05.079556Z","end":"2026-05-29T05:11:05.191777Z","steps":["trace[188970537] 'read index received' (duration: 35.252801ms)","trace[188970537] 'applied index is now lower than readState.Index' (duration: 76.966353ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:11:05.192181Z","caller":"traceutil/trace.go:171","msg":"trace[482548029] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"112.67505ms","start":"2026-05-29T05:11:05.079491Z","end":"2026-05-29T05:11:05.192166Z","steps":["trace[482548029] 'process raft request' (duration: 112.070552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:11:05.192616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.041986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-29T05:11:05.192672Z","caller":"traceutil/trace.go:171","msg":"trace[1210083833] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5478; }","duration":"113.123707ms","start":"2026-05-29T05:11:05.079534Z","end":"2026-05-29T05:11:05.192658Z","steps":["trace[1210083833] 'agreement among raft nodes before linearized reading' (duration: 112.917844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:11:05.192862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.714649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:11:05.192943Z","caller":"traceutil/trace.go:171","msg":"trace[741101978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5478; }","duration":"103.789439ms","start":"2026-05-29T05:11:05.089113Z","end":"2026-05-29T05:11:05.192903Z","steps":["trace[741101978] 'agreement among raft nodes before linearized reading' (duration: 103.714168ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:11:05.330237Z","caller":"traceutil/trace.go:171","msg":"trace[448538894] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"131.361317ms","start":"2026-05-29T05:11:05.198852Z","end":"2026-05-29T05:11:05.330214Z","steps":["trace[448538894] 'process raft request' (duration: 131.267016ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:11:05.330281Z","caller":"traceutil/trace.go:171","msg":"trace[467079340] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"131.398467ms","start":"2026-05-29T05:11:05.198834Z","end":"2026-05-29T05:11:05.330232Z","steps":["trace[467079340] 'process raft request' (duration: 128.458577ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:11:05.330596Z","caller":"traceutil/trace.go:171","msg":"trace[445882894] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"131.62237ms","start":"2026-05-29T05:11:05.198942Z","end":"2026-05-29T05:11:05.330564Z","steps":["trace[445882894] 'process raft request' (duration: 131.213184ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:13:49.322005Z","caller":"traceutil/trace.go:171","msg":"trace[1183544714] transaction","detail":"{read_only:false; response_revision:6615; number_of_response:1; }","duration":"100.473435ms","start":"2026-05-29T05:13:49.221493Z","end":"2026-05-29T05:13:49.321966Z","steps":["trace[1183544714] 'process raft request' (duration: 54.079407ms)","trace[1183544714] 'compare' (duration: 45.81536ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:13:49.322052Z","caller":"traceutil/trace.go:171","msg":"trace[1640750996] transaction","detail":"{read_only:false; response_revision:6616; number_of_response:1; }","duration":"100.072613ms","start":"2026-05-29T05:13:49.221928Z","end":"2026-05-29T05:13:49.322001Z","steps":["trace[1640750996] 'process raft request' (duration: 99.66288ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:13:51.479607Z","caller":"traceutil/trace.go:171","msg":"trace[1061239098] transaction","detail":"{read_only:false; response_revision:6625; number_of_response:1; }","duration":"151.627131ms","start":"2026-05-29T05:13:51.327945Z","end":"2026-05-29T05:13:51.479572Z","steps":["trace[1061239098] 'process raft request' (duration: 151.409925ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:13:51.512977Z","caller":"traceutil/trace.go:171","msg":"trace[731612081] transaction","detail":"{read_only:false; response_revision:6626; number_of_response:1; }","duration":"183.612969ms","start":"2026-05-29T05:13:51.329251Z","end":"2026-05-29T05:13:51.512864Z","steps":["trace[731612081] 'process raft request' (duration: 183.260808ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:13:52.351162Z","caller":"traceutil/trace.go:171","msg":"trace[1597782166] transaction","detail":"{read_only:false; response_revision:6630; number_of_response:1; }","duration":"130.013195ms","start":"2026-05-29T05:13:52.221104Z","end":"2026-05-29T05:13:52.351117Z","steps":["trace[1597782166] 'process raft request' (duration: 129.8395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:14:12.141036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.857775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107267"} {"level":"info","ts":"2026-05-29T05:14:12.141147Z","caller":"traceutil/trace.go:171","msg":"trace[1703923864] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6786; }","duration":"191.054271ms","start":"2026-05-29T05:14:11.950072Z","end":"2026-05-29T05:14:12.141127Z","steps":["trace[1703923864] 'range keys from in-memory index tree' (duration: 190.557846ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:14:12.141735Z","caller":"traceutil/trace.go:171","msg":"trace[844793752] transaction","detail":"{read_only:false; response_revision:6787; number_of_response:1; }","duration":"160.00314ms","start":"2026-05-29T05:14:11.981693Z","end":"2026-05-29T05:14:12.141696Z","steps":["trace[844793752] 'process raft request' (duration: 143.071178ms)","trace[844793752] 'compare' (duration: 16.028705ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:14:12.444016Z","caller":"traceutil/trace.go:171","msg":"trace[962979841] transaction","detail":"{read_only:false; response_revision:6789; number_of_response:1; }","duration":"117.16555ms","start":"2026-05-29T05:14:12.326807Z","end":"2026-05-29T05:14:12.443973Z","steps":["trace[962979841] 'process raft request' (duration: 80.189005ms)","trace[962979841] 'compare' (duration: 36.742318ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:15:50.339764Z","caller":"traceutil/trace.go:171","msg":"trace[653042183] transaction","detail":"{read_only:false; response_revision:7309; number_of_response:1; }","duration":"133.340723ms","start":"2026-05-29T05:15:50.206368Z","end":"2026-05-29T05:15:50.339709Z","steps":["trace[653042183] 'process raft request' (duration: 133.162478ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:16:01.856873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5457} {"level":"info","ts":"2026-05-29T05:16:01.945871Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5457,"took":"86.216501ms","hash":4194856074,"current-db-size-bytes":53186560,"current-db-size":"53 MB","current-db-size-in-use-bytes":18317312,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-29T05:16:01.945963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4194856074,"revision":5457,"compact-revision":2855}