{"level":"warn","ts":"2026-05-29T11:22:44.594009Z","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-29T11:22:44.594105Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.54:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.54:2380","--initial-cluster=instance=https://199.19.213.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.54: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-29T11:22:44.594166Z","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-29T11:22:44.594173Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-05-29T11:22:44.59419Z","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-29T11:22:44.59485Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"]} {"level":"info","ts":"2026-05-29T11:22:44.594925Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.54: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-29T11:22:44.600717Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.611733ms"} {"level":"info","ts":"2026-05-29T11:22:44.608769Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"193b4dda7792855b","cluster-id":"dd6702a2f79ce697"} {"level":"info","ts":"2026-05-29T11:22:44.608856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T11:22:44.608882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 0"} {"level":"info","ts":"2026-05-29T11:22:44.608899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 193b4dda7792855b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T11:22:44.608911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 1"} {"level":"info","ts":"2026-05-29T11:22:44.608961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"warn","ts":"2026-05-29T11:22:44.616099Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T11:22:44.617671Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T11:22:44.61879Z","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-29T11:22:44.621521Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"193b4dda7792855b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T11:22:44.621701Z","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-29T11:22:44.621793Z","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-29T11:22:44.621823Z","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-29T11:22:44.622239Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"193b4dda7792855b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T11:22:44.624116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"info","ts":"2026-05-29T11:22:44.624248Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","added-peer-id":"193b4dda7792855b","added-peer-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-05-29T11:22:44.626505Z","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-29T11:22:44.626702Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-05-29T11:22:44.62673Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-05-29T11:22:44.627463Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T11:22:44.626989Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"193b4dda7792855b","initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T11:22:45.009444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T11:22:45.009528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T11:22:45.009571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgPreVoteResp from 193b4dda7792855b at term 1"} {"level":"info","ts":"2026-05-29T11:22:45.009597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became candidate at term 2"} {"level":"info","ts":"2026-05-29T11:22:45.009783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgVoteResp from 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-05-29T11:22:45.009871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became leader at term 2"} {"level":"info","ts":"2026-05-29T11:22:45.010018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 193b4dda7792855b elected leader 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-05-29T11:22:45.011127Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"193b4dda7792855b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.54:2379]}","request-path":"/0/members/193b4dda7792855b/attributes","cluster-id":"dd6702a2f79ce697","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T11:22:45.011184Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T11:22:45.011675Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:22:45.011826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T11:22:45.012467Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:22:45.01257Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:22:45.012605Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:22:45.014465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.54:2379"} {"level":"info","ts":"2026-05-29T11:22:45.015858Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T11:22:45.02037Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T11:22:45.02044Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T11:23:42.363576Z","caller":"traceutil/trace.go:171","msg":"trace[2062517469] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"322.830814ms","start":"2026-05-29T11:23:42.040719Z","end":"2026-05-29T11:23:42.36355Z","steps":["trace[2062517469] 'process raft request' (duration: 315.966028ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:23:42.363795Z","caller":"traceutil/trace.go:171","msg":"trace[987530574] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"292.528825ms","start":"2026-05-29T11:23:42.071228Z","end":"2026-05-29T11:23:42.363757Z","steps":["trace[987530574] 'process raft request' (duration: 292.139802ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:23:42.363904Z","caller":"traceutil/trace.go:171","msg":"trace[127673748] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"147.763815ms","start":"2026-05-29T11:23:42.21611Z","end":"2026-05-29T11:23:42.363874Z","steps":["trace[127673748] 'process raft request' (duration: 147.472892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:23:42.364048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:23:42.040699Z","time spent":"322.963005ms","remote":"127.0.0.1:36900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T11:23:47.897409Z","caller":"traceutil/trace.go:171","msg":"trace[776609547] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"131.725885ms","start":"2026-05-29T11:23:47.765665Z","end":"2026-05-29T11:23:47.897391Z","steps":["trace[776609547] 'read index received' (duration: 67.404344ms)","trace[776609547] 'applied index is now lower than readState.Index' (duration: 64.320771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:23:47.89757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.882147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:23:47.897599Z","caller":"traceutil/trace.go:171","msg":"trace[941927190] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:639; }","duration":"131.957848ms","start":"2026-05-29T11:23:47.765633Z","end":"2026-05-29T11:23:47.897591Z","steps":["trace[941927190] 'agreement among raft nodes before linearized reading' (duration: 131.872027ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:23:47.897886Z","caller":"traceutil/trace.go:171","msg":"trace[1824549095] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"147.153877ms","start":"2026-05-29T11:23:47.750702Z","end":"2026-05-29T11:23:47.897856Z","steps":["trace[1824549095] 'process raft request' (duration: 82.358642ms)","trace[1824549095] 'compare' (duration: 64.177038ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:23:47.897996Z","caller":"traceutil/trace.go:171","msg":"trace[2059617379] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"147.146637ms","start":"2026-05-29T11:23:47.750842Z","end":"2026-05-29T11:23:47.897989Z","steps":["trace[2059617379] 'process raft request' (duration: 146.490729ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:23:48.471318Z","caller":"traceutil/trace.go:171","msg":"trace[211831909] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"173.584634ms","start":"2026-05-29T11:23:48.297704Z","end":"2026-05-29T11:23:48.471288Z","steps":["trace[211831909] 'process raft request' (duration: 172.962417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:24:51.741395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.128115ms","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-29T11:24:51.741484Z","caller":"traceutil/trace.go:171","msg":"trace[1750278961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1352; }","duration":"146.272756ms","start":"2026-05-29T11:24:51.595193Z","end":"2026-05-29T11:24:51.741466Z","steps":["trace[1750278961] 'range keys from in-memory index tree' (duration: 145.913122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.101371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.422538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T11:25:26.10146Z","caller":"traceutil/trace.go:171","msg":"trace[1612091361] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1900; }","duration":"213.585639ms","start":"2026-05-29T11:25:25.887854Z","end":"2026-05-29T11:25:26.10144Z","steps":["trace[1612091361] 'count revisions from in-memory index tree' (duration: 213.180385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.101464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.985171ms","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-29T11:25:26.101544Z","caller":"traceutil/trace.go:171","msg":"trace[2121885640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1900; }","duration":"178.121283ms","start":"2026-05-29T11:25:25.923401Z","end":"2026-05-29T11:25:26.101523Z","steps":["trace[2121885640] 'range keys from in-memory index tree' (duration: 177.774339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.101603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.68566ms","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-29T11:25:26.101694Z","caller":"traceutil/trace.go:171","msg":"trace[237539838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1900; }","duration":"154.798012ms","start":"2026-05-29T11:25:25.946875Z","end":"2026-05-29T11:25:26.101673Z","steps":["trace[237539838] 'range keys from in-memory index tree' (duration: 154.535959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.101752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.195338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:25:26.101791Z","caller":"traceutil/trace.go:171","msg":"trace[1477060390] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1900; }","duration":"190.266789ms","start":"2026-05-29T11:25:25.911512Z","end":"2026-05-29T11:25:26.101778Z","steps":["trace[1477060390] 'count revisions from in-memory index tree' (duration: 190.127078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.101818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.118726ms","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-29T11:25:26.101857Z","caller":"traceutil/trace.go:171","msg":"trace[890054282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1900; }","duration":"139.184458ms","start":"2026-05-29T11:25:25.962659Z","end":"2026-05-29T11:25:26.101843Z","steps":["trace[890054282] 'range keys from in-memory index tree' (duration: 138.996196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.102156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.339297ms","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-29T11:25:26.102212Z","caller":"traceutil/trace.go:171","msg":"trace[1383165893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1900; }","duration":"155.431739ms","start":"2026-05-29T11:25:25.946768Z","end":"2026-05-29T11:25:26.102199Z","steps":["trace[1383165893] 'range keys from in-memory index tree' (duration: 155.094966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.536548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448448712118382,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T11:25:26.55008Z","caller":"traceutil/trace.go:171","msg":"trace[1624671617] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"557.756081ms","start":"2026-05-29T11:25:25.992301Z","end":"2026-05-29T11:25:26.550057Z","steps":["trace[1624671617] 'process raft request' (duration: 557.605259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.55056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:25.992263Z","time spent":"557.890192ms","remote":"127.0.0.1:56520","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-29T11:25:26.566662Z","caller":"traceutil/trace.go:171","msg":"trace[274148226] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"459.32691ms","start":"2026-05-29T11:25:26.107306Z","end":"2026-05-29T11:25:26.566633Z","steps":["trace[274148226] 'process raft request' (duration: 459.058926ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:26.566657Z","caller":"traceutil/trace.go:171","msg":"trace[605861167] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"459.335079ms","start":"2026-05-29T11:25:26.107293Z","end":"2026-05-29T11:25:26.566628Z","steps":["trace[605861167] 'process raft request' (duration: 459.238149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.566767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.10728Z","time spent":"459.43417ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:25:26.567141Z","caller":"traceutil/trace.go:171","msg":"trace[1921261898] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"459.535231ms","start":"2026-05-29T11:25:26.107586Z","end":"2026-05-29T11:25:26.567122Z","steps":["trace[1921261898] 'process raft request' (duration: 458.990885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.567788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.107268Z","time spent":"459.46392ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:25:26.56797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.10757Z","time spent":"460.260419ms","remote":"127.0.0.1:56520","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-29T11:25:26.567332Z","caller":"traceutil/trace.go:171","msg":"trace[1034582723] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"171.047003ms","start":"2026-05-29T11:25:26.396216Z","end":"2026-05-29T11:25:26.567263Z","steps":["trace[1034582723] 'process raft request' (duration: 170.644179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.567585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.064582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2458f05-5594-487f-887f-f9b1f6a182ae\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-29T11:25:26.56836Z","caller":"traceutil/trace.go:171","msg":"trace[2030843074] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f2458f05-5594-487f-887f-f9b1f6a182ae; range_end:; response_count:1; response_revision:1905; }","duration":"532.861911ms","start":"2026-05-29T11:25:26.035482Z","end":"2026-05-29T11:25:26.568344Z","steps":["trace[2030843074] 'agreement among raft nodes before linearized reading' (duration: 532.016733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.568402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.035468Z","time spent":"532.921572ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2458f05-5594-487f-887f-f9b1f6a182ae\" "} {"level":"info","ts":"2026-05-29T11:25:26.567038Z","caller":"traceutil/trace.go:171","msg":"trace[1769185697] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1982; }","duration":"531.505516ms","start":"2026-05-29T11:25:26.035516Z","end":"2026-05-29T11:25:26.567022Z","steps":["trace[1769185697] 'read index received' (duration: 514.403694ms)","trace[1769185697] 'applied index is now lower than readState.Index' (duration: 17.101072ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:25:26.568595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.722457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-29T11:25:26.568637Z","caller":"traceutil/trace.go:171","msg":"trace[1244919911] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1905; }","duration":"461.836047ms","start":"2026-05-29T11:25:26.106789Z","end":"2026-05-29T11:25:26.568625Z","steps":["trace[1244919911] 'agreement among raft nodes before linearized reading' (duration: 461.700565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.568664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.106757Z","time spent":"461.900388ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-29T11:25:26.568764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.487085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2458f05-5594-487f-887f-f9b1f6a182ae\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-05-29T11:25:26.568733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.652892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-29T11:25:26.568803Z","caller":"traceutil/trace.go:171","msg":"trace[790644618] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f2458f05-5594-487f-887f-f9b1f6a182ae; range_end:; response_count:1; response_revision:1905; }","duration":"185.527066ms","start":"2026-05-29T11:25:26.383266Z","end":"2026-05-29T11:25:26.568793Z","steps":["trace[790644618] 'agreement among raft nodes before linearized reading' (duration: 185.431235ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:26.56882Z","caller":"traceutil/trace.go:171","msg":"trace[725800343] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1905; }","duration":"138.778433ms","start":"2026-05-29T11:25:26.430023Z","end":"2026-05-29T11:25:26.568802Z","steps":["trace[725800343] 'agreement among raft nodes before linearized reading' (duration: 138.554831ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:26.888601Z","caller":"traceutil/trace.go:171","msg":"trace[2063902275] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"313.506068ms","start":"2026-05-29T11:25:26.575059Z","end":"2026-05-29T11:25:26.888565Z","steps":["trace[2063902275] 'process raft request' (duration: 313.228414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.888887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.575045Z","time spent":"313.655829ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:25:26.889067Z","caller":"traceutil/trace.go:171","msg":"trace[1441414056] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1988; }","duration":"115.699535ms","start":"2026-05-29T11:25:26.773341Z","end":"2026-05-29T11:25:26.88904Z","steps":["trace[1441414056] 'read index received' (duration: 115.012937ms)","trace[1441414056] 'applied index is now lower than readState.Index' (duration: 685.158µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:25:26.889123Z","caller":"traceutil/trace.go:171","msg":"trace[154339754] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"313.322437ms","start":"2026-05-29T11:25:26.575777Z","end":"2026-05-29T11:25:26.8891Z","steps":["trace[154339754] 'process raft request' (duration: 313.062183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.889289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.926757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T11:25:26.889305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.575769Z","time spent":"313.385697ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:25:26.889302Z","caller":"traceutil/trace.go:171","msg":"trace[2083264159] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"314.093884ms","start":"2026-05-29T11:25:26.575173Z","end":"2026-05-29T11:25:26.889267Z","steps":["trace[2083264159] 'process raft request' (duration: 313.327365ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:26.889355Z","caller":"traceutil/trace.go:171","msg":"trace[2089938041] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1910; }","duration":"116.059449ms","start":"2026-05-29T11:25:26.773275Z","end":"2026-05-29T11:25:26.889334Z","steps":["trace[2089938041] 'agreement among raft nodes before linearized reading' (duration: 115.870777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.889466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.575167Z","time spent":"314.203625ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:25:26.889709Z","caller":"traceutil/trace.go:171","msg":"trace[2130495406] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"313.456048ms","start":"2026-05-29T11:25:26.576203Z","end":"2026-05-29T11:25:26.889659Z","steps":["trace[2130495406] 'process raft request' (duration: 312.77698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:26.889816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:25:26.576194Z","time spent":"313.558619ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:25:26.893358Z","caller":"traceutil/trace.go:171","msg":"trace[81916317] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"104.241117ms","start":"2026-05-29T11:25:26.789104Z","end":"2026-05-29T11:25:26.893345Z","steps":["trace[81916317] 'process raft request' (duration: 104.163277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:27.361906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.962799ms","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-29T11:25:27.36199Z","caller":"traceutil/trace.go:171","msg":"trace[111366366] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1911; }","duration":"252.091641ms","start":"2026-05-29T11:25:27.109874Z","end":"2026-05-29T11:25:27.361966Z","steps":["trace[111366366] 'range keys from in-memory index tree' (duration: 251.793077ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:42.76277Z","caller":"traceutil/trace.go:171","msg":"trace[1333663028] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"160.88944ms","start":"2026-05-29T11:25:42.601861Z","end":"2026-05-29T11:25:42.76275Z","steps":["trace[1333663028] 'process raft request' (duration: 160.681137ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:42.774512Z","caller":"traceutil/trace.go:171","msg":"trace[1806441818] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2115; }","duration":"130.979375ms","start":"2026-05-29T11:25:42.643516Z","end":"2026-05-29T11:25:42.774495Z","steps":["trace[1806441818] 'read index received' (duration: 119.038961ms)","trace[1806441818] 'applied index is now lower than readState.Index' (duration: 11.939514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:25:42.77471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.169958ms","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-29T11:25:42.77468Z","caller":"traceutil/trace.go:171","msg":"trace[35143635] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"149.313419ms","start":"2026-05-29T11:25:42.625319Z","end":"2026-05-29T11:25:42.774633Z","steps":["trace[35143635] 'process raft request' (duration: 149.095347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:42.774726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.069579ms","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-29T11:25:42.774746Z","caller":"traceutil/trace.go:171","msg":"trace[1936680887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2031; }","duration":"131.271809ms","start":"2026-05-29T11:25:42.643463Z","end":"2026-05-29T11:25:42.774735Z","steps":["trace[1936680887] 'agreement among raft nodes before linearized reading' (duration: 131.135327ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:42.774765Z","caller":"traceutil/trace.go:171","msg":"trace[1492012961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2031; }","duration":"125.108039ms","start":"2026-05-29T11:25:42.649648Z","end":"2026-05-29T11:25:42.774756Z","steps":["trace[1492012961] 'agreement among raft nodes before linearized reading' (duration: 125.031589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:25:42.774864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.147426ms","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-29T11:25:42.774864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.622849ms","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-29T11:25:42.774908Z","caller":"traceutil/trace.go:171","msg":"trace[1021129433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2031; }","duration":"131.419431ms","start":"2026-05-29T11:25:42.643463Z","end":"2026-05-29T11:25:42.774882Z","steps":["trace[1021129433] 'agreement among raft nodes before linearized reading' (duration: 131.110387ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:42.774981Z","caller":"traceutil/trace.go:171","msg":"trace[1224031069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2031; }","duration":"128.72025ms","start":"2026-05-29T11:25:42.646207Z","end":"2026-05-29T11:25:42.774927Z","steps":["trace[1224031069] 'agreement among raft nodes before linearized reading' (duration: 128.459008ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:26:08.805939Z","caller":"traceutil/trace.go:171","msg":"trace[1859862000] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"111.631359ms","start":"2026-05-29T11:26:08.694272Z","end":"2026-05-29T11:26:08.805904Z","steps":["trace[1859862000] 'process raft request' (duration: 84.91538ms)","trace[1859862000] 'compare' (duration: 26.473326ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:26:08.805987Z","caller":"traceutil/trace.go:171","msg":"trace[1671969846] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"111.187933ms","start":"2026-05-29T11:26:08.694775Z","end":"2026-05-29T11:26:08.805963Z","steps":["trace[1671969846] 'process raft request' (duration: 111.053412ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:26:09.1215Z","caller":"traceutil/trace.go:171","msg":"trace[1527979967] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"261.730239ms","start":"2026-05-29T11:26:08.859744Z","end":"2026-05-29T11:26:09.121474Z","steps":["trace[1527979967] 'process raft request' (duration: 251.342592ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:26:09.155982Z","caller":"traceutil/trace.go:171","msg":"trace[1517881512] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2271; }","duration":"262.509868ms","start":"2026-05-29T11:26:08.893229Z","end":"2026-05-29T11:26:09.155739Z","steps":["trace[1517881512] 'read index received' (duration: 217.953659ms)","trace[1517881512] 'applied index is now lower than readState.Index' (duration: 44.555149ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:26:09.156242Z","caller":"traceutil/trace.go:171","msg":"trace[1780165813] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"293.177461ms","start":"2026-05-29T11:26:08.863056Z","end":"2026-05-29T11:26:09.156233Z","steps":["trace[1780165813] 'process raft request' (duration: 292.559283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.156909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.706821ms","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-29T11:26:09.156964Z","caller":"traceutil/trace.go:171","msg":"trace[129261914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2180; }","duration":"263.814551ms","start":"2026-05-29T11:26:08.89314Z","end":"2026-05-29T11:26:09.156955Z","steps":["trace[129261914] 'agreement among raft nodes before linearized reading' (duration: 263.227525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.157119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.775891ms","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-29T11:26:09.157144Z","caller":"traceutil/trace.go:171","msg":"trace[170529562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2180; }","duration":"255.824712ms","start":"2026-05-29T11:26:08.901313Z","end":"2026-05-29T11:26:09.157138Z","steps":["trace[170529562] 'agreement among raft nodes before linearized reading' (duration: 255.754441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.157332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.69468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-05-29T11:26:09.157358Z","caller":"traceutil/trace.go:171","msg":"trace[1898636218] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2180; }","duration":"229.7364ms","start":"2026-05-29T11:26:08.927616Z","end":"2026-05-29T11:26:09.157352Z","steps":["trace[1898636218] 'agreement among raft nodes before linearized reading' (duration: 229.663269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.15769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.115317ms","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-29T11:26:09.15772Z","caller":"traceutil/trace.go:171","msg":"trace[1491945000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2180; }","duration":"241.170067ms","start":"2026-05-29T11:26:08.916544Z","end":"2026-05-29T11:26:09.157714Z","steps":["trace[1491945000] 'agreement among raft nodes before linearized reading' (duration: 240.910675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.157978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.396375ms","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-29T11:26:09.158057Z","caller":"traceutil/trace.go:171","msg":"trace[1313936372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2180; }","duration":"244.466875ms","start":"2026-05-29T11:26:08.913548Z","end":"2026-05-29T11:26:09.158015Z","steps":["trace[1313936372] 'agreement among raft nodes before linearized reading' (duration: 244.332454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.623269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.552269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T11:26:09.623604Z","caller":"traceutil/trace.go:171","msg":"trace[1332470569] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"459.305379ms","start":"2026-05-29T11:26:09.164268Z","end":"2026-05-29T11:26:09.623573Z","steps":["trace[1332470569] 'process raft request' (duration: 237.254724ms)","trace[1332470569] 'compare' (duration: 221.287596ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:26:09.62387Z","caller":"traceutil/trace.go:171","msg":"trace[1226015675] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2273; }","duration":"458.652741ms","start":"2026-05-29T11:26:09.165155Z","end":"2026-05-29T11:26:09.623807Z","steps":["trace[1226015675] 'read index received' (duration: 236.492805ms)","trace[1226015675] 'applied index is now lower than readState.Index' (duration: 222.157676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:26:09.624068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.164246Z","time spent":"459.42887ms","remote":"127.0.0.1:56520","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-29T11:26:09.624337Z","caller":"traceutil/trace.go:171","msg":"trace[1449633706] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"459.065886ms","start":"2026-05-29T11:26:09.165257Z","end":"2026-05-29T11:26:09.624323Z","steps":["trace[1449633706] 'process raft request' (duration: 458.108455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.624398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.165249Z","time spent":"459.120967ms","remote":"127.0.0.1:56520","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-29T11:26:09.624641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.480601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-29T11:26:09.624673Z","caller":"traceutil/trace.go:171","msg":"trace[2123609317] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2182; }","duration":"459.510591ms","start":"2026-05-29T11:26:09.165152Z","end":"2026-05-29T11:26:09.624663Z","steps":["trace[2123609317] 'agreement among raft nodes before linearized reading' (duration: 458.802393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.624705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.165129Z","time spent":"459.559391ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-29T11:26:09.624946Z","caller":"traceutil/trace.go:171","msg":"trace[763248717] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"457.132644ms","start":"2026-05-29T11:26:09.167803Z","end":"2026-05-29T11:26:09.624936Z","steps":["trace[763248717] 'process raft request' (duration: 456.459987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.624992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.167785Z","time spent":"457.184194ms","remote":"127.0.0.1:56520","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-29T11:26:09.625076Z","caller":"traceutil/trace.go:171","msg":"trace[1082489105] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"455.81998ms","start":"2026-05-29T11:26:09.169231Z","end":"2026-05-29T11:26:09.625051Z","steps":["trace[1082489105] 'process raft request' (duration: 455.620768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.625358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.169179Z","time spent":"456.003752ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:26:09.625034Z","caller":"traceutil/trace.go:171","msg":"trace[58869531] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"453.100068ms","start":"2026-05-29T11:26:09.171913Z","end":"2026-05-29T11:26:09.625013Z","steps":["trace[58869531] 'process raft request' (duration: 453.037208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:09.625682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.171895Z","time spent":"453.732036ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:26:10.298146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.415464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T11:26:10.298426Z","caller":"traceutil/trace.go:171","msg":"trace[950214735] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"665.592066ms","start":"2026-05-29T11:26:09.632815Z","end":"2026-05-29T11:26:10.298407Z","steps":["trace[950214735] 'process raft request' (duration: 665.437695ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:26:10.298465Z","caller":"traceutil/trace.go:171","msg":"trace[93623723] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"785.69856ms","start":"2026-05-29T11:26:09.512731Z","end":"2026-05-29T11:26:10.29843Z","steps":["trace[93623723] 'process raft request' (duration: 237.885341ms)","trace[93623723] 'compare' (duration: 547.153171ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:26:10.298512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.632796Z","time spent":"665.672297ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:26:10.298837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.512706Z","time spent":"786.011413ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:26:10.298895Z","caller":"traceutil/trace.go:171","msg":"trace[1038736788] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"674.961432ms","start":"2026-05-29T11:26:09.623921Z","end":"2026-05-29T11:26:10.298882Z","steps":["trace[1038736788] 'read index received' (duration: 126.802339ms)","trace[1038736788] 'applied index is now lower than readState.Index' (duration: 548.157673ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:26:10.299038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128632216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T11:26:10.299044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035083449s","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-29T11:26:10.299086Z","caller":"traceutil/trace.go:171","msg":"trace[967023113] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2187; }","duration":"1.128692867s","start":"2026-05-29T11:26:09.170379Z","end":"2026-05-29T11:26:10.299072Z","steps":["trace[967023113] 'agreement among raft nodes before linearized reading' (duration: 1.128557445s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:10.299091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133896015s","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-29T11:26:10.299102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.565888ms","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-29T11:26:10.299122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.170375Z","time spent":"1.128740178s","remote":"127.0.0.1:56716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-29T11:26:10.29912Z","caller":"traceutil/trace.go:171","msg":"trace[1997393983] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2187; }","duration":"1.035201381s","start":"2026-05-29T11:26:09.263896Z","end":"2026-05-29T11:26:10.299097Z","steps":["trace[1997393983] 'agreement among raft nodes before linearized reading' (duration: 1.03506985s)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:26:10.299144Z","caller":"traceutil/trace.go:171","msg":"trace[1748388696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2187; }","duration":"1.133957506s","start":"2026-05-29T11:26:09.165172Z","end":"2026-05-29T11:26:10.29913Z","steps":["trace[1748388696] 'agreement among raft nodes before linearized reading' (duration: 1.133772273s)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:26:10.29915Z","caller":"traceutil/trace.go:171","msg":"trace[484474550] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"599.658639ms","start":"2026-05-29T11:26:09.699479Z","end":"2026-05-29T11:26:10.299137Z","steps":["trace[484474550] 'agreement among raft nodes before linearized reading' (duration: 599.493077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:10.299166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.263877Z","time spent":"1.035278323s","remote":"127.0.0.1:52954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-05-29T11:26:10.299188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.165169Z","time spent":"1.134012487s","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T11:26:10.299192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.699458Z","time spent":"599.72646ms","remote":"127.0.0.1:45534","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":"warn","ts":"2026-05-29T11:26:10.299355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.292665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:26:10.299414Z","caller":"traceutil/trace.go:171","msg":"trace[491576195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2187; }","duration":"768.380206ms","start":"2026-05-29T11:26:09.531013Z","end":"2026-05-29T11:26:10.299394Z","steps":["trace[491576195] 'agreement among raft nodes before linearized reading' (duration: 768.310365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:26:10.299467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:26:09.530986Z","time spent":"768.468577ms","remote":"127.0.0.1:36860","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-29T11:26:57.704215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.692735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:26:57.704393Z","caller":"traceutil/trace.go:171","msg":"trace[431352723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2493; }","duration":"172.901248ms","start":"2026-05-29T11:26:57.531459Z","end":"2026-05-29T11:26:57.70436Z","steps":["trace[431352723] 'range keys from in-memory index tree' (duration: 172.59596ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:27:08.458438Z","caller":"traceutil/trace.go:171","msg":"trace[808785853] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"512.491733ms","start":"2026-05-29T11:27:07.945918Z","end":"2026-05-29T11:27:08.45841Z","steps":["trace[808785853] 'process raft request' (duration: 512.303062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:27:08.458605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:27:07.945902Z","time spent":"512.61333ms","remote":"127.0.0.1:56520","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-29T11:27:08.458934Z","caller":"traceutil/trace.go:171","msg":"trace[1569470816] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"508.631397ms","start":"2026-05-29T11:27:07.950279Z","end":"2026-05-29T11:27:08.45891Z","steps":["trace[1569470816] 'process raft request' (duration: 508.482989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:27:08.459011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:27:07.950265Z","time spent":"508.707661ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:27:08.458965Z","caller":"traceutil/trace.go:171","msg":"trace[1661971869] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"508.469359ms","start":"2026-05-29T11:27:07.950468Z","end":"2026-05-29T11:27:08.458938Z","steps":["trace[1661971869] 'process raft request' (duration: 508.390765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:27:08.459372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:27:07.950456Z","time spent":"508.842219ms","remote":"127.0.0.1:56520","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-29T11:27:08.459082Z","caller":"traceutil/trace.go:171","msg":"trace[485970258] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"508.525452ms","start":"2026-05-29T11:27:07.950525Z","end":"2026-05-29T11:27:08.459051Z","steps":["trace[485970258] 'process raft request' (duration: 508.370263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:27:08.459591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:27:07.950518Z","time spent":"509.009568ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:27:39.212214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.387132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-29T11:27:39.212307Z","caller":"traceutil/trace.go:171","msg":"trace[1795969792] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2771; }","duration":"465.502018ms","start":"2026-05-29T11:27:38.746784Z","end":"2026-05-29T11:27:39.212286Z","steps":["trace[1795969792] 'range keys from in-memory index tree' (duration: 465.246566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:27:39.212357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:27:38.746776Z","time spent":"465.56471ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-29T11:27:39.2134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.126076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T11:27:39.213541Z","caller":"traceutil/trace.go:171","msg":"trace[2022597131] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"466.178139ms","start":"2026-05-29T11:27:38.747332Z","end":"2026-05-29T11:27:39.21351Z","steps":["trace[2022597131] 'process raft request' (duration: 171.865185ms)","trace[2022597131] 'compare' (duration: 293.976469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:27:39.213709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:27:38.747316Z","time spent":"466.315295ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T11:27:44.804304Z","caller":"traceutil/trace.go:171","msg":"trace[2107353788] linearizableReadLoop","detail":"{readStateIndex:2914; appliedIndex:2913; }","duration":"157.178241ms","start":"2026-05-29T11:27:44.647099Z","end":"2026-05-29T11:27:44.804278Z","steps":["trace[2107353788] 'read index received' (duration: 157.067336ms)","trace[2107353788] 'applied index is now lower than readState.Index' (duration: 109.005µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:27:44.804384Z","caller":"traceutil/trace.go:171","msg":"trace[827783791] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"159.020048ms","start":"2026-05-29T11:27:44.645325Z","end":"2026-05-29T11:27:44.804345Z","steps":["trace[827783791] 'process raft request' (duration: 158.765626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:27:44.80456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.444404ms","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-29T11:27:44.804603Z","caller":"traceutil/trace.go:171","msg":"trace[1539063971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2800; }","duration":"157.548669ms","start":"2026-05-29T11:27:44.647043Z","end":"2026-05-29T11:27:44.804591Z","steps":["trace[1539063971] 'agreement among raft nodes before linearized reading' (duration: 157.382952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:27:44.804833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.191159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T11:27:44.804883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.401901ms","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-29T11:27:44.804929Z","caller":"traceutil/trace.go:171","msg":"trace[637698906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2800; }","duration":"144.310395ms","start":"2026-05-29T11:27:44.660599Z","end":"2026-05-29T11:27:44.804909Z","steps":["trace[637698906] 'agreement among raft nodes before linearized reading' (duration: 144.140948ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:27:44.804964Z","caller":"traceutil/trace.go:171","msg":"trace[658593838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2800; }","duration":"149.614492ms","start":"2026-05-29T11:27:44.655333Z","end":"2026-05-29T11:27:44.804947Z","steps":["trace[658593838] 'agreement among raft nodes before linearized reading' (duration: 149.319788ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:27:45.038611Z","caller":"traceutil/trace.go:171","msg":"trace[1755178780] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"227.746463ms","start":"2026-05-29T11:27:44.810837Z","end":"2026-05-29T11:27:45.038584Z","steps":["trace[1755178780] 'process raft request' (duration: 227.68399ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:27:45.039016Z","caller":"traceutil/trace.go:171","msg":"trace[1479717572] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"229.39729ms","start":"2026-05-29T11:27:44.809601Z","end":"2026-05-29T11:27:45.038998Z","steps":["trace[1479717572] 'process raft request' (duration: 224.538426ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:39.704389Z","caller":"traceutil/trace.go:171","msg":"trace[1248468419] transaction","detail":"{read_only:false; response_revision:4560; number_of_response:1; }","duration":"111.966487ms","start":"2026-05-29T11:31:39.592392Z","end":"2026-05-29T11:31:39.704359Z","steps":["trace[1248468419] 'process raft request' (duration: 111.779922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:47.26744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.952494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T11:31:47.267672Z","caller":"traceutil/trace.go:171","msg":"trace[2066511401] linearizableReadLoop","detail":"{readStateIndex:4957; appliedIndex:4956; }","duration":"144.805982ms","start":"2026-05-29T11:31:47.122846Z","end":"2026-05-29T11:31:47.267652Z","steps":["trace[2066511401] 'read index received' (duration: 33.552µs)","trace[2066511401] 'applied index is now lower than readState.Index' (duration: 144.77043ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:31:47.267754Z","caller":"traceutil/trace.go:171","msg":"trace[2018696241] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"153.010164ms","start":"2026-05-29T11:31:47.114711Z","end":"2026-05-29T11:31:47.267721Z","steps":["trace[2018696241] 'compare' (duration: 144.507355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:47.268001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.145059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-29T11:31:47.268114Z","caller":"traceutil/trace.go:171","msg":"trace[1859558363] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4763; }","duration":"145.294621ms","start":"2026-05-29T11:31:47.1228Z","end":"2026-05-29T11:31:47.268094Z","steps":["trace[1859558363] 'agreement among raft nodes before linearized reading' (duration: 144.968884ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:47.471999Z","caller":"traceutil/trace.go:171","msg":"trace[842273442] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"136.974276ms","start":"2026-05-29T11:31:47.334995Z","end":"2026-05-29T11:31:47.47197Z","steps":["trace[842273442] 'process raft request' (duration: 136.602338ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:48.742232Z","caller":"traceutil/trace.go:171","msg":"trace[470965659] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4963; }","duration":"458.59648ms","start":"2026-05-29T11:31:48.283611Z","end":"2026-05-29T11:31:48.742208Z","steps":["trace[470965659] 'read index received' (duration: 458.430915ms)","trace[470965659] 'applied index is now lower than readState.Index' (duration: 164.465µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:31:48.742345Z","caller":"traceutil/trace.go:171","msg":"trace[2017639712] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"463.244774ms","start":"2026-05-29T11:31:48.279066Z","end":"2026-05-29T11:31:48.742311Z","steps":["trace[2017639712] 'process raft request' (duration: 462.979187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:48.742471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.850836ms","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-29T11:31:48.742523Z","caller":"traceutil/trace.go:171","msg":"trace[785744954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4770; }","duration":"458.948268ms","start":"2026-05-29T11:31:48.28356Z","end":"2026-05-29T11:31:48.742508Z","steps":["trace[785744954] 'agreement among raft nodes before linearized reading' (duration: 458.767294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:48.742526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:48.279042Z","time spent":"463.377427ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:31:48.742578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:48.283541Z","time spent":"459.026399ms","remote":"127.0.0.1:56520","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-29T11:31:48.742995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.560999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T11:31:48.743044Z","caller":"traceutil/trace.go:171","msg":"trace[565681431] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4773; }","duration":"275.673321ms","start":"2026-05-29T11:31:48.467359Z","end":"2026-05-29T11:31:48.743032Z","steps":["trace[565681431] 'agreement among raft nodes before linearized reading' (duration: 275.574619ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:48.743152Z","caller":"traceutil/trace.go:171","msg":"trace[943054565] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"457.643459ms","start":"2026-05-29T11:31:48.285497Z","end":"2026-05-29T11:31:48.74314Z","steps":["trace[943054565] 'process raft request' (duration: 457.2501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:48.743228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:48.285484Z","time spent":"457.692329ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:31:48.743337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.068481ms","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-29T11:31:48.743391Z","caller":"traceutil/trace.go:171","msg":"trace[1553750742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4773; }","duration":"251.170514ms","start":"2026-05-29T11:31:48.492205Z","end":"2026-05-29T11:31:48.743375Z","steps":["trace[1553750742] 'agreement among raft nodes before linearized reading' (duration: 251.01226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:48.743457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.310211ms","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-29T11:31:48.743481Z","caller":"traceutil/trace.go:171","msg":"trace[20382001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4773; }","duration":"185.395724ms","start":"2026-05-29T11:31:48.558078Z","end":"2026-05-29T11:31:48.743473Z","steps":["trace[20382001] 'agreement among raft nodes before linearized reading' (duration: 185.328862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:53.860836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.715509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b405f7513a58a5\" ","response":"range_response_count:1 size:1012"} {"level":"warn","ts":"2026-05-29T11:31:53.86087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.950195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T11:31:53.860944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.793296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6f5db21e-1b03-4a56-b417-e5f6d0d0f008\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-29T11:31:53.860965Z","caller":"traceutil/trace.go:171","msg":"trace[301093379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4862; }","duration":"332.095699ms","start":"2026-05-29T11:31:53.528847Z","end":"2026-05-29T11:31:53.860942Z","steps":["trace[301093379] 'range keys from in-memory index tree' (duration: 331.858804ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:53.861019Z","caller":"traceutil/trace.go:171","msg":"trace[392975100] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6f5db21e-1b03-4a56-b417-e5f6d0d0f008; range_end:; response_count:1; response_revision:4862; }","duration":"337.922309ms","start":"2026-05-29T11:31:53.52308Z","end":"2026-05-29T11:31:53.861002Z","steps":["trace[392975100] 'range keys from in-memory index tree' (duration: 337.52463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:53.861025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:53.528827Z","time spent":"332.188251ms","remote":"127.0.0.1:36860","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-29T11:31:53.861063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:53.523056Z","time spent":"337.99951ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6f5db21e-1b03-4a56-b417-e5f6d0d0f008\" "} {"level":"info","ts":"2026-05-29T11:31:53.86094Z","caller":"traceutil/trace.go:171","msg":"trace[188913518] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b405f7513a58a5; range_end:; response_count:1; response_revision:4862; }","duration":"198.897914ms","start":"2026-05-29T11:31:53.662015Z","end":"2026-05-29T11:31:53.860913Z","steps":["trace[188913518] 'range keys from in-memory index tree' (duration: 198.504005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:53.861959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.1289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T11:31:53.861993Z","caller":"traceutil/trace.go:171","msg":"trace[1034173297] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4862; }","duration":"323.240673ms","start":"2026-05-29T11:31:53.538744Z","end":"2026-05-29T11:31:53.861985Z","steps":["trace[1034173297] 'count revisions from in-memory index tree' (duration: 323.037158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:53.862013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:53.538717Z","time spent":"323.287634ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-05-29T11:31:54.088412Z","caller":"traceutil/trace.go:171","msg":"trace[258165115] linearizableReadLoop","detail":"{readStateIndex:5058; appliedIndex:5057; }","duration":"225.567042ms","start":"2026-05-29T11:31:53.86282Z","end":"2026-05-29T11:31:54.088387Z","steps":["trace[258165115] 'read index received' (duration: 157.197117ms)","trace[258165115] 'applied index is now lower than readState.Index' (duration: 68.368275ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:31:54.088737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.817928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:31:54.088834Z","caller":"traceutil/trace.go:171","msg":"trace[110220583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4862; }","duration":"226.012573ms","start":"2026-05-29T11:31:53.862799Z","end":"2026-05-29T11:31:54.088811Z","steps":["trace[110220583] 'agreement among raft nodes before linearized reading' (duration: 225.752147ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:54.092507Z","caller":"traceutil/trace.go:171","msg":"trace[1883823265] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"228.213441ms","start":"2026-05-29T11:31:53.864271Z","end":"2026-05-29T11:31:54.092485Z","steps":["trace[1883823265] 'process raft request' (duration: 227.896004ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:54.894481Z","caller":"traceutil/trace.go:171","msg":"trace[108146470] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5061; }","duration":"115.246934ms","start":"2026-05-29T11:31:54.77921Z","end":"2026-05-29T11:31:54.894457Z","steps":["trace[108146470] 'read index received' (duration: 40.705232ms)","trace[108146470] 'applied index is now lower than readState.Index' (duration: 74.540472ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:31:54.894726Z","caller":"traceutil/trace.go:171","msg":"trace[746032334] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"118.557267ms","start":"2026-05-29T11:31:54.776154Z","end":"2026-05-29T11:31:54.894711Z","steps":["trace[746032334] 'process raft request' (duration: 56.530622ms)","trace[746032334] 'compare' (duration: 61.655097ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:31:54.894977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.676398ms","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-29T11:31:54.895056Z","caller":"traceutil/trace.go:171","msg":"trace[1401916289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4867; }","duration":"113.77973ms","start":"2026-05-29T11:31:54.781258Z","end":"2026-05-29T11:31:54.895038Z","steps":["trace[1401916289] 'agreement among raft nodes before linearized reading' (duration: 113.528585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:54.895128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.897838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-29T11:31:54.895219Z","caller":"traceutil/trace.go:171","msg":"trace[1760383086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4867; }","duration":"116.055332ms","start":"2026-05-29T11:31:54.779142Z","end":"2026-05-29T11:31:54.895198Z","steps":["trace[1760383086] 'agreement among raft nodes before linearized reading' (duration: 115.787896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:54.895242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.64499ms","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-29T11:31:54.895276Z","caller":"traceutil/trace.go:171","msg":"trace[1036925740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4867; }","duration":"109.675ms","start":"2026-05-29T11:31:54.785589Z","end":"2026-05-29T11:31:54.895263Z","steps":["trace[1036925740] 'agreement among raft nodes before linearized reading' (duration: 109.582819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:54.895443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.892046ms","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-29T11:31:54.895566Z","caller":"traceutil/trace.go:171","msg":"trace[1789341222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4867; }","duration":"110.041868ms","start":"2026-05-29T11:31:54.785494Z","end":"2026-05-29T11:31:54.895535Z","steps":["trace[1789341222] 'agreement among raft nodes before linearized reading' (duration: 109.783563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:54.895465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.86404ms","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-29T11:31:54.895646Z","caller":"traceutil/trace.go:171","msg":"trace[1973116743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4867; }","duration":"113.070085ms","start":"2026-05-29T11:31:54.782565Z","end":"2026-05-29T11:31:54.895636Z","steps":["trace[1973116743] 'agreement among raft nodes before linearized reading' (duration: 112.756278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:56.163828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.306778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"warn","ts":"2026-05-29T11:31:56.163952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.251166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-29T11:31:56.165294Z","caller":"traceutil/trace.go:171","msg":"trace[1179602546] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4883; }","duration":"120.610445ms","start":"2026-05-29T11:31:56.044667Z","end":"2026-05-29T11:31:56.165278Z","steps":["trace[1179602546] 'range keys from in-memory index tree' (duration: 119.114021ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:56.16552Z","caller":"traceutil/trace.go:171","msg":"trace[216843654] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4883; }","duration":"140.6929ms","start":"2026-05-29T11:31:56.024448Z","end":"2026-05-29T11:31:56.16514Z","steps":["trace[216843654] 'range keys from in-memory index tree' (duration: 139.085804ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:58.499914Z","caller":"traceutil/trace.go:171","msg":"trace[2039923507] linearizableReadLoop","detail":"{readStateIndex:5117; appliedIndex:5116; }","duration":"468.277058ms","start":"2026-05-29T11:31:58.031596Z","end":"2026-05-29T11:31:58.499873Z","steps":["trace[2039923507] 'read index received' (duration: 467.918561ms)","trace[2039923507] 'applied index is now lower than readState.Index' (duration: 356.307µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:31:58.500137Z","caller":"traceutil/trace.go:171","msg":"trace[1174380232] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"582.184731ms","start":"2026-05-29T11:31:57.91793Z","end":"2026-05-29T11:31:58.500115Z","steps":["trace[1174380232] 'process raft request' (duration: 581.697701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:58.500353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.740289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-29T11:31:58.500439Z","caller":"traceutil/trace.go:171","msg":"trace[2027890921] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4921; }","duration":"468.880002ms","start":"2026-05-29T11:31:58.031539Z","end":"2026-05-29T11:31:58.500419Z","steps":["trace[2027890921] 'agreement among raft nodes before linearized reading' (duration: 468.645287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:58.500476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:58.031515Z","time spent":"468.954054ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-29T11:31:58.500337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:57.917915Z","time spent":"582.273403ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:31:58.506326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.608943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tzj6f\" ","response":"range_response_count:1 size:9938"} {"level":"info","ts":"2026-05-29T11:31:58.506417Z","caller":"traceutil/trace.go:171","msg":"trace[1577243310] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tzj6f; range_end:; response_count:1; response_revision:4923; }","duration":"452.675245ms","start":"2026-05-29T11:31:58.053698Z","end":"2026-05-29T11:31:58.506373Z","steps":["trace[1577243310] 'agreement among raft nodes before linearized reading' (duration: 452.570533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:58.506443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:58.053632Z","time spent":"452.804247ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9961,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tzj6f\" "} {"level":"info","ts":"2026-05-29T11:31:58.507094Z","caller":"traceutil/trace.go:171","msg":"trace[1179369181] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"240.906339ms","start":"2026-05-29T11:31:58.264956Z","end":"2026-05-29T11:31:58.505862Z","steps":["trace[1179369181] 'process raft request' (duration: 240.10445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:58.506088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.736231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T11:31:58.50861Z","caller":"traceutil/trace.go:171","msg":"trace[546284828] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:4923; }","duration":"423.121764ms","start":"2026-05-29T11:31:58.085266Z","end":"2026-05-29T11:31:58.508388Z","steps":["trace[546284828] 'agreement among raft nodes before linearized reading' (duration: 420.720601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:58.508684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:31:58.085243Z","time spent":"423.390929ms","remote":"127.0.0.1:56586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":26,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-29T11:32:08.748567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448448712126136,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T11:32:08.982455Z","caller":"traceutil/trace.go:171","msg":"trace[1590225456] linearizableReadLoop","detail":"{readStateIndex:5249; appliedIndex:5248; }","duration":"734.180895ms","start":"2026-05-29T11:32:08.24825Z","end":"2026-05-29T11:32:08.982431Z","steps":["trace[1590225456] 'read index received' (duration: 733.96166ms)","trace[1590225456] 'applied index is now lower than readState.Index' (duration: 218.055µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:32:08.982496Z","caller":"traceutil/trace.go:171","msg":"trace[1376255403] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"817.776895ms","start":"2026-05-29T11:32:08.164687Z","end":"2026-05-29T11:32:08.982464Z","steps":["trace[1376255403] 'process raft request' (duration: 817.55354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:08.982624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.164648Z","time spent":"817.906677ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10663,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:32:08.982764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.499023ms","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-29T11:32:08.983044Z","caller":"traceutil/trace.go:171","msg":"trace[1202286095] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5049; }","duration":"734.685457ms","start":"2026-05-29T11:32:08.248145Z","end":"2026-05-29T11:32:08.98283Z","steps":["trace[1202286095] 'agreement among raft nodes before linearized reading' (duration: 734.418002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:08.983121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.248116Z","time spent":"734.989704ms","remote":"127.0.0.1:56434","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-29T11:32:09.121568Z","caller":"traceutil/trace.go:171","msg":"trace[920222329] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"332.12807ms","start":"2026-05-29T11:32:08.789417Z","end":"2026-05-29T11:32:09.121545Z","steps":["trace[920222329] 'process raft request' (duration: 331.901525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.121915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.789387Z","time spent":"332.257832ms","remote":"127.0.0.1:56520","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-29T11:32:09.12207Z","caller":"traceutil/trace.go:171","msg":"trace[6983076] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"247.201993ms","start":"2026-05-29T11:32:08.874759Z","end":"2026-05-29T11:32:09.121961Z","steps":["trace[6983076] 'process raft request' (duration: 246.740763ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:32:09.122135Z","caller":"traceutil/trace.go:171","msg":"trace[2104523328] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"151.375558ms","start":"2026-05-29T11:32:08.970699Z","end":"2026-05-29T11:32:09.122075Z","steps":["trace[2104523328] 'process raft request' (duration: 151.055141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.122261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.877214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.54\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-29T11:32:09.1223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.284149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-29T11:32:09.12234Z","caller":"traceutil/trace.go:171","msg":"trace[515580541] range","detail":"{range_begin:/registry/masterleases/199.19.213.54; range_end:; response_count:1; response_revision:5052; }","duration":"457.990186ms","start":"2026-05-29T11:32:08.664319Z","end":"2026-05-29T11:32:09.12231Z","steps":["trace[515580541] 'agreement among raft nodes before linearized reading' (duration: 457.794552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.122381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.664298Z","time spent":"458.077729ms","remote":"127.0.0.1:36882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.54\" "} {"level":"info","ts":"2026-05-29T11:32:09.122393Z","caller":"traceutil/trace.go:171","msg":"trace[205197569] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5052; }","duration":"364.428312ms","start":"2026-05-29T11:32:08.757943Z","end":"2026-05-29T11:32:09.122371Z","steps":["trace[205197569] 'agreement among raft nodes before linearized reading' (duration: 364.055633ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:32:09.122404Z","caller":"traceutil/trace.go:171","msg":"trace[142822599] linearizableReadLoop","detail":"{readStateIndex:5252; appliedIndex:5249; }","duration":"139.359338ms","start":"2026-05-29T11:32:08.982532Z","end":"2026-05-29T11:32:09.121891Z","steps":["trace[142822599] 'read index received' (duration: 138.832267ms)","trace[142822599] 'applied index is now lower than readState.Index' (duration: 526.101µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:32:09.122439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.757924Z","time spent":"364.504843ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-29T11:32:09.12258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.409729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:32:09.122643Z","caller":"traceutil/trace.go:171","msg":"trace[1888064653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5052; }","duration":"592.515602ms","start":"2026-05-29T11:32:08.530111Z","end":"2026-05-29T11:32:09.122626Z","steps":["trace[1888064653] 'agreement among raft nodes before linearized reading' (duration: 592.382559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.122677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.530089Z","time spent":"592.580314ms","remote":"127.0.0.1:36860","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-29T11:32:09.31728Z","caller":"traceutil/trace.go:171","msg":"trace[1199093417] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"182.629016ms","start":"2026-05-29T11:32:09.134627Z","end":"2026-05-29T11:32:09.317256Z","steps":["trace[1199093417] 'process raft request' (duration: 182.534285ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:32:09.317734Z","caller":"traceutil/trace.go:171","msg":"trace[1564573709] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"327.700108ms","start":"2026-05-29T11:32:08.990012Z","end":"2026-05-29T11:32:09.317713Z","steps":["trace[1564573709] 'process raft request' (duration: 326.974722ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:32:09.317671Z","caller":"traceutil/trace.go:171","msg":"trace[378537824] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"328.287341ms","start":"2026-05-29T11:32:08.989348Z","end":"2026-05-29T11:32:09.317636Z","steps":["trace[378537824] 'process raft request' (duration: 273.4669ms)","trace[378537824] 'compare' (duration: 53.952122ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:32:09.317837Z","caller":"traceutil/trace.go:171","msg":"trace[1847895249] linearizableReadLoop","detail":"{readStateIndex:5256; appliedIndex:5252; }","duration":"195.346903ms","start":"2026-05-29T11:32:09.122474Z","end":"2026-05-29T11:32:09.317821Z","steps":["trace[1847895249] 'read index received' (duration: 140.310408ms)","trace[1847895249] 'applied index is now lower than readState.Index' (duration: 55.034825ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:32:09.317812Z","caller":"traceutil/trace.go:171","msg":"trace[91863060] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"327.36265ms","start":"2026-05-29T11:32:08.990212Z","end":"2026-05-29T11:32:09.317575Z","steps":["trace[91863060] 'process raft request' (duration: 326.810008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.318075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.989335Z","time spent":"328.524146ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1504,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:32:09.318166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.932397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T11:32:09.318265Z","caller":"traceutil/trace.go:171","msg":"trace[2078054199] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5057; }","duration":"243.076589ms","start":"2026-05-29T11:32:09.075168Z","end":"2026-05-29T11:32:09.318245Z","steps":["trace[2078054199] 'agreement among raft nodes before linearized reading' (duration: 242.925505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.318183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.990005Z","time spent":"327.780259ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1427,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:32:09.318393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.219904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"warn","ts":"2026-05-29T11:32:09.318461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.308556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6f5db21e-1b03-4a56-b417-e5f6d0d0f008\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-29T11:32:09.318517Z","caller":"traceutil/trace.go:171","msg":"trace[1308805419] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6f5db21e-1b03-4a56-b417-e5f6d0d0f008; range_end:; response_count:1; response_revision:5057; }","duration":"330.393077ms","start":"2026-05-29T11:32:08.98811Z","end":"2026-05-29T11:32:09.318503Z","steps":["trace[1308805419] 'agreement among raft nodes before linearized reading' (duration: 330.199972ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:32:09.318435Z","caller":"traceutil/trace.go:171","msg":"trace[1293734362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5057; }","duration":"302.288696ms","start":"2026-05-29T11:32:09.016135Z","end":"2026-05-29T11:32:09.318423Z","steps":["trace[1293734362] 'agreement among raft nodes before linearized reading' (duration: 301.905318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.318139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.990204Z","time spent":"327.787289ms","remote":"127.0.0.1:56448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:32:09.318558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.988094Z","time spent":"330.456428ms","remote":"127.0.0.1:56428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-6f5db21e-1b03-4a56-b417-e5f6d0d0f008\" "} {"level":"warn","ts":"2026-05-29T11:32:09.31863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.294329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:32:09.318682Z","caller":"traceutil/trace.go:171","msg":"trace[278595318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5057; }","duration":"193.369111ms","start":"2026-05-29T11:32:09.125301Z","end":"2026-05-29T11:32:09.31867Z","steps":["trace[278595318] 'agreement among raft nodes before linearized reading' (duration: 193.290679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.318588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:09.016121Z","time spent":"302.445009ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-29T11:32:09.318936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.958519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-29T11:32:09.318974Z","caller":"traceutil/trace.go:171","msg":"trace[1105456730] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5057; }","duration":"192.008ms","start":"2026-05-29T11:32:09.126957Z","end":"2026-05-29T11:32:09.318965Z","steps":["trace[1105456730] 'agreement among raft nodes before linearized reading' (duration: 191.887258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.319165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.682006ms","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-29T11:32:09.319219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.148434ms","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-29T11:32:09.319315Z","caller":"traceutil/trace.go:171","msg":"trace[1778882867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5057; }","duration":"303.266616ms","start":"2026-05-29T11:32:09.016036Z","end":"2026-05-29T11:32:09.319302Z","steps":["trace[1778882867] 'agreement among raft nodes before linearized reading' (duration: 303.082573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.319356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:09.016024Z","time spent":"303.322128ms","remote":"127.0.0.1:56520","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-29T11:32:09.319216Z","caller":"traceutil/trace.go:171","msg":"trace[1579989223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5057; }","duration":"297.745757ms","start":"2026-05-29T11:32:09.021457Z","end":"2026-05-29T11:32:09.319202Z","steps":["trace[1579989223] 'agreement among raft nodes before linearized reading' (duration: 297.503731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.319394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.900152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-29T11:32:09.319509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.325089ms","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-29T11:32:09.31962Z","caller":"traceutil/trace.go:171","msg":"trace[569310901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5057; }","duration":"321.469192ms","start":"2026-05-29T11:32:08.998135Z","end":"2026-05-29T11:32:09.319605Z","steps":["trace[569310901] 'agreement among raft nodes before linearized reading' (duration: 321.285829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.319665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.998121Z","time spent":"321.534964ms","remote":"127.0.0.1:56520","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-29T11:32:09.319739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.022956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-29T11:32:09.319765Z","caller":"traceutil/trace.go:171","msg":"trace[1614425875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5057; }","duration":"322.121066ms","start":"2026-05-29T11:32:08.997391Z","end":"2026-05-29T11:32:09.319512Z","steps":["trace[1614425875] 'agreement among raft nodes before linearized reading' (duration: 321.744608ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:32:09.319782Z","caller":"traceutil/trace.go:171","msg":"trace[221756249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5057; }","duration":"300.086318ms","start":"2026-05-29T11:32:09.019686Z","end":"2026-05-29T11:32:09.319772Z","steps":["trace[221756249] 'agreement among raft nodes before linearized reading' (duration: 299.979986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.319816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:09.019675Z","time spent":"300.134879ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T11:32:09.319915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.997371Z","time spent":"322.527176ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T11:32:09.317744Z","caller":"traceutil/trace.go:171","msg":"trace[1879650918] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"328.145097ms","start":"2026-05-29T11:32:08.989541Z","end":"2026-05-29T11:32:09.317686Z","steps":["trace[1879650918] 'process raft request' (duration: 327.384291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:09.321068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:08.989536Z","time spent":"331.417859ms","remote":"127.0.0.1:56448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":949,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:32:09.59832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.714068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T11:32:09.598499Z","caller":"traceutil/trace.go:171","msg":"trace[174489424] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5064; }","duration":"135.965864ms","start":"2026-05-29T11:32:09.462512Z","end":"2026-05-29T11:32:09.598477Z","steps":["trace[174489424] 'count revisions from in-memory index tree' (duration: 135.611297ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:32:45.502537Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2807} {"level":"info","ts":"2026-05-29T11:32:45.589397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2807,"took":"85.071045ms","hash":3914083154,"current-db-size-bytes":53202944,"current-db-size":"53 MB","current-db-size-in-use-bytes":28401664,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-29T11:32:45.589452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3914083154,"revision":2807,"compact-revision":-1} {"level":"info","ts":"2026-05-29T11:36:23.040637Z","caller":"traceutil/trace.go:171","msg":"trace[1877194291] transaction","detail":"{read_only:false; response_revision:6890; number_of_response:1; }","duration":"186.809274ms","start":"2026-05-29T11:36:22.853806Z","end":"2026-05-29T11:36:23.040615Z","steps":["trace[1877194291] 'process raft request' (duration: 186.68318ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:36:23.040786Z","caller":"traceutil/trace.go:171","msg":"trace[1019041854] transaction","detail":"{read_only:false; response_revision:6892; number_of_response:1; }","duration":"184.980545ms","start":"2026-05-29T11:36:22.855782Z","end":"2026-05-29T11:36:23.040762Z","steps":["trace[1019041854] 'process raft request' (duration: 184.889481ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:36:23.040819Z","caller":"traceutil/trace.go:171","msg":"trace[1515120440] transaction","detail":"{read_only:false; response_revision:6889; number_of_response:1; }","duration":"187.706914ms","start":"2026-05-29T11:36:22.853088Z","end":"2026-05-29T11:36:23.040795Z","steps":["trace[1515120440] 'process raft request' (duration: 148.665525ms)","trace[1515120440] 'compare' (duration: 38.477261ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:36:23.040965Z","caller":"traceutil/trace.go:171","msg":"trace[1011925560] transaction","detail":"{read_only:false; response_revision:6891; number_of_response:1; }","duration":"186.375021ms","start":"2026-05-29T11:36:22.854581Z","end":"2026-05-29T11:36:23.040956Z","steps":["trace[1011925560] 'process raft request' (duration: 185.999608ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:08.486563Z","caller":"traceutil/trace.go:171","msg":"trace[863472151] transaction","detail":"{read_only:false; response_revision:7148; number_of_response:1; }","duration":"127.747698ms","start":"2026-05-29T11:37:08.358783Z","end":"2026-05-29T11:37:08.48653Z","steps":["trace[863472151] 'process raft request' (duration: 101.311709ms)","trace[863472151] 'compare' (duration: 26.294185ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:09.545376Z","caller":"traceutil/trace.go:171","msg":"trace[784747127] transaction","detail":"{read_only:false; response_revision:7154; number_of_response:1; }","duration":"153.077537ms","start":"2026-05-29T11:37:09.392263Z","end":"2026-05-29T11:37:09.545341Z","steps":["trace[784747127] 'process raft request' (duration: 123.043678ms)","trace[784747127] 'compare' (duration: 29.628436ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:09.574706Z","caller":"traceutil/trace.go:171","msg":"trace[86215232] transaction","detail":"{read_only:false; response_revision:7156; number_of_response:1; }","duration":"181.819547ms","start":"2026-05-29T11:37:09.392861Z","end":"2026-05-29T11:37:09.574681Z","steps":["trace[86215232] 'process raft request' (duration: 181.766175ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:09.574782Z","caller":"traceutil/trace.go:171","msg":"trace[1729473521] transaction","detail":"{read_only:false; response_revision:7155; number_of_response:1; }","duration":"182.051123ms","start":"2026-05-29T11:37:09.392703Z","end":"2026-05-29T11:37:09.574754Z","steps":["trace[1729473521] 'process raft request' (duration: 181.779465ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:11.07899Z","caller":"traceutil/trace.go:171","msg":"trace[1218912925] transaction","detail":"{read_only:false; response_revision:7160; number_of_response:1; }","duration":"140.398666ms","start":"2026-05-29T11:37:10.938565Z","end":"2026-05-29T11:37:11.078964Z","steps":["trace[1218912925] 'process raft request' (duration: 140.257952ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:11.726384Z","caller":"traceutil/trace.go:171","msg":"trace[1419639765] linearizableReadLoop","detail":"{readStateIndex:7439; appliedIndex:7438; }","duration":"117.841268ms","start":"2026-05-29T11:37:11.608521Z","end":"2026-05-29T11:37:11.726363Z","steps":["trace[1419639765] 'read index received' (duration: 116.491715ms)","trace[1419639765] 'applied index is now lower than readState.Index' (duration: 1.348763ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:11.726464Z","caller":"traceutil/trace.go:171","msg":"trace[1647778137] transaction","detail":"{read_only:false; response_revision:7165; number_of_response:1; }","duration":"141.108816ms","start":"2026-05-29T11:37:11.585328Z","end":"2026-05-29T11:37:11.726437Z","steps":["trace[1647778137] 'process raft request' (duration: 139.60123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:37:11.726491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.945991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:37:11.726658Z","caller":"traceutil/trace.go:171","msg":"trace[1696827448] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:7165; }","duration":"118.178678ms","start":"2026-05-29T11:37:11.608468Z","end":"2026-05-29T11:37:11.726647Z","steps":["trace[1696827448] 'agreement among raft nodes before linearized reading' (duration: 117.972242ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:13.71947Z","caller":"traceutil/trace.go:171","msg":"trace[83025082] linearizableReadLoop","detail":"{readStateIndex:7449; appliedIndex:7448; }","duration":"109.10957ms","start":"2026-05-29T11:37:13.610336Z","end":"2026-05-29T11:37:13.719445Z","steps":["trace[83025082] 'read index received' (duration: 93.364455ms)","trace[83025082] 'applied index is now lower than readState.Index' (duration: 15.743585ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:13.719602Z","caller":"traceutil/trace.go:171","msg":"trace[780048549] transaction","detail":"{read_only:false; response_revision:7175; number_of_response:1; }","duration":"113.827093ms","start":"2026-05-29T11:37:13.605741Z","end":"2026-05-29T11:37:13.719568Z","steps":["trace[780048549] 'process raft request' (duration: 97.824361ms)","trace[780048549] 'compare' (duration: 15.780246ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:37:13.719691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.333426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-05-29T11:37:13.719743Z","caller":"traceutil/trace.go:171","msg":"trace[1876165174] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret; range_end:; response_count:1; response_revision:7175; }","duration":"109.432268ms","start":"2026-05-29T11:37:13.610296Z","end":"2026-05-29T11:37:13.719728Z","steps":["trace[1876165174] 'agreement among raft nodes before linearized reading' (duration: 109.230882ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:19.568861Z","caller":"traceutil/trace.go:171","msg":"trace[633109238] transaction","detail":"{read_only:false; response_revision:7204; number_of_response:1; }","duration":"140.490052ms","start":"2026-05-29T11:37:19.428336Z","end":"2026-05-29T11:37:19.568826Z","steps":["trace[633109238] 'process raft request' (duration: 139.859252ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:19.729008Z","caller":"traceutil/trace.go:171","msg":"trace[694264936] transaction","detail":"{read_only:false; response_revision:7205; number_of_response:1; }","duration":"152.750245ms","start":"2026-05-29T11:37:19.576172Z","end":"2026-05-29T11:37:19.728922Z","steps":["trace[694264936] 'process raft request' (duration: 52.783612ms)","trace[694264936] 'compare' (duration: 99.699045ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:23.383602Z","caller":"traceutil/trace.go:171","msg":"trace[1639191908] transaction","detail":"{read_only:false; response_revision:7221; number_of_response:1; }","duration":"111.760075ms","start":"2026-05-29T11:37:23.271798Z","end":"2026-05-29T11:37:23.383558Z","steps":["trace[1639191908] 'process raft request' (duration: 111.58367ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:23.967137Z","caller":"traceutil/trace.go:171","msg":"trace[1153110942] linearizableReadLoop","detail":"{readStateIndex:7500; appliedIndex:7499; }","duration":"111.017494ms","start":"2026-05-29T11:37:23.856084Z","end":"2026-05-29T11:37:23.967102Z","steps":["trace[1153110942] 'read index received' (duration: 32.298122ms)","trace[1153110942] 'applied index is now lower than readState.Index' (duration: 78.717372ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:23.967191Z","caller":"traceutil/trace.go:171","msg":"trace[227637200] transaction","detail":"{read_only:false; response_revision:7224; number_of_response:1; }","duration":"111.638592ms","start":"2026-05-29T11:37:23.855506Z","end":"2026-05-29T11:37:23.967145Z","steps":["trace[227637200] 'process raft request' (duration: 33.005613ms)","trace[227637200] 'compare' (duration: 78.324249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:37:23.967445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.344893ms","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-29T11:37:23.967528Z","caller":"traceutil/trace.go:171","msg":"trace[669187537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7224; }","duration":"111.497249ms","start":"2026-05-29T11:37:23.856015Z","end":"2026-05-29T11:37:23.967512Z","steps":["trace[669187537] 'agreement among raft nodes before linearized reading' (duration: 111.21913ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:24.180439Z","caller":"traceutil/trace.go:171","msg":"trace[1831826618] transaction","detail":"{read_only:false; response_revision:7226; number_of_response:1; }","duration":"160.636915ms","start":"2026-05-29T11:37:24.019759Z","end":"2026-05-29T11:37:24.180396Z","steps":["trace[1831826618] 'process raft request' (duration: 122.91946ms)","trace[1831826618] 'compare' (duration: 37.53873ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:24.946113Z","caller":"traceutil/trace.go:171","msg":"trace[936091089] transaction","detail":"{read_only:false; response_revision:7229; number_of_response:1; }","duration":"101.672715ms","start":"2026-05-29T11:37:24.844398Z","end":"2026-05-29T11:37:24.94607Z","steps":["trace[936091089] 'process raft request' (duration: 22.300586ms)","trace[936091089] 'compare' (duration: 78.76937ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:25.992495Z","caller":"traceutil/trace.go:171","msg":"trace[655500203] transaction","detail":"{read_only:false; response_revision:7232; number_of_response:1; }","duration":"104.307861ms","start":"2026-05-29T11:37:25.888152Z","end":"2026-05-29T11:37:25.992459Z","steps":["trace[655500203] 'process raft request' (duration: 104.154637ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:27.645187Z","caller":"traceutil/trace.go:171","msg":"trace[1953901605] transaction","detail":"{read_only:false; response_revision:7242; number_of_response:1; }","duration":"154.720307ms","start":"2026-05-29T11:37:27.49044Z","end":"2026-05-29T11:37:27.64516Z","steps":["trace[1953901605] 'process raft request' (duration: 101.55163ms)","trace[1953901605] 'compare' (duration: 53.058723ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:28.375735Z","caller":"traceutil/trace.go:171","msg":"trace[823404710] transaction","detail":"{read_only:false; response_revision:7248; number_of_response:1; }","duration":"100.961189ms","start":"2026-05-29T11:37:28.274747Z","end":"2026-05-29T11:37:28.375708Z","steps":["trace[823404710] 'process raft request' (duration: 100.806454ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:29.595355Z","caller":"traceutil/trace.go:171","msg":"trace[785426931] transaction","detail":"{read_only:false; response_revision:7254; number_of_response:1; }","duration":"102.273973ms","start":"2026-05-29T11:37:29.493036Z","end":"2026-05-29T11:37:29.59531Z","steps":["trace[785426931] 'process raft request' (duration: 102.034366ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:30.226265Z","caller":"traceutil/trace.go:171","msg":"trace[1019724402] transaction","detail":"{read_only:false; response_revision:7258; number_of_response:1; }","duration":"115.043975ms","start":"2026-05-29T11:37:30.111196Z","end":"2026-05-29T11:37:30.22624Z","steps":["trace[1019724402] 'process raft request' (duration: 72.570475ms)","trace[1019724402] 'compare' (duration: 42.359226ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:31.717623Z","caller":"traceutil/trace.go:171","msg":"trace[2131094546] transaction","detail":"{read_only:false; response_revision:7263; number_of_response:1; }","duration":"113.388161ms","start":"2026-05-29T11:37:31.604196Z","end":"2026-05-29T11:37:31.717584Z","steps":["trace[2131094546] 'process raft request' (duration: 113.172064ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:31.897869Z","caller":"traceutil/trace.go:171","msg":"trace[1041370615] transaction","detail":"{read_only:false; response_revision:7264; number_of_response:1; }","duration":"115.719801ms","start":"2026-05-29T11:37:31.782123Z","end":"2026-05-29T11:37:31.897843Z","steps":["trace[1041370615] 'process raft request' (duration: 30.211048ms)","trace[1041370615] 'compare' (duration: 85.384409ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:45.511556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5386} {"level":"info","ts":"2026-05-29T11:37:45.601936Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5386,"took":"88.640672ms","hash":1960404504,"current-db-size-bytes":53202944,"current-db-size":"53 MB","current-db-size-in-use-bytes":18350080,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-29T11:37:45.601994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1960404504,"revision":5386,"compact-revision":2807} {"level":"info","ts":"2026-05-29T11:38:22.659818Z","caller":"traceutil/trace.go:171","msg":"trace[1659994172] transaction","detail":"{read_only:false; response_revision:7521; number_of_response:1; }","duration":"115.192254ms","start":"2026-05-29T11:38:22.544582Z","end":"2026-05-29T11:38:22.659775Z","steps":["trace[1659994172] 'process raft request' (duration: 86.149442ms)","trace[1659994172] 'compare' (duration: 28.769655ms)"],"step_count":2}