{"level":"warn","ts":"2026-04-01T23:26:18.551838Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-01T23:26:18.551921Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-04-01T23:26:18.551994Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-01T23:26:18.552001Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]}
{"level":"info","ts":"2026-04-01T23:26:18.55202Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-04-01T23:26:18.552638Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]}
{"level":"info","ts":"2026-04-01T23:26:18.552705Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-04-01T23:26:18.556098Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.184171ms"}
{"level":"info","ts":"2026-04-01T23:26:18.563459Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"}
{"level":"info","ts":"2026-04-01T23:26:18.56353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"}
{"level":"info","ts":"2026-04-01T23:26:18.563548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"}
{"level":"info","ts":"2026-04-01T23:26:18.563561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-01T23:26:18.56357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"}
{"level":"info","ts":"2026-04-01T23:26:18.563608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"}
{"level":"warn","ts":"2026-04-01T23:26:18.56948Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-01T23:26:18.570855Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-01T23:26:18.573213Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-04-01T23:26:18.576591Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-01T23:26:18.576805Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-01T23:26:18.577045Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-01T23:26:18.577139Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-01T23:26:18.577166Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-01T23:26:18.581068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"}
{"level":"info","ts":"2026-04-01T23:26:18.581637Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]}
{"level":"info","ts":"2026-04-01T23:26:18.582739Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-04-01T23:26:18.582978Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"}
{"level":"info","ts":"2026-04-01T23:26:18.583018Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-01T23:26:18.58298Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-01T23:26:18.583037Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"}
{"level":"info","ts":"2026-04-01T23:26:19.067076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"}
{"level":"info","ts":"2026-04-01T23:26:19.067147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-01T23:26:19.067203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"}
{"level":"info","ts":"2026-04-01T23:26:19.067231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"}
{"level":"info","ts":"2026-04-01T23:26:19.067242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"}
{"level":"info","ts":"2026-04-01T23:26:19.067261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"}
{"level":"info","ts":"2026-04-01T23:26:19.067274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"}
{"level":"info","ts":"2026-04-01T23:26:19.068194Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-01T23:26:19.069038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-01T23:26:19.069036Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-01T23:26:19.069202Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-01T23:26:19.069777Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-01T23:26:19.069769Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-01T23:26:19.069857Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-01T23:26:19.069854Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-01T23:26:19.069883Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-01T23:26:19.071533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-01T23:26:19.071948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"}
{"level":"info","ts":"2026-04-01T23:26:38.642509Z","caller":"traceutil/trace.go:171","msg":"trace[1793788866] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"120.800892ms","start":"2026-04-01T23:26:38.521678Z","end":"2026-04-01T23:26:38.642479Z","steps":["trace[1793788866] 'process raft request'  (duration: 91.290524ms)","trace[1793788866] 'compare'  (duration: 29.364104ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:26:39.109385Z","caller":"traceutil/trace.go:171","msg":"trace[1379374325] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"400.750112ms","start":"2026-04-01T23:26:38.708609Z","end":"2026-04-01T23:26:39.109359Z","steps":["trace[1379374325] 'process raft request'  (duration: 400.602038ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:39.109959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:38.708585Z","time spent":"400.884656ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:410 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-04-01T23:26:44.478772Z","caller":"traceutil/trace.go:171","msg":"trace[1169880278] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"367.748465ms","start":"2026-04-01T23:26:44.111Z","end":"2026-04-01T23:26:44.478749Z","steps":["trace[1169880278] 'process raft request'  (duration: 367.610622ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:44.478905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:44.110968Z","time spent":"367.877419ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:373 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:471 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-04-01T23:26:44.795163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.785539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-01T23:26:44.795338Z","caller":"traceutil/trace.go:171","msg":"trace[332645128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"225.999195ms","start":"2026-04-01T23:26:44.569305Z","end":"2026-04-01T23:26:44.795304Z","steps":["trace[332645128] 'range keys from in-memory index tree'  (duration: 225.729858ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:44.795632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.02232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-01T23:26:44.795785Z","caller":"traceutil/trace.go:171","msg":"trace[118525532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"132.245187ms","start":"2026-04-01T23:26:44.663506Z","end":"2026-04-01T23:26:44.795751Z","steps":["trace[118525532] 'range keys from in-memory index tree'  (duration: 131.328912ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:26:54.485315Z","caller":"traceutil/trace.go:171","msg":"trace[379406237] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"167.726928ms","start":"2026-04-01T23:26:54.317507Z","end":"2026-04-01T23:26:54.485234Z","steps":["trace[379406237] 'process raft request'  (duration: 163.647094ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:55.680977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675773924034531,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-04-01T23:26:55.853101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.579428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:26:55.853209Z","caller":"traceutil/trace.go:171","msg":"trace[1053381475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"955.778793ms","start":"2026-04-01T23:26:54.897409Z","end":"2026-04-01T23:26:55.853187Z","steps":["trace[1053381475] 'range keys from in-memory index tree'  (duration: 955.518087ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:55.853303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:54.897393Z","time spent":"955.900238ms","remote":"127.0.0.1:42732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-04-01T23:26:55.853294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.608324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f99jq\" ","response":"range_response_count:1 size:16961"}
{"level":"info","ts":"2026-04-01T23:26:55.853359Z","caller":"traceutil/trace.go:171","msg":"trace[125672513] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f99jq; range_end:; response_count:1; response_revision:578; }","duration":"930.709037ms","start":"2026-04-01T23:26:54.922634Z","end":"2026-04-01T23:26:55.853343Z","steps":["trace[125672513] 'range keys from in-memory index tree'  (duration: 930.449161ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:55.853367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.06103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-01T23:26:55.853436Z","caller":"traceutil/trace.go:171","msg":"trace[1902937841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"941.180073ms","start":"2026-04-01T23:26:54.912238Z","end":"2026-04-01T23:26:55.853418Z","steps":["trace[1902937841] 'range keys from in-memory index tree'  (duration: 940.866575ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:55.853489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:54.912213Z","time spent":"941.269625ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-01T23:26:55.853392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:54.922564Z","time spent":"930.821701ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-f99jq\" "}
{"level":"info","ts":"2026-04-01T23:26:55.853952Z","caller":"traceutil/trace.go:171","msg":"trace[1500738329] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"821.573637ms","start":"2026-04-01T23:26:55.032309Z","end":"2026-04-01T23:26:55.853883Z","steps":["trace[1500738329] 'process raft request'  (duration: 818.459167ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:26:55.853959Z","caller":"traceutil/trace.go:171","msg":"trace[1148933661] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"673.857078ms","start":"2026-04-01T23:26:55.179965Z","end":"2026-04-01T23:26:55.853822Z","steps":["trace[1148933661] 'read index received'  (duration: 670.641496ms)","trace[1148933661] 'applied index is now lower than readState.Index'  (duration: 3.214032ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:26:55.854105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.120454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-04-01T23:26:55.854154Z","caller":"traceutil/trace.go:171","msg":"trace[811592467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"674.214016ms","start":"2026-04-01T23:26:55.179925Z","end":"2026-04-01T23:26:55.854139Z","steps":["trace[811592467] 'agreement among raft nodes before linearized reading'  (duration: 674.020052ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:55.854162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:55.032272Z","time spent":"821.793302ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:576 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036225 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"warn","ts":"2026-04-01T23:26:55.854197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:55.179909Z","time spent":"674.280867ms","remote":"127.0.0.1:43008","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-04-01T23:26:55.854562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.706656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-01T23:26:55.854738Z","caller":"traceutil/trace.go:171","msg":"trace[2034867506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"942.513857ms","start":"2026-04-01T23:26:54.912172Z","end":"2026-04-01T23:26:55.854686Z","steps":["trace[2034867506] 'range keys from in-memory index tree'  (duration: 940.774672ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:55.85483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:54.912162Z","time spent":"942.63644ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-04-01T23:26:57.374016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.176286ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:26:57.374153Z","caller":"traceutil/trace.go:171","msg":"trace[1344913058] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:605; }","duration":"264.33602ms","start":"2026-04-01T23:26:57.109796Z","end":"2026-04-01T23:26:57.374133Z","steps":["trace[1344913058] 'range keys from in-memory index tree'  (duration: 264.152636ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:26:57.374308Z","caller":"traceutil/trace.go:171","msg":"trace[1657644642] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"320.676361ms","start":"2026-04-01T23:26:57.053586Z","end":"2026-04-01T23:26:57.374262Z","steps":["trace[1657644642] 'process raft request'  (duration: 319.591344ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:57.374457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:57.053565Z","time spent":"320.815485ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" value_size:3111 >> failure:<>"}
{"level":"info","ts":"2026-04-01T23:26:57.37878Z","caller":"traceutil/trace.go:171","msg":"trace[935812476] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"325.00503ms","start":"2026-04-01T23:26:57.053745Z","end":"2026-04-01T23:26:57.37875Z","steps":["trace[935812476] 'process raft request'  (duration: 324.817186ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:26:57.37893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:57.053724Z","time spent":"325.132073ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" value_size:3013 >> failure:<>"}
{"level":"info","ts":"2026-04-01T23:26:57.381041Z","caller":"traceutil/trace.go:171","msg":"trace[1882159697] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"128.653196ms","start":"2026-04-01T23:26:57.252351Z","end":"2026-04-01T23:26:57.381004Z","steps":["trace[1882159697] 'process raft request'  (duration: 128.424011ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:27:37.557466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.500828ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924035624 > lease_revoke:<id:4d269d4b5e83ca5a>","response":"size:28"}
{"level":"info","ts":"2026-04-01T23:27:37.557606Z","caller":"traceutil/trace.go:171","msg":"trace[1085729024] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"328.19101ms","start":"2026-04-01T23:27:37.229386Z","end":"2026-04-01T23:27:37.557577Z","steps":["trace[1085729024] 'read index received'  (duration: 14.487187ms)","trace[1085729024] 'applied index is now lower than readState.Index'  (duration: 313.702313ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:27:37.557822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.179844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:27:37.557863Z","caller":"traceutil/trace.go:171","msg":"trace[501126882] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1136; }","duration":"165.258175ms","start":"2026-04-01T23:27:37.392594Z","end":"2026-04-01T23:27:37.557852Z","steps":["trace[501126882] 'agreement among raft nodes before linearized reading'  (duration: 165.144602ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:27:37.557923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.53203ms","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-04-01T23:27:37.557993Z","caller":"traceutil/trace.go:171","msg":"trace[304025522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1136; }","duration":"328.656743ms","start":"2026-04-01T23:27:37.229324Z","end":"2026-04-01T23:27:37.55798Z","steps":["trace[304025522] 'agreement among raft nodes before linearized reading'  (duration: 328.362356ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:27:37.558028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:37.229299Z","time spent":"328.723004ms","remote":"127.0.0.1:43008","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-04-01T23:27:48.542798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.999715ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924035850 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1\" mod_revision:1239 > success:<request_put:<key:\"/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1\" value_size:105286 >> failure:<request_range:<key:\"/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-01T23:27:48.542935Z","caller":"traceutil/trace.go:171","msg":"trace[836137627] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"158.252608ms","start":"2026-04-01T23:27:48.384647Z","end":"2026-04-01T23:27:48.542899Z","steps":["trace[836137627] 'process raft request'  (duration: 40.071267ms)","trace[836137627] 'compare'  (duration: 117.821181ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:28:21.253651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.8902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-01T23:28:21.253727Z","caller":"traceutil/trace.go:171","msg":"trace[109660614] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1784; }","duration":"277.013853ms","start":"2026-04-01T23:28:20.976698Z","end":"2026-04-01T23:28:21.253711Z","steps":["trace[109660614] 'count revisions from in-memory index tree'  (duration: 276.798656ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:21.841239Z","caller":"traceutil/trace.go:171","msg":"trace[757751330] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1854; }","duration":"444.099757ms","start":"2026-04-01T23:28:21.397105Z","end":"2026-04-01T23:28:21.841205Z","steps":["trace[757751330] 'read index received'  (duration: 443.744638ms)","trace[757751330] 'applied index is now lower than readState.Index'  (duration: 354.059µs)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:28:21.841297Z","caller":"traceutil/trace.go:171","msg":"trace[2055051252] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"477.933012ms","start":"2026-04-01T23:28:21.363322Z","end":"2026-04-01T23:28:21.841255Z","steps":["trace[2055051252] 'process raft request'  (duration: 477.657095ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:28:21.841422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.308612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b798ac9c-14d2-43f5-af76-2ba0f7eba050\" ","response":"range_response_count:1 size:3743"}
{"level":"info","ts":"2026-04-01T23:28:21.84149Z","caller":"traceutil/trace.go:171","msg":"trace[475991176] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b798ac9c-14d2-43f5-af76-2ba0f7eba050; range_end:; response_count:1; response_revision:1785; }","duration":"444.391594ms","start":"2026-04-01T23:28:21.397085Z","end":"2026-04-01T23:28:21.841477Z","steps":["trace[475991176] 'agreement among raft nodes before linearized reading'  (duration: 444.271391ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:28:21.841483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.363311Z","time spent":"478.069116ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b798ac9c-14d2-43f5-af76-2ba0f7eba050\" mod_revision:1735 > success:<request_put:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b798ac9c-14d2-43f5-af76-2ba0f7eba050\" value_size:3628 >> failure:<request_range:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b798ac9c-14d2-43f5-af76-2ba0f7eba050\" > >"}
{"level":"warn","ts":"2026-04-01T23:28:21.841526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.397013Z","time spent":"444.503637ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b798ac9c-14d2-43f5-af76-2ba0f7eba050\" "}
{"level":"warn","ts":"2026-04-01T23:28:21.841602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.041035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:28:21.841692Z","caller":"traceutil/trace.go:171","msg":"trace[1371041026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1785; }","duration":"262.220529ms","start":"2026-04-01T23:28:21.57945Z","end":"2026-04-01T23:28:21.84167Z","steps":["trace[1371041026] 'agreement among raft nodes before linearized reading'  (duration: 261.957382ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:28:21.978559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.892508ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924036926 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:1767 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-01T23:28:21.978727Z","caller":"traceutil/trace.go:171","msg":"trace[1769265155] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"126.839556ms","start":"2026-04-01T23:28:21.85187Z","end":"2026-04-01T23:28:21.978709Z","steps":["trace[1769265155] 'read index received'  (duration: 16.635199ms)","trace[1769265155] 'applied index is now lower than readState.Index'  (duration: 110.202867ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:28:21.978836Z","caller":"traceutil/trace.go:171","msg":"trace[27523026] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"131.803552ms","start":"2026-04-01T23:28:21.84696Z","end":"2026-04-01T23:28:21.978763Z","steps":["trace[27523026] 'process raft request'  (duration: 21.620095ms)","trace[27523026] 'compare'  (duration: 109.770276ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:28:21.978976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.072942ms","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-04-01T23:28:21.979065Z","caller":"traceutil/trace.go:171","msg":"trace[268271927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1786; }","duration":"127.209666ms","start":"2026-04-01T23:28:21.851835Z","end":"2026-04-01T23:28:21.979045Z","steps":["trace[268271927] 'agreement among raft nodes before linearized reading'  (duration: 126.9466ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:28:22.581006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.623914ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924036934 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/162.253.55.62\" mod_revision:1654 > success:<request_put:<key:\"/registry/masterleases/162.253.55.62\" value_size:66 lease:5559303737069261124 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.62\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-01T23:28:22.581158Z","caller":"traceutil/trace.go:171","msg":"trace[1792869665] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"364.835813ms","start":"2026-04-01T23:28:22.216261Z","end":"2026-04-01T23:28:22.581097Z","steps":["trace[1792869665] 'read index received'  (duration: 38.831µs)","trace[1792869665] 'applied index is now lower than readState.Index'  (duration: 364.795142ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:28:22.581257Z","caller":"traceutil/trace.go:171","msg":"trace[1004110944] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"524.54661ms","start":"2026-04-01T23:28:22.056699Z","end":"2026-04-01T23:28:22.581245Z","steps":["trace[1004110944] 'process raft request'  (duration: 63.554537ms)","trace[1004110944] 'compare'  (duration: 460.294576ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:28:22.58133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:22.056676Z","time spent":"524.614561ms","remote":"127.0.0.1:42766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/masterleases/162.253.55.62\" mod_revision:1654 > success:<request_put:<key:\"/registry/masterleases/162.253.55.62\" value_size:66 lease:5559303737069261124 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.62\" > >"}
{"level":"warn","ts":"2026-04-01T23:28:22.581464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.163547ms","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-04-01T23:28:22.581486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.594489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-04-01T23:28:22.581515Z","caller":"traceutil/trace.go:171","msg":"trace[194664278] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1788; }","duration":"298.262319ms","start":"2026-04-01T23:28:22.283239Z","end":"2026-04-01T23:28:22.581501Z","steps":["trace[194664278] 'agreement among raft nodes before linearized reading'  (duration: 298.052823ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:28:22.581509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.372401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-01T23:28:22.581533Z","caller":"traceutil/trace.go:171","msg":"trace[766439553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1788; }","duration":"277.68374ms","start":"2026-04-01T23:28:22.303836Z","end":"2026-04-01T23:28:22.58152Z","steps":["trace[766439553] 'agreement among raft nodes before linearized reading'  (duration: 277.524376ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:22.581571Z","caller":"traceutil/trace.go:171","msg":"trace[211335250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1788; }","duration":"271.474954ms","start":"2026-04-01T23:28:22.310079Z","end":"2026-04-01T23:28:22.581554Z","steps":["trace[211335250] 'agreement among raft nodes before linearized reading'  (duration: 271.276448ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:28:22.581613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.123562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"warn","ts":"2026-04-01T23:28:22.581653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.357196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"}
{"level":"info","ts":"2026-04-01T23:28:22.581809Z","caller":"traceutil/trace.go:171","msg":"trace[8409385] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1788; }","duration":"365.570851ms","start":"2026-04-01T23:28:22.216213Z","end":"2026-04-01T23:28:22.581784Z","steps":["trace[8409385] 'agreement among raft nodes before linearized reading'  (duration: 365.112469ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:22.581698Z","caller":"traceutil/trace.go:171","msg":"trace[43449080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1788; }","duration":"263.242935ms","start":"2026-04-01T23:28:22.318436Z","end":"2026-04-01T23:28:22.581679Z","steps":["trace[43449080] 'agreement among raft nodes before linearized reading'  (duration: 262.931017ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:28:22.58189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:22.21619Z","time spent":"365.686734ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-01T23:28:22.581951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.551505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-01T23:28:22.581997Z","caller":"traceutil/trace.go:171","msg":"trace[1868428817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1788; }","duration":"271.615407ms","start":"2026-04-01T23:28:22.310368Z","end":"2026-04-01T23:28:22.581984Z","steps":["trace[1868428817] 'agreement among raft nodes before linearized reading'  (duration: 270.93405ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:26.030376Z","caller":"traceutil/trace.go:171","msg":"trace[13641325] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1821; }","duration":"102.255155ms","start":"2026-04-01T23:28:25.928098Z","end":"2026-04-01T23:28:26.030353Z","steps":["trace[13641325] 'compare'  (duration: 91.897624ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:26.356758Z","caller":"traceutil/trace.go:171","msg":"trace[76291872] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"153.51583ms","start":"2026-04-01T23:28:26.203209Z","end":"2026-04-01T23:28:26.356724Z","steps":["trace[76291872] 'process raft request'  (duration: 97.922795ms)","trace[76291872] 'compare'  (duration: 55.454622ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:28:26.563992Z","caller":"traceutil/trace.go:171","msg":"trace[1504925617] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"101.491935ms","start":"2026-04-01T23:28:26.462481Z","end":"2026-04-01T23:28:26.563973Z","steps":["trace[1504925617] 'process raft request'  (duration: 101.358172ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:26.638468Z","caller":"traceutil/trace.go:171","msg":"trace[1352942025] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"115.308395ms","start":"2026-04-01T23:28:26.523131Z","end":"2026-04-01T23:28:26.63844Z","steps":["trace[1352942025] 'process raft request'  (duration: 115.080159ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:26.638713Z","caller":"traceutil/trace.go:171","msg":"trace[5393422] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1902; }","duration":"105.659571ms","start":"2026-04-01T23:28:26.533011Z","end":"2026-04-01T23:28:26.63867Z","steps":["trace[5393422] 'read index received'  (duration: 30.897441ms)","trace[5393422] 'applied index is now lower than readState.Index'  (duration: 74.75854ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:28:26.638843Z","caller":"traceutil/trace.go:171","msg":"trace[354574090] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"111.673303ms","start":"2026-04-01T23:28:26.527075Z","end":"2026-04-01T23:28:26.638749Z","steps":["trace[354574090] 'process raft request'  (duration: 111.294813ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:28:26.639125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.075341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"}
{"level":"info","ts":"2026-04-01T23:28:26.639246Z","caller":"traceutil/trace.go:171","msg":"trace[567339347] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1833; }","duration":"106.282577ms","start":"2026-04-01T23:28:26.532932Z","end":"2026-04-01T23:28:26.639215Z","steps":["trace[567339347] 'agreement among raft nodes before linearized reading'  (duration: 105.871336ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:38.271398Z","caller":"traceutil/trace.go:171","msg":"trace[639428067] linearizableReadLoop","detail":"{readStateIndex:1999; appliedIndex:1998; }","duration":"138.036979ms","start":"2026-04-01T23:28:38.133336Z","end":"2026-04-01T23:28:38.271373Z","steps":["trace[639428067] 'read index received'  (duration: 137.745691ms)","trace[639428067] 'applied index is now lower than readState.Index'  (duration: 290.618µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:28:38.271608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.258314ms","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-04-01T23:28:38.271647Z","caller":"traceutil/trace.go:171","msg":"trace[579613221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1924; }","duration":"138.359707ms","start":"2026-04-01T23:28:38.133277Z","end":"2026-04-01T23:28:38.271637Z","steps":["trace[579613221] 'agreement among raft nodes before linearized reading'  (duration: 138.192263ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:28:38.272035Z","caller":"traceutil/trace.go:171","msg":"trace[70490971] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"164.229881ms","start":"2026-04-01T23:28:38.107589Z","end":"2026-04-01T23:28:38.271819Z","steps":["trace[70490971] 'process raft request'  (duration: 163.624737ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:07.218025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.47186ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-01T23:29:07.21811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.10187ms","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-04-01T23:29:07.218201Z","caller":"traceutil/trace.go:171","msg":"trace[1033271992] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2086; }","duration":"108.655065ms","start":"2026-04-01T23:29:07.10952Z","end":"2026-04-01T23:29:07.218175Z","steps":["trace[1033271992] 'range keys from in-memory index tree'  (duration: 108.451829ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:07.218227Z","caller":"traceutil/trace.go:171","msg":"trace[864040477] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2086; }","duration":"301.345407ms","start":"2026-04-01T23:29:06.916858Z","end":"2026-04-01T23:29:07.218204Z","steps":["trace[864040477] 'range keys from in-memory index tree'  (duration: 300.869095ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:07.218278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:06.916838Z","time spent":"301.427599ms","remote":"127.0.0.1:55470","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-04-01T23:29:07.218652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.79149ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924037654 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2078 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-01T23:29:07.218871Z","caller":"traceutil/trace.go:171","msg":"trace[1021828041] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"246.387248ms","start":"2026-04-01T23:29:06.972465Z","end":"2026-04-01T23:29:07.218852Z","steps":["trace[1021828041] 'process raft request'  (duration: 74.283819ms)","trace[1021828041] 'compare'  (duration: 171.120143ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:29:07.218788Z","caller":"traceutil/trace.go:171","msg":"trace[1216580658] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"224.331508ms","start":"2026-04-01T23:29:06.994437Z","end":"2026-04-01T23:29:07.218769Z","steps":["trace[1216580658] 'read index received'  (duration: 52.319861ms)","trace[1216580658] 'applied index is now lower than readState.Index'  (duration: 172.009727ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:29:07.219183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.739977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-01T23:29:07.219269Z","caller":"traceutil/trace.go:171","msg":"trace[1466479695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2087; }","duration":"224.85901ms","start":"2026-04-01T23:29:06.994388Z","end":"2026-04-01T23:29:07.219247Z","steps":["trace[1466479695] 'agreement among raft nodes before linearized reading'  (duration: 224.599284ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:07.906655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.823912ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924037655 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2083 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-01T23:29:07.906789Z","caller":"traceutil/trace.go:171","msg":"trace[1916270947] linearizableReadLoop","detail":"{readStateIndex:2168; appliedIndex:2167; }","duration":"687.70593ms","start":"2026-04-01T23:29:07.219062Z","end":"2026-04-01T23:29:07.906767Z","steps":["trace[1916270947] 'read index received'  (duration: 416.658392ms)","trace[1916270947] 'applied index is now lower than readState.Index'  (duration: 271.046048ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:29:07.906831Z","caller":"traceutil/trace.go:171","msg":"trace[1756212477] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"804.609774ms","start":"2026-04-01T23:29:07.102185Z","end":"2026-04-01T23:29:07.906795Z","steps":["trace[1756212477] 'process raft request'  (duration: 533.509545ms)","trace[1756212477] 'compare'  (duration: 270.713319ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:29:07.907006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:07.102168Z","time spent":"804.748639ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2083 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-04-01T23:29:07.90701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.0967ms","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-04-01T23:29:07.90711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.377453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-04-01T23:29:07.907105Z","caller":"traceutil/trace.go:171","msg":"trace[215620545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2088; }","duration":"811.197952ms","start":"2026-04-01T23:29:07.095882Z","end":"2026-04-01T23:29:07.90708Z","steps":["trace[215620545] 'agreement among raft nodes before linearized reading'  (duration: 810.974127ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:07.907168Z","caller":"traceutil/trace.go:171","msg":"trace[1729862075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2088; }","duration":"812.469255ms","start":"2026-04-01T23:29:07.094682Z","end":"2026-04-01T23:29:07.907151Z","steps":["trace[1729862075] 'agreement among raft nodes before linearized reading'  (duration: 812.355762ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:07.907202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:07.095875Z","time spent":"811.291845ms","remote":"127.0.0.1:43008","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-04-01T23:29:07.907209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:07.094651Z","time spent":"812.547397ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-04-01T23:29:07.907355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.139492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-04-01T23:29:07.907472Z","caller":"traceutil/trace.go:171","msg":"trace[1286710944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2088; }","duration":"848.323166ms","start":"2026-04-01T23:29:07.059122Z","end":"2026-04-01T23:29:07.907445Z","steps":["trace[1286710944] 'agreement among raft nodes before linearized reading'  (duration: 847.957807ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:07.90753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:07.059095Z","time spent":"848.421879ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-04-01T23:29:07.997151Z","caller":"traceutil/trace.go:171","msg":"trace[2135120998] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"772.552969ms","start":"2026-04-01T23:29:07.22456Z","end":"2026-04-01T23:29:07.997113Z","steps":["trace[2135120998] 'process raft request'  (duration: 772.309193ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:07.997355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:07.224535Z","time spent":"772.711363ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2079 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-04-01T23:29:08.091639Z","caller":"traceutil/trace.go:171","msg":"trace[1936320102] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"184.069862ms","start":"2026-04-01T23:29:07.90755Z","end":"2026-04-01T23:29:08.091619Z","steps":["trace[1936320102] 'process raft request'  (duration: 183.864887ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:08.091628Z","caller":"traceutil/trace.go:171","msg":"trace[1240484004] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2168; }","duration":"184.767262ms","start":"2026-04-01T23:29:07.906824Z","end":"2026-04-01T23:29:08.091592Z","steps":["trace[1240484004] 'read index received'  (duration: 90.061202ms)","trace[1240484004] 'applied index is now lower than readState.Index'  (duration: 94.699309ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:29:08.116518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.061344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:29:08.11662Z","caller":"traceutil/trace.go:171","msg":"trace[1227443326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2090; }","duration":"219.223008ms","start":"2026-04-01T23:29:07.897371Z","end":"2026-04-01T23:29:08.116594Z","steps":["trace[1227443326] 'agreement among raft nodes before linearized reading'  (duration: 194.318965ms)","trace[1227443326] 'range keys from in-memory index tree'  (duration: 24.75309ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:29:08.116698Z","caller":"traceutil/trace.go:171","msg":"trace[1687561241] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"205.799566ms","start":"2026-04-01T23:29:07.910873Z","end":"2026-04-01T23:29:08.116673Z","steps":["trace[1687561241] 'process raft request'  (duration: 180.732228ms)","trace[1687561241] 'compare'  (duration: 24.902444ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:29:08.117052Z","caller":"traceutil/trace.go:171","msg":"trace[1658401270] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"205.728335ms","start":"2026-04-01T23:29:07.911309Z","end":"2026-04-01T23:29:08.117037Z","steps":["trace[1658401270] 'process raft request'  (duration: 205.317804ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:08.117158Z","caller":"traceutil/trace.go:171","msg":"trace[1523408557] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"204.720639ms","start":"2026-04-01T23:29:07.912413Z","end":"2026-04-01T23:29:08.117133Z","steps":["trace[1523408557] 'process raft request'  (duration: 204.506784ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:08.117196Z","caller":"traceutil/trace.go:171","msg":"trace[638570518] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"196.50951ms","start":"2026-04-01T23:29:07.920645Z","end":"2026-04-01T23:29:08.117155Z","steps":["trace[638570518] 'process raft request'  (duration: 196.400988ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:08.394542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.913886ms","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-04-01T23:29:08.39472Z","caller":"traceutil/trace.go:171","msg":"trace[227981589] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2094; }","duration":"169.142512ms","start":"2026-04-01T23:29:08.225545Z","end":"2026-04-01T23:29:08.394688Z","steps":["trace[227981589] 'range keys from in-memory index tree'  (duration: 168.62786ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:14.065617Z","caller":"traceutil/trace.go:171","msg":"trace[2143637985] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"197.168117ms","start":"2026-04-01T23:29:13.868429Z","end":"2026-04-01T23:29:14.065597Z","steps":["trace[2143637985] 'process raft request'  (duration: 197.041212ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:14.159388Z","caller":"traceutil/trace.go:171","msg":"trace[1711462101] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2200; }","duration":"140.021052ms","start":"2026-04-01T23:29:14.019328Z","end":"2026-04-01T23:29:14.159349Z","steps":["trace[1711462101] 'read index received'  (duration: 46.173374ms)","trace[1711462101] 'applied index is now lower than readState.Index'  (duration: 93.846518ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:29:14.159417Z","caller":"traceutil/trace.go:171","msg":"trace[978081500] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"233.626714ms","start":"2026-04-01T23:29:13.925759Z","end":"2026-04-01T23:29:14.159385Z","steps":["trace[978081500] 'process raft request'  (duration: 230.611227ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:14.159707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.319701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-04-01T23:29:14.159769Z","caller":"traceutil/trace.go:171","msg":"trace[1449799476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2120; }","duration":"140.467504ms","start":"2026-04-01T23:29:14.019284Z","end":"2026-04-01T23:29:14.159752Z","steps":["trace[1449799476] 'agreement among raft nodes before linearized reading'  (duration: 140.231668ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:14.751369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.972529ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924037748 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:2113 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-01T23:29:14.751666Z","caller":"traceutil/trace.go:171","msg":"trace[1831783532] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2203; }","duration":"314.696065ms","start":"2026-04-01T23:29:14.436951Z","end":"2026-04-01T23:29:14.751647Z","steps":["trace[1831783532] 'read index received'  (duration: 29.297976ms)","trace[1831783532] 'applied index is now lower than readState.Index'  (duration: 285.397129ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:29:14.751748Z","caller":"traceutil/trace.go:171","msg":"trace[280535796] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"587.138557ms","start":"2026-04-01T23:29:14.164583Z","end":"2026-04-01T23:29:14.751721Z","steps":["trace[280535796] 'process raft request'  (duration: 586.984252ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:14.751798Z","caller":"traceutil/trace.go:171","msg":"trace[2142383042] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"516.432258ms","start":"2026-04-01T23:29:14.235333Z","end":"2026-04-01T23:29:14.751765Z","steps":["trace[2142383042] 'process raft request'  (duration: 516.265463ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:14.751813Z","caller":"traceutil/trace.go:171","msg":"trace[443616040] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"587.675942ms","start":"2026-04-01T23:29:14.164112Z","end":"2026-04-01T23:29:14.751788Z","steps":["trace[443616040] 'process raft request'  (duration: 587.359883ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:14.751865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.906461ms","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-04-01T23:29:14.751869Z","caller":"traceutil/trace.go:171","msg":"trace[559703568] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"587.827534ms","start":"2026-04-01T23:29:14.163993Z","end":"2026-04-01T23:29:14.751821Z","steps":["trace[559703568] 'process raft request'  (duration: 302.307271ms)","trace[559703568] 'compare'  (duration: 284.705283ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:29:14.751893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:14.164573Z","time spent":"587.239479ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2111 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-04-01T23:29:14.751914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.971495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"warn","ts":"2026-04-01T23:29:14.751945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:14.164088Z","time spent":"587.795724ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:2114 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-04-01T23:29:14.751954Z","caller":"traceutil/trace.go:171","msg":"trace[106053486] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2125; }","duration":"251.053317ms","start":"2026-04-01T23:29:14.500885Z","end":"2026-04-01T23:29:14.751939Z","steps":["trace[106053486] 'agreement among raft nodes before linearized reading'  (duration: 250.872313ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:14.751974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.926198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"warn","ts":"2026-04-01T23:29:14.751976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:14.235283Z","time spent":"516.595412ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:2073 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:599 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-04-01T23:29:14.752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:14.16397Z","time spent":"587.971018ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:2113 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-04-01T23:29:14.752024Z","caller":"traceutil/trace.go:171","msg":"trace[2038587580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2125; }","duration":"123.04761ms","start":"2026-04-01T23:29:14.628964Z","end":"2026-04-01T23:29:14.752011Z","steps":["trace[2038587580] 'agreement among raft nodes before linearized reading'  (duration: 122.866176ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:29:14.751897Z","caller":"traceutil/trace.go:171","msg":"trace[904733212] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2125; }","duration":"314.975893ms","start":"2026-04-01T23:29:14.436911Z","end":"2026-04-01T23:29:14.751887Z","steps":["trace[904733212] 'agreement among raft nodes before linearized reading'  (duration: 314.83528ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:14.752144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:14.436895Z","time spent":"315.207948ms","remote":"127.0.0.1:55470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-04-01T23:29:56.698981Z","caller":"traceutil/trace.go:171","msg":"trace[696138398] linearizableReadLoop","detail":"{readStateIndex:2488; appliedIndex:2487; }","duration":"262.785645ms","start":"2026-04-01T23:29:56.43616Z","end":"2026-04-01T23:29:56.698946Z","steps":["trace[696138398] 'read index received'  (duration: 262.5453ms)","trace[696138398] 'applied index is now lower than readState.Index'  (duration: 238.635µs)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:29:56.699038Z","caller":"traceutil/trace.go:171","msg":"trace[2049421386] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"395.41143ms","start":"2026-04-01T23:29:56.303594Z","end":"2026-04-01T23:29:56.699005Z","steps":["trace[2049421386] 'process raft request'  (duration: 395.193495ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:29:56.699191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:56.30357Z","time spent":"395.536963ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2384 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-04-01T23:29:56.699217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.044352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-04-01T23:29:56.699254Z","caller":"traceutil/trace.go:171","msg":"trace[541243166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2397; }","duration":"263.134305ms","start":"2026-04-01T23:29:56.436108Z","end":"2026-04-01T23:29:56.699243Z","steps":["trace[541243166] 'agreement among raft nodes before linearized reading'  (duration: 262.97218ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:30:33.137486Z","caller":"traceutil/trace.go:171","msg":"trace[485925630] linearizableReadLoop","detail":"{readStateIndex:2696; appliedIndex:2695; }","duration":"304.740675ms","start":"2026-04-01T23:30:32.832728Z","end":"2026-04-01T23:30:33.137469Z","steps":["trace[485925630] 'read index received'  (duration: 304.563601ms)","trace[485925630] 'applied index is now lower than readState.Index'  (duration: 176.504µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:30:33.137626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.871348ms","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-04-01T23:30:33.137666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.481305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-04-01T23:30:33.137689Z","caller":"traceutil/trace.go:171","msg":"trace[68125222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2597; }","duration":"254.544047ms","start":"2026-04-01T23:30:32.883138Z","end":"2026-04-01T23:30:33.137682Z","steps":["trace[68125222] 'agreement among raft nodes before linearized reading'  (duration: 254.428684ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:30:33.137695Z","caller":"traceutil/trace.go:171","msg":"trace[2119693249] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2597; }","duration":"304.991731ms","start":"2026-04-01T23:30:32.832686Z","end":"2026-04-01T23:30:33.137677Z","steps":["trace[2119693249] 'agreement among raft nodes before linearized reading'  (duration: 304.853368ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:30:33.137732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:32.832666Z","time spent":"305.060053ms","remote":"127.0.0.1:43210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "}
{"level":"warn","ts":"2026-04-01T23:30:33.13771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.312872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:30:33.137786Z","caller":"traceutil/trace.go:171","msg":"trace[783284198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2597; }","duration":"201.439016ms","start":"2026-04-01T23:30:32.936335Z","end":"2026-04-01T23:30:33.137774Z","steps":["trace[783284198] 'agreement among raft nodes before linearized reading'  (duration: 201.302672ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:30:33.137585Z","caller":"traceutil/trace.go:171","msg":"trace[1965824721] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"304.816716ms","start":"2026-04-01T23:30:32.832703Z","end":"2026-04-01T23:30:33.13752Z","steps":["trace[1965824721] 'process raft request'  (duration: 304.662863ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:30:33.138184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:32.832681Z","time spent":"305.407532ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2587 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-04-01T23:30:38.351391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.459099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"}
{"level":"info","ts":"2026-04-01T23:30:38.351482Z","caller":"traceutil/trace.go:171","msg":"trace[1766674100] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2626; }","duration":"109.588333ms","start":"2026-04-01T23:30:38.241871Z","end":"2026-04-01T23:30:38.35146Z","steps":["trace[1766674100] 'range keys from in-memory index tree'  (duration: 109.242003ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:33:12.2054Z","caller":"traceutil/trace.go:171","msg":"trace[1476932915] linearizableReadLoop","detail":"{readStateIndex:3761; appliedIndex:3760; }","duration":"277.199846ms","start":"2026-04-01T23:33:11.928164Z","end":"2026-04-01T23:33:12.205364Z","steps":["trace[1476932915] 'read index received'  (duration: 196.217776ms)","trace[1476932915] 'applied index is now lower than readState.Index'  (duration: 80.97987ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:33:12.205531Z","caller":"traceutil/trace.go:171","msg":"trace[2079081972] transaction","detail":"{read_only:false; response_revision:3623; number_of_response:1; }","duration":"279.975619ms","start":"2026-04-01T23:33:11.925525Z","end":"2026-04-01T23:33:12.205501Z","steps":["trace[2079081972] 'process raft request'  (duration: 198.843254ms)","trace[2079081972] 'compare'  (duration: 80.824946ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:33:12.205767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.573836ms","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-04-01T23:33:12.205776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.518735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-01T23:33:12.205838Z","caller":"traceutil/trace.go:171","msg":"trace[1269799516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3623; }","duration":"277.67745ms","start":"2026-04-01T23:33:11.928146Z","end":"2026-04-01T23:33:12.205823Z","steps":["trace[1269799516] 'agreement among raft nodes before linearized reading'  (duration: 277.443684ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:33:12.205884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.928207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"warn","ts":"2026-04-01T23:33:12.205924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.273181ms","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-04-01T23:33:12.205958Z","caller":"traceutil/trace.go:171","msg":"trace[543900892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3623; }","duration":"102.347553ms","start":"2026-04-01T23:33:12.103599Z","end":"2026-04-01T23:33:12.205946Z","steps":["trace[543900892] 'agreement among raft nodes before linearized reading'  (duration: 102.214809ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:33:12.205953Z","caller":"traceutil/trace.go:171","msg":"trace[338266015] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3623; }","duration":"273.017389ms","start":"2026-04-01T23:33:11.932916Z","end":"2026-04-01T23:33:12.205933Z","steps":["trace[338266015] 'agreement among raft nodes before linearized reading'  (duration: 272.892536ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:33:12.205851Z","caller":"traceutil/trace.go:171","msg":"trace[2041857157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3623; }","duration":"277.599837ms","start":"2026-04-01T23:33:11.928233Z","end":"2026-04-01T23:33:12.205833Z","steps":["trace[2041857157] 'agreement among raft nodes before linearized reading'  (duration: 277.409712ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:33:12.205969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.985238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-04-01T23:33:12.206075Z","caller":"traceutil/trace.go:171","msg":"trace[1183161368] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:3623; }","duration":"194.135882ms","start":"2026-04-01T23:33:12.011923Z","end":"2026-04-01T23:33:12.206059Z","steps":["trace[1183161368] 'agreement among raft nodes before linearized reading'  (duration: 193.862196ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:33:12.527042Z","caller":"traceutil/trace.go:171","msg":"trace[1528091936] transaction","detail":"{read_only:false; response_revision:3624; number_of_response:1; }","duration":"321.252854ms","start":"2026-04-01T23:33:12.205762Z","end":"2026-04-01T23:33:12.527014Z","steps":["trace[1528091936] 'process raft request'  (duration: 308.970166ms)","trace[1528091936] 'compare'  (duration: 12.136174ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:33:12.527184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:33:12.205737Z","time spent":"321.373746ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:3619 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-04-01T23:33:12.579219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:33:12.206827Z","time spent":"372.383933ms","remote":"127.0.0.1:42766","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-04-01T23:33:12.579326Z","caller":"traceutil/trace.go:171","msg":"trace[1218419737] transaction","detail":"{read_only:false; response_revision:3626; number_of_response:1; }","duration":"350.739945ms","start":"2026-04-01T23:33:12.228562Z","end":"2026-04-01T23:33:12.579301Z","steps":["trace[1218419737] 'process raft request'  (duration: 350.664262ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:33:12.579343Z","caller":"traceutil/trace.go:171","msg":"trace[1117781672] transaction","detail":"{read_only:false; response_revision:3625; number_of_response:1; }","duration":"350.832558ms","start":"2026-04-01T23:33:12.228478Z","end":"2026-04-01T23:33:12.579311Z","steps":["trace[1117781672] 'process raft request'  (duration: 350.622072ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:33:12.57948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:33:12.228534Z","time spent":"350.864918ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:3618 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:409 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-04-01T23:33:12.579545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:33:12.228462Z","time spent":"350.943911ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:3617 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-04-01T23:33:12.579688Z","caller":"traceutil/trace.go:171","msg":"trace[629461155] transaction","detail":"{read_only:false; response_revision:3627; number_of_response:1; }","duration":"350.96332ms","start":"2026-04-01T23:33:12.22871Z","end":"2026-04-01T23:33:12.579673Z","steps":["trace[629461155] 'process raft request'  (duration: 350.55396ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:33:12.57975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:33:12.22868Z","time spent":"351.039742ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:3616 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-04-01T23:33:12.579753Z","caller":"traceutil/trace.go:171","msg":"trace[639093229] linearizableReadLoop","detail":"{readStateIndex:3767; appliedIndex:3761; }","duration":"331.97915ms","start":"2026-04-01T23:33:12.247744Z","end":"2026-04-01T23:33:12.579723Z","steps":["trace[639093229] 'read index received'  (duration: 267.28793ms)","trace[639093229] 'applied index is now lower than readState.Index'  (duration: 64.68993ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:33:12.57987Z","caller":"traceutil/trace.go:171","msg":"trace[2081458861] transaction","detail":"{read_only:false; response_revision:3628; number_of_response:1; }","duration":"350.263743ms","start":"2026-04-01T23:33:12.229593Z","end":"2026-04-01T23:33:12.579857Z","steps":["trace[2081458861] 'process raft request'  (duration: 349.987386ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:33:12.579914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:33:12.229572Z","time spent":"350.319563ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:3615 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-04-01T23:33:12.580017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.251738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-04-01T23:33:12.580117Z","caller":"traceutil/trace.go:171","msg":"trace[517514418] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3628; }","duration":"332.366771ms","start":"2026-04-01T23:33:12.247707Z","end":"2026-04-01T23:33:12.580074Z","steps":["trace[517514418] 'agreement among raft nodes before linearized reading'  (duration: 332.119014ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:33:12.580167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:33:12.247696Z","time spent":"332.457613ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-01T23:34:17.002035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.093071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:34:17.002166Z","caller":"traceutil/trace.go:171","msg":"trace[840140148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4067; }","duration":"107.262615ms","start":"2026-04-01T23:34:16.894877Z","end":"2026-04-01T23:34:17.00214Z","steps":["trace[840140148] 'range keys from in-memory index tree'  (duration: 107.023819ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:34:21.548095Z","caller":"traceutil/trace.go:171","msg":"trace[32531107] transaction","detail":"{read_only:false; response_revision:4312; number_of_response:1; }","duration":"115.187508ms","start":"2026-04-01T23:34:21.432882Z","end":"2026-04-01T23:34:21.54807Z","steps":["trace[32531107] 'process raft request'  (duration: 100.025846ms)","trace[32531107] 'compare'  (duration: 15.014559ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:34:21.580162Z","caller":"traceutil/trace.go:171","msg":"trace[356090846] transaction","detail":"{read_only:false; response_revision:4313; number_of_response:1; }","duration":"105.18433ms","start":"2026-04-01T23:34:21.474946Z","end":"2026-04-01T23:34:21.580131Z","steps":["trace[356090846] 'process raft request'  (duration: 104.885372ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:34:25.327031Z","caller":"traceutil/trace.go:171","msg":"trace[1741574142] transaction","detail":"{read_only:false; response_revision:4518; number_of_response:1; }","duration":"105.478656ms","start":"2026-04-01T23:34:25.221518Z","end":"2026-04-01T23:34:25.326997Z","steps":["trace[1741574142] 'process raft request'  (duration: 105.294941ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:34:30.528412Z","caller":"traceutil/trace.go:171","msg":"trace[131570940] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"110.570457ms","start":"2026-04-01T23:34:30.417818Z","end":"2026-04-01T23:34:30.528389Z","steps":["trace[131570940] 'process raft request'  (duration: 104.508801ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:30.960805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.735712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:34:30.96089Z","caller":"traceutil/trace.go:171","msg":"trace[327398958] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4637; }","duration":"329.879506ms","start":"2026-04-01T23:34:30.630992Z","end":"2026-04-01T23:34:30.960871Z","steps":["trace[327398958] 'count revisions from in-memory index tree'  (duration: 329.585189ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:30.960918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.911597ms","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-04-01T23:34:30.960929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:30.630968Z","time spent":"329.949968ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "}
{"level":"info","ts":"2026-04-01T23:34:30.960958Z","caller":"traceutil/trace.go:171","msg":"trace[1035258706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4637; }","duration":"329.996639ms","start":"2026-04-01T23:34:30.630952Z","end":"2026-04-01T23:34:30.960949Z","steps":["trace[1035258706] 'range keys from in-memory index tree'  (duration: 329.713142ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:30.960982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:30.630932Z","time spent":"330.04546ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-04-01T23:34:30.961158Z","caller":"traceutil/trace.go:171","msg":"trace[853251499] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"166.844443ms","start":"2026-04-01T23:34:30.794278Z","end":"2026-04-01T23:34:30.961123Z","steps":["trace[853251499] 'process raft request'  (duration: 163.585038ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:30.961374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.618135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2351357"}
{"level":"info","ts":"2026-04-01T23:34:30.961436Z","caller":"traceutil/trace.go:171","msg":"trace[1557335760] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4637; }","duration":"330.719737ms","start":"2026-04-01T23:34:30.630705Z","end":"2026-04-01T23:34:30.961424Z","steps":["trace[1557335760] 'range keys from in-memory index tree'  (duration: 330.04431ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:30.961462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:30.630689Z","time spent":"330.765569ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":50,"response size":2351380,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "}
{"level":"info","ts":"2026-04-01T23:34:32.086695Z","caller":"traceutil/trace.go:171","msg":"trace[26650438] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"165.522108ms","start":"2026-04-01T23:34:31.921137Z","end":"2026-04-01T23:34:32.086659Z","steps":["trace[26650438] 'process raft request'  (duration: 93.319142ms)","trace[26650438] 'compare'  (duration: 71.634471ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:34:32.086889Z","caller":"traceutil/trace.go:171","msg":"trace[1356935918] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"164.975954ms","start":"2026-04-01T23:34:31.921793Z","end":"2026-04-01T23:34:32.086769Z","steps":["trace[1356935918] 'process raft request'  (duration: 164.688497ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:34:45.406429Z","caller":"traceutil/trace.go:171","msg":"trace[1121427144] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"144.566897ms","start":"2026-04-01T23:34:45.261839Z","end":"2026-04-01T23:34:45.406406Z","steps":["trace[1121427144] 'process raft request'  (duration: 144.438034ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.743154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.460558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"info","ts":"2026-04-01T23:34:53.743272Z","caller":"traceutil/trace.go:171","msg":"trace[1172855440] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:5121; }","duration":"410.605351ms","start":"2026-04-01T23:34:53.332642Z","end":"2026-04-01T23:34:53.743248Z","steps":["trace[1172855440] 'range keys from in-memory index tree'  (duration: 410.319124ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.743319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.332632Z","time spent":"410.677274ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-w6nkh\" "}
{"level":"warn","ts":"2026-04-01T23:34:53.743315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.920136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-01T23:34:53.7434Z","caller":"traceutil/trace.go:171","msg":"trace[332937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5121; }","duration":"566.056019ms","start":"2026-04-01T23:34:53.177324Z","end":"2026-04-01T23:34:53.74338Z","steps":["trace[332937] 'range keys from in-memory index tree'  (duration: 565.736681ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.743452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.177304Z","time spent":"566.137411ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-01T23:34:53.74377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.610299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"}
{"level":"warn","ts":"2026-04-01T23:34:53.743175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.774596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc08643d-2aab-4a65-be52-7ca3deb7a0af\" ","response":"range_response_count:1 size:3645"}
{"level":"info","ts":"2026-04-01T23:34:53.74388Z","caller":"traceutil/trace.go:171","msg":"trace[143304397] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5121; }","duration":"553.786903ms","start":"2026-04-01T23:34:53.190072Z","end":"2026-04-01T23:34:53.743859Z","steps":["trace[143304397] 'range keys from in-memory index tree'  (duration: 553.389132ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.743936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.190051Z","time spent":"553.871605ms","remote":"127.0.0.1:42924","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":"info","ts":"2026-04-01T23:34:53.743922Z","caller":"traceutil/trace.go:171","msg":"trace[1342742855] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cc08643d-2aab-4a65-be52-7ca3deb7a0af; range_end:; response_count:1; response_revision:5121; }","duration":"310.567927ms","start":"2026-04-01T23:34:53.433325Z","end":"2026-04-01T23:34:53.743893Z","steps":["trace[1342742855] 'range keys from in-memory index tree'  (duration: 309.608632ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.743996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.433306Z","time spent":"310.667279ms","remote":"127.0.0.1:42924","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-cc08643d-2aab-4a65-be52-7ca3deb7a0af\" "}
{"level":"warn","ts":"2026-04-01T23:34:53.74401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.05857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"warn","ts":"2026-04-01T23:34:53.744051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.940485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc08643d-2aab-4a65-be52-7ca3deb7a0af\" ","response":"range_response_count:1 size:3645"}
{"level":"info","ts":"2026-04-01T23:34:53.744103Z","caller":"traceutil/trace.go:171","msg":"trace[1856639916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5121; }","duration":"412.213883ms","start":"2026-04-01T23:34:53.331865Z","end":"2026-04-01T23:34:53.744079Z","steps":["trace[1856639916] 'range keys from in-memory index tree'  (duration: 411.900725ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:34:53.744148Z","caller":"traceutil/trace.go:171","msg":"trace[1828659590] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cc08643d-2aab-4a65-be52-7ca3deb7a0af; range_end:; response_count:1; response_revision:5121; }","duration":"434.10297ms","start":"2026-04-01T23:34:53.310024Z","end":"2026-04-01T23:34:53.744127Z","steps":["trace[1828659590] 'range keys from in-memory index tree'  (duration: 433.773342ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.744174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.33184Z","time spent":"412.319366ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-04-01T23:34:53.744217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.309993Z","time spent":"434.207361ms","remote":"127.0.0.1:42924","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-cc08643d-2aab-4a65-be52-7ca3deb7a0af\" "}
{"level":"warn","ts":"2026-04-01T23:34:53.744225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.421896ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924045253 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:5002 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >>","response":"size:16"}
{"level":"warn","ts":"2026-04-01T23:34:53.74416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.546551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-04-01T23:34:53.744305Z","caller":"traceutil/trace.go:171","msg":"trace[170521602] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"264.414284ms","start":"2026-04-01T23:34:53.479874Z","end":"2026-04-01T23:34:53.744289Z","steps":["trace[170521602] 'process raft request'  (duration: 91.853653ms)","trace[170521602] 'compare'  (duration: 171.241536ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:34:53.744353Z","caller":"traceutil/trace.go:171","msg":"trace[1110487594] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5121; }","duration":"470.756218ms","start":"2026-04-01T23:34:53.27358Z","end":"2026-04-01T23:34:53.744336Z","steps":["trace[1110487594] 'range keys from in-memory index tree'  (duration: 470.413408ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.744404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.273569Z","time spent":"470.818529ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-01T23:34:53.744533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.073086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-01T23:34:53.74461Z","caller":"traceutil/trace.go:171","msg":"trace[1592478559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5121; }","duration":"519.175109ms","start":"2026-04-01T23:34:53.225422Z","end":"2026-04-01T23:34:53.744597Z","steps":["trace[1592478559] 'range keys from in-memory index tree'  (duration: 517.977618ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.744644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.405391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"warn","ts":"2026-04-01T23:34:53.744739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.22541Z","time spent":"519.317872ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-04-01T23:34:53.744825Z","caller":"traceutil/trace.go:171","msg":"trace[1358181435] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5121; }","duration":"414.568564ms","start":"2026-04-01T23:34:53.330196Z","end":"2026-04-01T23:34:53.744765Z","steps":["trace[1358181435] 'range keys from in-memory index tree'  (duration: 413.339403ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:53.744999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:34:53.330184Z","time spent":"414.728019ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-04-01T23:34:54.453751Z","caller":"traceutil/trace.go:171","msg":"trace[417254022] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"119.975332ms","start":"2026-04-01T23:34:54.333741Z","end":"2026-04-01T23:34:54.453716Z","steps":["trace[417254022] 'process raft request'  (duration: 119.768517ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:34:55.495148Z","caller":"traceutil/trace.go:171","msg":"trace[624182174] linearizableReadLoop","detail":"{readStateIndex:5341; appliedIndex:5340; }","duration":"113.219236ms","start":"2026-04-01T23:34:55.381906Z","end":"2026-04-01T23:34:55.495125Z","steps":["trace[624182174] 'read index received'  (duration: 113.036102ms)","trace[624182174] 'applied index is now lower than readState.Index'  (duration: 182.234µs)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:34:55.495163Z","caller":"traceutil/trace.go:171","msg":"trace[1166194510] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"121.450888ms","start":"2026-04-01T23:34:55.373692Z","end":"2026-04-01T23:34:55.495143Z","steps":["trace[1166194510] 'process raft request'  (duration: 121.304165ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:55.495413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.488723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"}
{"level":"info","ts":"2026-04-01T23:34:55.495543Z","caller":"traceutil/trace.go:171","msg":"trace[1929099548] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5151; }","duration":"113.674998ms","start":"2026-04-01T23:34:55.38185Z","end":"2026-04-01T23:34:55.495525Z","steps":["trace[1929099548] 'agreement among raft nodes before linearized reading'  (duration: 113.389901ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:34:55.657382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.472349ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924045306 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0.18a25fc3423cfe67\" mod_revision:0 > success:<request_put:<key:\"/registry/events/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0.18a25fc3423cfe67\" value_size:742 lease:5559303737069268880 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-04-01T23:34:55.657499Z","caller":"traceutil/trace.go:171","msg":"trace[305349590] linearizableReadLoop","detail":"{readStateIndex:5342; appliedIndex:5341; }","duration":"158.603748ms","start":"2026-04-01T23:34:55.498878Z","end":"2026-04-01T23:34:55.657481Z","steps":["trace[305349590] 'read index received'  (duration: 53.978424ms)","trace[305349590] 'applied index is now lower than readState.Index'  (duration: 104.624214ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:34:55.657542Z","caller":"traceutil/trace.go:171","msg":"trace[1336034609] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"158.737971ms","start":"2026-04-01T23:34:55.498769Z","end":"2026-04-01T23:34:55.657507Z","steps":["trace[1336034609] 'process raft request'  (duration: 54.062227ms)","trace[1336034609] 'compare'  (duration: 104.321746ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:34:55.657746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.859425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-df9376e6-3fec-4aeb-8a10-b3f45b82a62c\" ","response":"range_response_count:1 size:1275"}
{"level":"info","ts":"2026-04-01T23:34:55.65779Z","caller":"traceutil/trace.go:171","msg":"trace[1284486516] range","detail":"{range_begin:/registry/persistentvolumes/pvc-df9376e6-3fec-4aeb-8a10-b3f45b82a62c; range_end:; response_count:1; response_revision:5152; }","duration":"158.934537ms","start":"2026-04-01T23:34:55.498842Z","end":"2026-04-01T23:34:55.657776Z","steps":["trace[1284486516] 'agreement among raft nodes before linearized reading'  (duration: 158.69889ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:35:03.657774Z","caller":"traceutil/trace.go:171","msg":"trace[1397695319] linearizableReadLoop","detail":"{readStateIndex:5498; appliedIndex:5497; }","duration":"175.168568ms","start":"2026-04-01T23:35:03.482565Z","end":"2026-04-01T23:35:03.657734Z","steps":["trace[1397695319] 'read index received'  (duration: 76.338163ms)","trace[1397695319] 'applied index is now lower than readState.Index'  (duration: 98.828945ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:35:03.657833Z","caller":"traceutil/trace.go:171","msg":"trace[1915972834] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"202.284688ms","start":"2026-04-01T23:35:03.455508Z","end":"2026-04-01T23:35:03.657792Z","steps":["trace[1915972834] 'process raft request'  (duration: 103.388792ms)","trace[1915972834] 'compare'  (duration: 98.624398ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:35:03.658098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.432155ms","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-04-01T23:35:03.658133Z","caller":"traceutil/trace.go:171","msg":"trace[1244293312] 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:5304; }","duration":"175.618309ms","start":"2026-04-01T23:35:03.482504Z","end":"2026-04-01T23:35:03.658122Z","steps":["trace[1244293312] 'agreement among raft nodes before linearized reading'  (duration: 175.343182ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.023314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675773924045727,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-04-01T23:35:08.2322Z","caller":"traceutil/trace.go:171","msg":"trace[1529297691] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"709.865546ms","start":"2026-04-01T23:35:07.522299Z","end":"2026-04-01T23:35:08.232165Z","steps":["trace[1529297691] 'read index received'  (duration: 709.25128ms)","trace[1529297691] 'applied index is now lower than readState.Index'  (duration: 612.936µs)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:35:08.232261Z","caller":"traceutil/trace.go:171","msg":"trace[324290522] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"769.140099ms","start":"2026-04-01T23:35:07.463063Z","end":"2026-04-01T23:35:08.232203Z","steps":["trace[324290522] 'process raft request'  (duration: 768.80447ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.232504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.179905ms","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-04-01T23:35:08.232591Z","caller":"traceutil/trace.go:171","msg":"trace[878648278] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5333; }","duration":"710.281297ms","start":"2026-04-01T23:35:07.522291Z","end":"2026-04-01T23:35:08.232572Z","steps":["trace[878648278] 'agreement among raft nodes before linearized reading'  (duration: 710.013751ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.232585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.702991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"warn","ts":"2026-04-01T23:35:08.232642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.52222Z","time spent":"710.41285ms","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "}
{"level":"warn","ts":"2026-04-01T23:35:08.232745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.001357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:35:08.232815Z","caller":"traceutil/trace.go:171","msg":"trace[1849090143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5333; }","duration":"316.10444ms","start":"2026-04-01T23:35:07.916701Z","end":"2026-04-01T23:35:08.232806Z","steps":["trace[1849090143] 'agreement among raft nodes before linearized reading'  (duration: 315.886344ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.232862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.223586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"warn","ts":"2026-04-01T23:35:08.232509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.463046Z","time spent":"769.379225ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" mod_revision:5173 > success:<request_put:<key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" value_size:10610 >> failure:<request_range:<key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" > >"}
{"level":"info","ts":"2026-04-01T23:35:08.232909Z","caller":"traceutil/trace.go:171","msg":"trace[1640465777] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:5333; }","duration":"337.285167ms","start":"2026-04-01T23:35:07.895613Z","end":"2026-04-01T23:35:08.232899Z","steps":["trace[1640465777] 'agreement among raft nodes before linearized reading'  (duration: 337.180845ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.232956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.895603Z","time spent":"337.344749ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-w6nkh\" "}
{"level":"info","ts":"2026-04-01T23:35:08.232646Z","caller":"traceutil/trace.go:171","msg":"trace[260933033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5333; }","duration":"389.824714ms","start":"2026-04-01T23:35:07.842807Z","end":"2026-04-01T23:35:08.232632Z","steps":["trace[260933033] 'agreement among raft nodes before linearized reading'  (duration: 389.574988ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.233025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.870517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-04-01T23:35:08.232853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.916683Z","time spent":"316.162811ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-01T23:35:08.23311Z","caller":"traceutil/trace.go:171","msg":"trace[1663061580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5333; }","duration":"336.989919ms","start":"2026-04-01T23:35:07.896097Z","end":"2026-04-01T23:35:08.233087Z","steps":["trace[1663061580] 'agreement among raft nodes before linearized reading'  (duration: 336.747223ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.233167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.896092Z","time spent":"337.065041ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-01T23:35:08.23316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.071341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"warn","ts":"2026-04-01T23:35:08.233045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.842792Z","time spent":"390.241726ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-04-01T23:35:08.23334Z","caller":"traceutil/trace.go:171","msg":"trace[471643722] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5333; }","duration":"337.249796ms","start":"2026-04-01T23:35:07.896068Z","end":"2026-04-01T23:35:08.233317Z","steps":["trace[471643722] 'agreement among raft nodes before linearized reading'  (duration: 336.700352ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.233421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.357719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-04-01T23:35:08.233457Z","caller":"traceutil/trace.go:171","msg":"trace[1779738577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5333; }","duration":"354.423731ms","start":"2026-04-01T23:35:07.879023Z","end":"2026-04-01T23:35:08.233447Z","steps":["trace[1779738577] 'agreement among raft nodes before linearized reading'  (duration: 354.310827ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.232766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.61319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"}
{"level":"warn","ts":"2026-04-01T23:35:08.233495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.878995Z","time spent":"354.493603ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-04-01T23:35:08.233526Z","caller":"traceutil/trace.go:171","msg":"trace[232881291] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5333; }","duration":"337.373939ms","start":"2026-04-01T23:35:07.896131Z","end":"2026-04-01T23:35:08.233505Z","steps":["trace[232881291] 'agreement among raft nodes before linearized reading'  (duration: 336.541157ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:35:08.233588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.896127Z","time spent":"337.444312ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-01T23:35:08.233523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:35:07.896035Z","time spent":"337.415171ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-04-01T23:36:19.10011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2838}
{"level":"info","ts":"2026-04-01T23:36:19.17656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2838,"took":"74.247079ms","hash":229891130,"current-db-size-bytes":57864192,"current-db-size":"58 MB","current-db-size-in-use-bytes":32845824,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-04-01T23:36:19.176633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":229891130,"revision":2838,"compact-revision":-1}
{"level":"info","ts":"2026-04-01T23:40:50.429519Z","caller":"traceutil/trace.go:171","msg":"trace[1264164463] linearizableReadLoop","detail":"{readStateIndex:8222; appliedIndex:8221; }","duration":"266.309392ms","start":"2026-04-01T23:40:50.163178Z","end":"2026-04-01T23:40:50.429488Z","steps":["trace[1264164463] 'read index received'  (duration: 266.105377ms)","trace[1264164463] 'applied index is now lower than readState.Index'  (duration: 202.165µs)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:40:50.429589Z","caller":"traceutil/trace.go:171","msg":"trace[1484676795] transaction","detail":"{read_only:false; response_revision:7945; number_of_response:1; }","duration":"267.748529ms","start":"2026-04-01T23:40:50.161809Z","end":"2026-04-01T23:40:50.429557Z","steps":["trace[1484676795] 'process raft request'  (duration: 267.475682ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:40:50.429841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.628901ms","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-04-01T23:40:50.429927Z","caller":"traceutil/trace.go:171","msg":"trace[1507940573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7945; }","duration":"266.760344ms","start":"2026-04-01T23:40:50.163147Z","end":"2026-04-01T23:40:50.429908Z","steps":["trace[1507940573] 'agreement among raft nodes before linearized reading'  (duration: 266.500896ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:40:50.528669Z","caller":"traceutil/trace.go:171","msg":"trace[1681585196] transaction","detail":"{read_only:false; response_revision:7946; number_of_response:1; }","duration":"106.684743ms","start":"2026-04-01T23:40:50.421866Z","end":"2026-04-01T23:40:50.528551Z","steps":["trace[1681585196] 'process raft request'  (duration: 73.832956ms)","trace[1681585196] 'compare'  (duration: 32.511798ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:40:50.528696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.590462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"warn","ts":"2026-04-01T23:40:50.528742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.469986ms","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-04-01T23:40:50.528741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.611756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"warn","ts":"2026-04-01T23:40:50.528735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.748833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-01T23:40:50.528795Z","caller":"traceutil/trace.go:171","msg":"trace[1662698529] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7946; }","duration":"361.719755ms","start":"2026-04-01T23:40:50.167046Z","end":"2026-04-01T23:40:50.528766Z","steps":["trace[1662698529] 'agreement among raft nodes before linearized reading'  (duration: 361.468688ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:40:50.528829Z","caller":"traceutil/trace.go:171","msg":"trace[1712679882] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:7946; }","duration":"155.749299ms","start":"2026-04-01T23:40:50.37306Z","end":"2026-04-01T23:40:50.52881Z","steps":["trace[1712679882] 'agreement among raft nodes before linearized reading'  (duration: 155.493942ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:40:50.528845Z","caller":"traceutil/trace.go:171","msg":"trace[2063410528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7946; }","duration":"364.577188ms","start":"2026-04-01T23:40:50.164224Z","end":"2026-04-01T23:40:50.528801Z","steps":["trace[2063410528] 'agreement among raft nodes before linearized reading'  (duration: 364.402824ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:40:50.52885Z","caller":"traceutil/trace.go:171","msg":"trace[784110905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7946; }","duration":"364.988629ms","start":"2026-04-01T23:40:50.163838Z","end":"2026-04-01T23:40:50.528827Z","steps":["trace[784110905] 'agreement among raft nodes before linearized reading'  (duration: 364.62192ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:40:50.528857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:40:50.167034Z","time spent":"361.808957ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-01T23:40:50.528885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:40:50.16421Z","time spent":"364.66629ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-04-01T23:40:50.52891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:40:50.163813Z","time spent":"365.083581ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-01T23:40:50.528762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.658214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-04-01T23:40:50.528984Z","caller":"traceutil/trace.go:171","msg":"trace[705083442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7946; }","duration":"158.962453ms","start":"2026-04-01T23:40:50.370011Z","end":"2026-04-01T23:40:50.528973Z","steps":["trace[705083442] 'agreement among raft nodes before linearized reading'  (duration: 158.494201ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:01.102849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.182653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:41:01.102947Z","caller":"traceutil/trace.go:171","msg":"trace[733718141] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8006; }","duration":"115.299626ms","start":"2026-04-01T23:41:00.987622Z","end":"2026-04-01T23:41:01.102921Z","steps":["trace[733718141] 'range keys from in-memory index tree'  (duration: 115.067759ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:02.313496Z","caller":"traceutil/trace.go:171","msg":"trace[1480061764] transaction","detail":"{read_only:false; response_revision:8007; number_of_response:1; }","duration":"721.667694ms","start":"2026-04-01T23:41:01.591241Z","end":"2026-04-01T23:41:02.312909Z","steps":["trace[1480061764] 'process raft request'  (duration: 721.52282ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:02.314041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:01.591221Z","time spent":"722.361962ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:7965 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"info","ts":"2026-04-01T23:41:02.323278Z","caller":"traceutil/trace.go:171","msg":"trace[307114252] linearizableReadLoop","detail":"{readStateIndex:8288; appliedIndex:8286; }","duration":"426.039715ms","start":"2026-04-01T23:41:01.897213Z","end":"2026-04-01T23:41:02.323253Z","steps":["trace[307114252] 'read index received'  (duration: 415.629956ms)","trace[307114252] 'applied index is now lower than readState.Index'  (duration: 10.408679ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:41:02.323395Z","caller":"traceutil/trace.go:171","msg":"trace[1578240209] transaction","detail":"{read_only:false; response_revision:8008; number_of_response:1; }","duration":"577.79642ms","start":"2026-04-01T23:41:01.745588Z","end":"2026-04-01T23:41:02.323384Z","steps":["trace[1578240209] 'process raft request'  (duration: 577.460882ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:02.323464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.25735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-01T23:41:02.323504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:01.745565Z","time spent":"577.859202ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:7995 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-04-01T23:41:02.323523Z","caller":"traceutil/trace.go:171","msg":"trace[1988284879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8009; }","duration":"426.342342ms","start":"2026-04-01T23:41:01.897164Z","end":"2026-04-01T23:41:02.323506Z","steps":["trace[1988284879] 'agreement among raft nodes before linearized reading'  (duration: 426.2721ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:02.323559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:01.897143Z","time spent":"426.406534ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-01T23:41:02.323602Z","caller":"traceutil/trace.go:171","msg":"trace[504246733] transaction","detail":"{read_only:false; response_revision:8009; number_of_response:1; }","duration":"215.828089ms","start":"2026-04-01T23:41:02.107731Z","end":"2026-04-01T23:41:02.323559Z","steps":["trace[504246733] 'process raft request'  (duration: 215.45257ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:02.32391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.179538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-04-01T23:41:02.32397Z","caller":"traceutil/trace.go:171","msg":"trace[1483000924] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:8009; }","duration":"283.287851ms","start":"2026-04-01T23:41:02.040665Z","end":"2026-04-01T23:41:02.323953Z","steps":["trace[1483000924] 'agreement among raft nodes before linearized reading'  (duration: 283.056595ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:03.428233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675773924053131,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-04-01T23:41:03.714771Z","caller":"traceutil/trace.go:171","msg":"trace[1487275867] linearizableReadLoop","detail":"{readStateIndex:8299; appliedIndex:8298; }","duration":"787.103943ms","start":"2026-04-01T23:41:02.927642Z","end":"2026-04-01T23:41:03.714746Z","steps":["trace[1487275867] 'read index received'  (duration: 786.778874ms)","trace[1487275867] 'applied index is now lower than readState.Index'  (duration: 323.999µs)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:41:03.714852Z","caller":"traceutil/trace.go:171","msg":"trace[1321563014] transaction","detail":"{read_only:false; response_revision:8018; number_of_response:1; }","duration":"808.432742ms","start":"2026-04-01T23:41:02.906375Z","end":"2026-04-01T23:41:03.714807Z","steps":["trace[1321563014] 'process raft request'  (duration: 808.144584ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:03.715054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.38979ms","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-04-01T23:41:03.715049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:02.906361Z","time spent":"808.582726ms","remote":"127.0.0.1:42912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19338,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:7265 > success:<request_put:<key:\"/registry/minions/instance\" value_size:19302 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"warn","ts":"2026-04-01T23:41:03.715088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.89827ms","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-04-01T23:41:03.715169Z","caller":"traceutil/trace.go:171","msg":"trace[2137750396] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:8018; }","duration":"270.983773ms","start":"2026-04-01T23:41:03.444163Z","end":"2026-04-01T23:41:03.715147Z","steps":["trace[2137750396] 'agreement among raft nodes before linearized reading'  (duration: 270.839829ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:03.715107Z","caller":"traceutil/trace.go:171","msg":"trace[1029392263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8018; }","duration":"787.486202ms","start":"2026-04-01T23:41:02.927598Z","end":"2026-04-01T23:41:03.715084Z","steps":["trace[1029392263] 'agreement among raft nodes before linearized reading'  (duration: 787.334798ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:03.715361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:02.927579Z","time spent":"787.764778ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-04-01T23:41:19.107441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5932}
{"level":"info","ts":"2026-04-01T23:41:19.187478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5932,"took":"78.594788ms","hash":3293585931,"current-db-size-bytes":57864192,"current-db-size":"58 MB","current-db-size-in-use-bytes":25853952,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-04-01T23:41:19.187542Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3293585931,"revision":5932,"compact-revision":2838}
{"level":"warn","ts":"2026-04-01T23:41:29.021884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.614806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-01T23:41:29.021852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.736591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-01T23:41:29.021992Z","caller":"traceutil/trace.go:171","msg":"trace[2011022569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8200; }","duration":"124.735799ms","start":"2026-04-01T23:41:28.897205Z","end":"2026-04-01T23:41:29.02194Z","steps":["trace[2011022569] 'range keys from in-memory index tree'  (duration: 124.525344ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:29.02203Z","caller":"traceutil/trace.go:171","msg":"trace[1652493901] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8200; }","duration":"133.988707ms","start":"2026-04-01T23:41:28.888016Z","end":"2026-04-01T23:41:29.022004Z","steps":["trace[1652493901] 'count revisions from in-memory index tree'  (duration: 133.613618ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:29.022069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.23056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"warn","ts":"2026-04-01T23:41:29.02219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.912249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"warn","ts":"2026-04-01T23:41:29.0222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.611357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-04-01T23:41:29.022247Z","caller":"traceutil/trace.go:171","msg":"trace[1610829620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8200; }","duration":"231.008892ms","start":"2026-04-01T23:41:28.791221Z","end":"2026-04-01T23:41:29.02223Z","steps":["trace[1610829620] 'range keys from in-memory index tree'  (duration: 230.58251ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:29.022256Z","caller":"traceutil/trace.go:171","msg":"trace[411813745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8200; }","duration":"205.723039ms","start":"2026-04-01T23:41:28.816518Z","end":"2026-04-01T23:41:29.022241Z","steps":["trace[411813745] 'range keys from in-memory index tree'  (duration: 205.41682ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:29.021904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.112129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/rook-ceph/csi-nfsplugin-metrics\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-01T23:41:29.022132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.746634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-04-01T23:41:29.022363Z","caller":"traceutil/trace.go:171","msg":"trace[837448625] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:8200; }","duration":"223.550539ms","start":"2026-04-01T23:41:28.798796Z","end":"2026-04-01T23:41:29.022347Z","steps":["trace[837448625] 'range keys from in-memory index tree'  (duration: 223.118428ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:29.022385Z","caller":"traceutil/trace.go:171","msg":"trace[1550569634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8200; }","duration":"267.02ms","start":"2026-04-01T23:41:28.755349Z","end":"2026-04-01T23:41:29.022369Z","steps":["trace[1550569634] 'range keys from in-memory index tree'  (duration: 266.578809ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:29.022407Z","caller":"traceutil/trace.go:171","msg":"trace[989466066] range","detail":"{range_begin:/registry/services/specs/rook-ceph/csi-nfsplugin-metrics; range_end:; response_count:0; response_revision:8200; }","duration":"137.644702ms","start":"2026-04-01T23:41:28.884724Z","end":"2026-04-01T23:41:29.022369Z","steps":["trace[989466066] 'range keys from in-memory index tree'  (duration: 137.019467ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:29.022331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.533535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-01T23:41:29.022731Z","caller":"traceutil/trace.go:171","msg":"trace[568935417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8200; }","duration":"232.010526ms","start":"2026-04-01T23:41:28.790701Z","end":"2026-04-01T23:41:29.022712Z","steps":["trace[568935417] 'range keys from in-memory index tree'  (duration: 231.329759ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:29.022768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.702251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"}
{"level":"info","ts":"2026-04-01T23:41:29.022875Z","caller":"traceutil/trace.go:171","msg":"trace[439063835] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8200; }","duration":"195.816173ms","start":"2026-04-01T23:41:28.82702Z","end":"2026-04-01T23:41:29.022837Z","steps":["trace[439063835] 'range keys from in-memory index tree'  (duration: 195.574477ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:30.911076Z","caller":"traceutil/trace.go:171","msg":"trace[638648214] transaction","detail":"{read_only:false; response_revision:8212; number_of_response:1; }","duration":"223.239871ms","start":"2026-04-01T23:41:30.687819Z","end":"2026-04-01T23:41:30.911059Z","steps":["trace[638648214] 'process raft request'  (duration: 223.110908ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:30.91245Z","caller":"traceutil/trace.go:171","msg":"trace[230741771] transaction","detail":"{read_only:false; response_revision:8213; number_of_response:1; }","duration":"187.724045ms","start":"2026-04-01T23:41:30.724716Z","end":"2026-04-01T23:41:30.91244Z","steps":["trace[230741771] 'process raft request'  (duration: 187.583061ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:32.397194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675773924053768,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-04-01T23:41:32.429494Z","caller":"traceutil/trace.go:171","msg":"trace[911481595] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"548.563166ms","start":"2026-04-01T23:41:31.880902Z","end":"2026-04-01T23:41:32.429466Z","steps":["trace[911481595] 'process raft request'  (duration: 548.355941ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:32.429657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:31.88088Z","time spent":"548.6983ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:8209 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-04-01T23:41:32.430205Z","caller":"traceutil/trace.go:171","msg":"trace[2110498067] linearizableReadLoop","detail":"{readStateIndex:8509; appliedIndex:8507; }","duration":"533.056786ms","start":"2026-04-01T23:41:31.897132Z","end":"2026-04-01T23:41:32.430189Z","steps":["trace[2110498067] 'read index received'  (duration: 532.120922ms)","trace[2110498067] 'applied index is now lower than readState.Index'  (duration: 934.594µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:41:32.430544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.406875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:41:32.4307Z","caller":"traceutil/trace.go:171","msg":"trace[171665945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8221; }","duration":"533.58926ms","start":"2026-04-01T23:41:31.897094Z","end":"2026-04-01T23:41:32.430684Z","steps":["trace[171665945] 'agreement among raft nodes before linearized reading'  (duration: 533.419986ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:32.430747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:31.897067Z","time spent":"533.668252ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-04-01T23:41:32.430991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.009768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-04-01T23:41:32.431032Z","caller":"traceutil/trace.go:171","msg":"trace[1625494608] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:8221; }","duration":"389.10047ms","start":"2026-04-01T23:41:32.041922Z","end":"2026-04-01T23:41:32.431022Z","steps":["trace[1625494608] 'agreement among raft nodes before linearized reading'  (duration: 388.971377ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:32.431059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:32.041899Z","time spent":"389.153753ms","remote":"127.0.0.1:42766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "}
{"level":"warn","ts":"2026-04-01T23:41:32.431809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.896334ms","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-04-01T23:41:32.431869Z","caller":"traceutil/trace.go:171","msg":"trace[1423286882] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8221; }","duration":"225.254642ms","start":"2026-04-01T23:41:32.206597Z","end":"2026-04-01T23:41:32.431852Z","steps":["trace[1423286882] 'agreement among raft nodes before linearized reading'  (duration: 224.912554ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:32.664202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.400751ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924053775 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:4d269d4b5e84130e>","response":"size:40"}
{"level":"info","ts":"2026-04-01T23:41:32.664497Z","caller":"traceutil/trace.go:171","msg":"trace[633724872] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"187.808776ms","start":"2026-04-01T23:41:32.476672Z","end":"2026-04-01T23:41:32.664481Z","steps":["trace[633724872] 'process raft request'  (duration: 187.628512ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:45.289201Z","caller":"traceutil/trace.go:171","msg":"trace[837150788] linearizableReadLoop","detail":"{readStateIndex:8623; appliedIndex:8622; }","duration":"187.56516ms","start":"2026-04-01T23:41:45.10161Z","end":"2026-04-01T23:41:45.289176Z","steps":["trace[837150788] 'read index received'  (duration: 162.081722ms)","trace[837150788] 'applied index is now lower than readState.Index'  (duration: 25.482378ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:41:45.289332Z","caller":"traceutil/trace.go:171","msg":"trace[1487003119] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"188.640159ms","start":"2026-04-01T23:41:45.100675Z","end":"2026-04-01T23:41:45.289316Z","steps":["trace[1487003119] 'process raft request'  (duration: 163.068058ms)","trace[1487003119] 'compare'  (duration: 25.277342ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:41:45.289558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.93295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-01T23:41:45.289676Z","caller":"traceutil/trace.go:171","msg":"trace[150002678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8330; }","duration":"188.074953ms","start":"2026-04-01T23:41:45.101579Z","end":"2026-04-01T23:41:45.289654Z","steps":["trace[150002678] 'agreement among raft nodes before linearized reading'  (duration: 187.812836ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:45.32025Z","caller":"traceutil/trace.go:171","msg":"trace[1876968410] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"217.936644ms","start":"2026-04-01T23:41:45.102297Z","end":"2026-04-01T23:41:45.320234Z","steps":["trace[1876968410] 'process raft request'  (duration: 217.77588ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:45.320528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.119392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"info","ts":"2026-04-01T23:41:45.320568Z","caller":"traceutil/trace.go:171","msg":"trace[343367505] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:8332; }","duration":"203.202284ms","start":"2026-04-01T23:41:45.117356Z","end":"2026-04-01T23:41:45.320558Z","steps":["trace[343367505] 'agreement among raft nodes before linearized reading'  (duration: 203.063441ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:45.320717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.263559ms","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-04-01T23:41:45.320749Z","caller":"traceutil/trace.go:171","msg":"trace[1633546517] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"218.024726ms","start":"2026-04-01T23:41:45.102462Z","end":"2026-04-01T23:41:45.320487Z","steps":["trace[1633546517] 'process raft request'  (duration: 217.726489ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:45.320818Z","caller":"traceutil/trace.go:171","msg":"trace[1009536335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8332; }","duration":"214.398332ms","start":"2026-04-01T23:41:45.106398Z","end":"2026-04-01T23:41:45.320796Z","steps":["trace[1009536335] 'agreement among raft nodes before linearized reading'  (duration: 214.117744ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:46.096646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.855415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-01T23:41:46.096747Z","caller":"traceutil/trace.go:171","msg":"trace[1023875303] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8336; }","duration":"234.013729ms","start":"2026-04-01T23:41:45.862707Z","end":"2026-04-01T23:41:46.09672Z","steps":["trace[1023875303] 'count revisions from in-memory index tree'  (duration: 233.767473ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:46.097122Z","caller":"traceutil/trace.go:171","msg":"trace[1432751519] linearizableReadLoop","detail":"{readStateIndex:8630; appliedIndex:8629; }","duration":"175.780456ms","start":"2026-04-01T23:41:45.921314Z","end":"2026-04-01T23:41:46.097094Z","steps":["trace[1432751519] 'read index received'  (duration: 104.679311ms)","trace[1432751519] 'applied index is now lower than readState.Index'  (duration: 71.100185ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:41:46.097177Z","caller":"traceutil/trace.go:171","msg":"trace[297938258] transaction","detail":"{read_only:false; response_revision:8337; number_of_response:1; }","duration":"196.205534ms","start":"2026-04-01T23:41:45.90092Z","end":"2026-04-01T23:41:46.097125Z","steps":["trace[297938258] 'process raft request'  (duration: 125.172571ms)","trace[297938258] 'compare'  (duration: 70.807717ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:41:46.097592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.255428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-04-01T23:41:46.097641Z","caller":"traceutil/trace.go:171","msg":"trace[1131014284] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8337; }","duration":"176.359941ms","start":"2026-04-01T23:41:45.92127Z","end":"2026-04-01T23:41:46.097629Z","steps":["trace[1131014284] 'agreement among raft nodes before linearized reading'  (duration: 175.934341ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:46.325048Z","caller":"traceutil/trace.go:171","msg":"trace[917541212] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"222.02519ms","start":"2026-04-01T23:41:46.103Z","end":"2026-04-01T23:41:46.325025Z","steps":["trace[917541212] 'process raft request'  (duration: 221.867386ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:47.360575Z","caller":"traceutil/trace.go:171","msg":"trace[1341289540] transaction","detail":"{read_only:false; response_revision:8341; number_of_response:1; }","duration":"339.576663ms","start":"2026-04-01T23:41:47.020969Z","end":"2026-04-01T23:41:47.360546Z","steps":["trace[1341289540] 'process raft request'  (duration: 339.379599ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:47.360759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.020945Z","time spent":"339.722506ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:8328 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-04-01T23:41:47.626655Z","caller":"traceutil/trace.go:171","msg":"trace[592994201] linearizableReadLoop","detail":"{readStateIndex:8635; appliedIndex:8633; }","duration":"554.323524ms","start":"2026-04-01T23:41:47.072298Z","end":"2026-04-01T23:41:47.626621Z","steps":["trace[592994201] 'read index received'  (duration: 288.043962ms)","trace[592994201] 'applied index is now lower than readState.Index'  (duration: 266.278322ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:41:47.627101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.784217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-01T23:41:47.627176Z","caller":"traceutil/trace.go:171","msg":"trace[923277890] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8342; }","duration":"554.899309ms","start":"2026-04-01T23:41:47.072257Z","end":"2026-04-01T23:41:47.627157Z","steps":["trace[923277890] 'agreement among raft nodes before linearized reading'  (duration: 554.650323ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:47.62721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.07224Z","time spent":"554.963731ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-04-01T23:41:47.627459Z","caller":"traceutil/trace.go:171","msg":"trace[602066241] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"604.343147ms","start":"2026-04-01T23:41:47.023108Z","end":"2026-04-01T23:41:47.627451Z","steps":["trace[602066241] 'process raft request'  (duration: 603.221968ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:47.627527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.023099Z","time spent":"604.381158ms","remote":"127.0.0.1:58692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" mod_revision:8178 > success:<request_put:<key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" value_size:5405 >> failure:<request_range:<key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" > >"}
{"level":"warn","ts":"2026-04-01T23:41:47.669336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.453534ms","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-04-01T23:41:47.669553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.061351ms","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-04-01T23:41:47.669534Z","caller":"traceutil/trace.go:171","msg":"trace[1752895636] transaction","detail":"{read_only:false; response_revision:8343; number_of_response:1; }","duration":"223.886178ms","start":"2026-04-01T23:41:47.44562Z","end":"2026-04-01T23:41:47.669506Z","steps":["trace[1752895636] 'process raft request'  (duration: 223.404225ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:47.669608Z","caller":"traceutil/trace.go:171","msg":"trace[278749821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8343; }","duration":"322.144313ms","start":"2026-04-01T23:41:47.347451Z","end":"2026-04-01T23:41:47.669595Z","steps":["trace[278749821] 'agreement among raft nodes before linearized reading'  (duration: 321.989329ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:47.669336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.974419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"}
{"level":"warn","ts":"2026-04-01T23:41:47.669648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.347442Z","time spent":"322.197104ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-04-01T23:41:47.669676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.225728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"info","ts":"2026-04-01T23:41:47.669739Z","caller":"traceutil/trace.go:171","msg":"trace[459134538] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8343; }","duration":"341.3852ms","start":"2026-04-01T23:41:47.328305Z","end":"2026-04-01T23:41:47.66969Z","steps":["trace[459134538] 'agreement among raft nodes before linearized reading'  (duration: 340.890577ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:47.669819Z","caller":"traceutil/trace.go:171","msg":"trace[1890084637] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:8343; }","duration":"319.406932ms","start":"2026-04-01T23:41:47.350364Z","end":"2026-04-01T23:41:47.669771Z","steps":["trace[1890084637] 'agreement among raft nodes before linearized reading'  (duration: 319.015622ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:47.669791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.284975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-04-01T23:41:47.669883Z","caller":"traceutil/trace.go:171","msg":"trace[1380877587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8343; }","duration":"375.414598ms","start":"2026-04-01T23:41:47.294455Z","end":"2026-04-01T23:41:47.66987Z","steps":["trace[1380877587] 'agreement among raft nodes before linearized reading'  (duration: 375.214303ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:47.669899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.328281Z","time spent":"341.554954ms","remote":"127.0.0.1:58638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":3196,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" "}
{"level":"warn","ts":"2026-04-01T23:41:47.669911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.350352Z","time spent":"319.546016ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-w6nkh\" "}
{"level":"info","ts":"2026-04-01T23:41:47.669418Z","caller":"traceutil/trace.go:171","msg":"trace[258709599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8343; }","duration":"333.562487ms","start":"2026-04-01T23:41:47.335837Z","end":"2026-04-01T23:41:47.6694Z","steps":["trace[258709599] 'agreement among raft nodes before linearized reading'  (duration: 333.364353ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:47.669955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.294437Z","time spent":"375.50714ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-04-01T23:41:47.67012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.335827Z","time spent":"334.268225ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-01T23:41:47.670138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.663229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-01T23:41:47.670252Z","caller":"traceutil/trace.go:171","msg":"trace[778460586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8343; }","duration":"344.385357ms","start":"2026-04-01T23:41:47.325835Z","end":"2026-04-01T23:41:47.67022Z","steps":["trace[778460586] 'agreement among raft nodes before linearized reading'  (duration: 343.545045ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:47.670353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.325801Z","time spent":"344.529261ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-04-01T23:41:47.721552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.723562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-04-01T23:41:47.721748Z","caller":"traceutil/trace.go:171","msg":"trace[1296184584] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8343; }","duration":"395.964428ms","start":"2026-04-01T23:41:47.325749Z","end":"2026-04-01T23:41:47.721713Z","steps":["trace[1296184584] 'agreement among raft nodes before linearized reading'  (duration: 344.552631ms)","trace[1296184584] 'range keys from in-memory index tree'  (duration: 51.050347ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:41:47.721988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:41:47.325733Z","time spent":"396.06475ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"info","ts":"2026-04-01T23:41:48.266795Z","caller":"traceutil/trace.go:171","msg":"trace[1491957576] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"162.424712ms","start":"2026-04-01T23:41:48.104346Z","end":"2026-04-01T23:41:48.266771Z","steps":["trace[1491957576] 'process raft request'  (duration: 162.217356ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:58.772226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.783268ms","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-04-01T23:41:58.772277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.441312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-fdd77fddf-lgznd.18a260157fe524a2\" ","response":"range_response_count:1 size:1131"}
{"level":"info","ts":"2026-04-01T23:41:58.77231Z","caller":"traceutil/trace.go:171","msg":"trace[1303684152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8500; }","duration":"153.919002ms","start":"2026-04-01T23:41:58.618368Z","end":"2026-04-01T23:41:58.772287Z","steps":["trace[1303684152] 'range keys from in-memory index tree'  (duration: 153.566122ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:41:58.772353Z","caller":"traceutil/trace.go:171","msg":"trace[1326890948] range","detail":"{range_begin:/registry/events/openstack/barbican-api-fdd77fddf-lgznd.18a260157fe524a2; range_end:; response_count:1; response_revision:8500; }","duration":"174.530854ms","start":"2026-04-01T23:41:58.597802Z","end":"2026-04-01T23:41:58.772333Z","steps":["trace[1326890948] 'range keys from in-memory index tree'  (duration: 174.170294ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:58.772455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.028265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-818d2c8a-1dc4-43c6-9d87-5244a83f0040\" ","response":"range_response_count:1 size:3930"}
{"level":"info","ts":"2026-04-01T23:41:58.772568Z","caller":"traceutil/trace.go:171","msg":"trace[389895845] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-818d2c8a-1dc4-43c6-9d87-5244a83f0040; range_end:; response_count:1; response_revision:8500; }","duration":"142.160998ms","start":"2026-04-01T23:41:58.630363Z","end":"2026-04-01T23:41:58.772524Z","steps":["trace[389895845] 'range keys from in-memory index tree'  (duration: 141.83906ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:41:58.772688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.673117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ceph-object-controller-detect-version-zmrtq\" ","response":"range_response_count:1 size:4558"}
{"level":"info","ts":"2026-04-01T23:41:58.77275Z","caller":"traceutil/trace.go:171","msg":"trace[1223064309] range","detail":"{range_begin:/registry/pods/openstack/ceph-object-controller-detect-version-zmrtq; range_end:; response_count:1; response_revision:8500; }","duration":"129.774709ms","start":"2026-04-01T23:41:58.642959Z","end":"2026-04-01T23:41:58.772733Z","steps":["trace[1223064309] 'range keys from in-memory index tree'  (duration: 129.293937ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:42:42.042112Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002404.wal"}
{"level":"warn","ts":"2026-04-01T23:42:43.861464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.120272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:42:43.861558Z","caller":"traceutil/trace.go:171","msg":"trace[808216259] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8920; }","duration":"141.273076ms","start":"2026-04-01T23:42:43.720264Z","end":"2026-04-01T23:42:43.861537Z","steps":["trace[808216259] 'count revisions from in-memory index tree'  (duration: 141.024939ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:42:44.200044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.922976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-01T23:42:44.20014Z","caller":"traceutil/trace.go:171","msg":"trace[1528205931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8924; }","duration":"139.050899ms","start":"2026-04-01T23:42:44.061069Z","end":"2026-04-01T23:42:44.20012Z","steps":["trace[1528205931] 'agreement among raft nodes before linearized reading'  (duration: 138.811932ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:42:44.200251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.034537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-04-01T23:42:44.200485Z","caller":"traceutil/trace.go:171","msg":"trace[950312086] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8924; }","duration":"137.301543ms","start":"2026-04-01T23:42:44.06316Z","end":"2026-04-01T23:42:44.200462Z","steps":["trace[950312086] 'agreement among raft nodes before linearized reading'  (duration: 136.862352ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:42:44.200877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.747626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"info","ts":"2026-04-01T23:42:44.200992Z","caller":"traceutil/trace.go:171","msg":"trace[2081535980] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:8924; }","duration":"120.89421ms","start":"2026-04-01T23:42:44.080076Z","end":"2026-04-01T23:42:44.20097Z","steps":["trace[2081535980] 'agreement among raft nodes before linearized reading'  (duration: 119.872134ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:42:44.422326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.117734ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14782675773924055815 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:8914 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-01T23:42:44.422552Z","caller":"traceutil/trace.go:171","msg":"trace[138991162] linearizableReadLoop","detail":"{readStateIndex:9236; appliedIndex:9233; }","duration":"215.978443ms","start":"2026-04-01T23:42:44.206557Z","end":"2026-04-01T23:42:44.422536Z","steps":["trace[138991162] 'read index received'  (duration: 108.565451ms)","trace[138991162] 'applied index is now lower than readState.Index'  (duration: 107.410222ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:42:44.422744Z","caller":"traceutil/trace.go:171","msg":"trace[769446179] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"218.378986ms","start":"2026-04-01T23:42:44.204352Z","end":"2026-04-01T23:42:44.422731Z","steps":["trace[769446179] 'process raft request'  (duration: 218.070767ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:42:44.422791Z","caller":"traceutil/trace.go:171","msg":"trace[97303772] transaction","detail":"{read_only:false; response_revision:8927; number_of_response:1; }","duration":"217.872192ms","start":"2026-04-01T23:42:44.204884Z","end":"2026-04-01T23:42:44.422757Z","steps":["trace[97303772] 'process raft request'  (duration: 217.613925ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:42:44.423069Z","caller":"traceutil/trace.go:171","msg":"trace[1631672106] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"283.27824ms","start":"2026-04-01T23:42:44.139755Z","end":"2026-04-01T23:42:44.423033Z","steps":["trace[1631672106] 'process raft request'  (duration: 175.354665ms)","trace[1631672106] 'compare'  (duration: 106.981511ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:42:44.423161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.594129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:244603"}
{"level":"info","ts":"2026-04-01T23:42:44.423231Z","caller":"traceutil/trace.go:171","msg":"trace[684293197] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:8927; }","duration":"216.665141ms","start":"2026-04-01T23:42:44.20653Z","end":"2026-04-01T23:42:44.423195Z","steps":["trace[684293197] 'agreement among raft nodes before linearized reading'  (duration: 216.158308ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:42:48.989143Z","caller":"traceutil/trace.go:171","msg":"trace[596802988] linearizableReadLoop","detail":"{readStateIndex:9257; appliedIndex:9255; }","duration":"546.256737ms","start":"2026-04-01T23:42:48.442861Z","end":"2026-04-01T23:42:48.989118Z","steps":["trace[596802988] 'read index received'  (duration: 69.917965ms)","trace[596802988] 'applied index is now lower than readState.Index'  (duration: 476.337602ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:42:48.989174Z","caller":"traceutil/trace.go:171","msg":"trace[215168262] transaction","detail":"{read_only:false; response_revision:8947; number_of_response:1; }","duration":"546.462092ms","start":"2026-04-01T23:42:48.442664Z","end":"2026-04-01T23:42:48.989127Z","steps":["trace[215168262] 'process raft request'  (duration: 521.697173ms)","trace[215168262] 'compare'  (duration: 24.264017ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:42:48.989154Z","caller":"traceutil/trace.go:171","msg":"trace[1129475937] transaction","detail":"{read_only:false; response_revision:8948; number_of_response:1; }","duration":"545.073646ms","start":"2026-04-01T23:42:48.444057Z","end":"2026-04-01T23:42:48.98913Z","steps":["trace[1129475937] 'process raft request'  (duration: 544.951073ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:42:48.989349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:42:48.442654Z","time spent":"546.594895ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:8938 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-01T23:42:48.98935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:42:48.44405Z","time spent":"545.207559ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:8937 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-01T23:42:48.989386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.499463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"info","ts":"2026-04-01T23:42:48.989486Z","caller":"traceutil/trace.go:171","msg":"trace[670879906] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:8948; }","duration":"546.630207ms","start":"2026-04-01T23:42:48.442832Z","end":"2026-04-01T23:42:48.989462Z","steps":["trace[670879906] 'agreement among raft nodes before linearized reading'  (duration: 546.37548ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:42:48.989572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:42:48.442816Z","time spent":"546.741139ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-w6nkh\" "}
{"level":"warn","ts":"2026-04-01T23:42:48.994619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.971044ms","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":"warn","ts":"2026-04-01T23:42:48.994648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.509118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-fdd77fddf-lgznd.18a260157fe524a2\" ","response":"range_response_count:1 size:1131"}
{"level":"info","ts":"2026-04-01T23:42:48.994706Z","caller":"traceutil/trace.go:171","msg":"trace[1443302631] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8949; }","duration":"154.066175ms","start":"2026-04-01T23:42:48.84061Z","end":"2026-04-01T23:42:48.994676Z","steps":["trace[1443302631] 'agreement among raft nodes before linearized reading'  (duration: 153.926702ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:42:48.994766Z","caller":"traceutil/trace.go:171","msg":"trace[766928634] range","detail":"{range_begin:/registry/events/openstack/barbican-api-fdd77fddf-lgznd.18a260157fe524a2; range_end:; response_count:1; response_revision:8949; }","duration":"397.626411ms","start":"2026-04-01T23:42:48.597074Z","end":"2026-04-01T23:42:48.994701Z","steps":["trace[766928634] 'agreement among raft nodes before linearized reading'  (duration: 397.454877ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:42:48.994812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:42:48.597046Z","time spent":"397.754154ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1154,"request content":"key:\"/registry/events/openstack/barbican-api-fdd77fddf-lgznd.18a260157fe524a2\" "}
{"level":"info","ts":"2026-04-01T23:42:49.195485Z","caller":"traceutil/trace.go:171","msg":"trace[1602243819] transaction","detail":"{read_only:false; response_revision:8951; number_of_response:1; }","duration":"157.644788ms","start":"2026-04-01T23:42:49.037823Z","end":"2026-04-01T23:42:49.195468Z","steps":["trace[1602243819] 'process raft request'  (duration: 123.059196ms)","trace[1602243819] 'compare'  (duration: 34.276734ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:42:56.450364Z","caller":"traceutil/trace.go:171","msg":"trace[1851469461] linearizableReadLoop","detail":"{readStateIndex:9324; appliedIndex:9323; }","duration":"131.01609ms","start":"2026-04-01T23:42:56.319322Z","end":"2026-04-01T23:42:56.450338Z","steps":["trace[1851469461] 'read index received'  (duration: 77.000377ms)","trace[1851469461] 'applied index is now lower than readState.Index'  (duration: 54.014623ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:42:56.450445Z","caller":"traceutil/trace.go:171","msg":"trace[1306394398] transaction","detail":"{read_only:false; response_revision:9013; number_of_response:1; }","duration":"162.562665ms","start":"2026-04-01T23:42:56.287842Z","end":"2026-04-01T23:42:56.450405Z","steps":["trace[1306394398] 'process raft request'  (duration: 108.583342ms)","trace[1306394398] 'compare'  (duration: 53.760878ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:42:56.450679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.337239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-operator-config\" ","response":"range_response_count:1 size:6824"}
{"level":"info","ts":"2026-04-01T23:42:56.450743Z","caller":"traceutil/trace.go:171","msg":"trace[395529691] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-operator-config; range_end:; response_count:1; response_revision:9013; }","duration":"131.490103ms","start":"2026-04-01T23:42:56.319237Z","end":"2026-04-01T23:42:56.450727Z","steps":["trace[395529691] 'agreement among raft nodes before linearized reading'  (duration: 131.200996ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:43:25.947125Z","caller":"traceutil/trace.go:171","msg":"trace[1063835014] transaction","detail":"{read_only:false; response_revision:9280; number_of_response:1; }","duration":"127.971361ms","start":"2026-04-01T23:43:25.819131Z","end":"2026-04-01T23:43:25.947102Z","steps":["trace[1063835014] 'process raft request'  (duration: 127.723425ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:43:26.139548Z","caller":"traceutil/trace.go:171","msg":"trace[706175471] transaction","detail":"{read_only:false; response_revision:9281; number_of_response:1; }","duration":"183.095402ms","start":"2026-04-01T23:43:25.956429Z","end":"2026-04-01T23:43:26.139525Z","steps":["trace[706175471] 'process raft request'  (duration: 121.382151ms)","trace[706175471] 'compare'  (duration: 61.580958ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-01T23:43:26.394846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.210396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-04-01T23:43:26.394918Z","caller":"traceutil/trace.go:171","msg":"trace[1546444800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9282; }","duration":"133.34508ms","start":"2026-04-01T23:43:26.261556Z","end":"2026-04-01T23:43:26.394901Z","steps":["trace[1546444800] 'range keys from in-memory index tree'  (duration: 132.987801ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:43:26.583624Z","caller":"traceutil/trace.go:171","msg":"trace[926515580] transaction","detail":"{read_only:false; response_revision:9284; number_of_response:1; }","duration":"184.372586ms","start":"2026-04-01T23:43:26.399222Z","end":"2026-04-01T23:43:26.583595Z","steps":["trace[926515580] 'process raft request'  (duration: 115.138181ms)","trace[926515580] 'compare'  (duration: 69.073901ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:43:26.583743Z","caller":"traceutil/trace.go:171","msg":"trace[1798758919] transaction","detail":"{read_only:false; response_revision:9285; number_of_response:1; }","duration":"184.301285ms","start":"2026-04-01T23:43:26.399415Z","end":"2026-04-01T23:43:26.583716Z","steps":["trace[1798758919] 'process raft request'  (duration: 184.1401ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:44:16.874089Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-01T23:44:16.878446Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-04-01T23:44:16.878525Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-04-01T23:46:19.113436Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8106}
{"level":"info","ts":"2026-04-01T23:46:19.171077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8106,"took":"55.809659ms","hash":3181676350,"current-db-size-bytes":57864192,"current-db-size":"58 MB","current-db-size-in-use-bytes":29388800,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-04-01T23:46:19.171127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3181676350,"revision":8106,"compact-revision":5932}
{"level":"warn","ts":"2026-04-01T23:47:00.473358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.83435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"}
{"level":"info","ts":"2026-04-01T23:47:00.473474Z","caller":"traceutil/trace.go:171","msg":"trace[294346768] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:10747; }","duration":"107.983254ms","start":"2026-04-01T23:47:00.365466Z","end":"2026-04-01T23:47:00.47345Z","steps":["trace[294346768] 'range keys from in-memory index tree'  (duration: 107.740018ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:00.989131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675773924060828,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-04-01T23:47:01.489542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675773924060828,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-04-01T23:47:01.556233Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.079442111s","expected-duration":"1s"}
{"level":"info","ts":"2026-04-01T23:47:01.55669Z","caller":"traceutil/trace.go:171","msg":"trace[1339591456] transaction","detail":"{read_only:false; response_revision:10748; number_of_response:1; }","duration":"1.079947793s","start":"2026-04-01T23:47:00.4767Z","end":"2026-04-01T23:47:01.556647Z","steps":["trace[1339591456] 'process raft request'  (duration: 1.079729038s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:01.556806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.476682Z","time spent":"1.080049066s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:10735 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-04-01T23:47:01.557229Z","caller":"traceutil/trace.go:171","msg":"trace[1180838196] linearizableReadLoop","detail":"{readStateIndex:11121; appliedIndex:11117; }","duration":"1.068454447s","start":"2026-04-01T23:47:00.488762Z","end":"2026-04-01T23:47:01.557217Z","steps":["trace[1180838196] 'read index received'  (duration: 1.067617816s)","trace[1180838196] 'applied index is now lower than readState.Index'  (duration: 835.881µs)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:47:01.55732Z","caller":"traceutil/trace.go:171","msg":"trace[1766039151] transaction","detail":"{read_only:false; response_revision:10751; number_of_response:1; }","duration":"787.486282ms","start":"2026-04-01T23:47:00.769821Z","end":"2026-04-01T23:47:01.557307Z","steps":["trace[1766039151] 'process raft request'  (duration: 787.361019ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:47:01.557333Z","caller":"traceutil/trace.go:171","msg":"trace[1394437375] transaction","detail":"{read_only:false; response_revision:10750; number_of_response:1; }","duration":"1.079244764s","start":"2026-04-01T23:47:00.478062Z","end":"2026-04-01T23:47:01.557306Z","steps":["trace[1394437375] 'process raft request'  (duration: 1.07904125s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:01.55739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.769801Z","time spent":"787.547984ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:10739 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-04-01T23:47:01.557484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.478057Z","time spent":"1.079352299s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:10736 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-04-01T23:47:01.557604Z","caller":"traceutil/trace.go:171","msg":"trace[46795602] transaction","detail":"{read_only:false; response_revision:10749; number_of_response:1; }","duration":"1.08057273s","start":"2026-04-01T23:47:00.477021Z","end":"2026-04-01T23:47:01.557594Z","steps":["trace[46795602] 'process raft request'  (duration: 1.080013906s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:01.557681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.105498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:47:01.557715Z","caller":"traceutil/trace.go:171","msg":"trace[461144732] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:10751; }","duration":"864.18619ms","start":"2026-04-01T23:47:00.69352Z","end":"2026-04-01T23:47:01.557706Z","steps":["trace[461144732] 'agreement among raft nodes before linearized reading'  (duration: 864.107328ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:01.55771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.859193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-01T23:47:01.557721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.477005Z","time spent":"1.080617231s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:10737 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-01T23:47:01.557734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.693499Z","time spent":"864.230472ms","remote":"127.0.0.1:58648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "}
{"level":"info","ts":"2026-04-01T23:47:01.557751Z","caller":"traceutil/trace.go:171","msg":"trace[1205260990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10751; }","duration":"659.924895ms","start":"2026-04-01T23:47:00.897813Z","end":"2026-04-01T23:47:01.557738Z","steps":["trace[1205260990] 'agreement among raft nodes before linearized reading'  (duration: 659.848693ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:01.557774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.89777Z","time spent":"659.998867ms","remote":"127.0.0.1:42732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-04-01T23:47:01.557801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.626898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"warn","ts":"2026-04-01T23:47:01.557816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.963929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:47:01.557857Z","caller":"traceutil/trace.go:171","msg":"trace[1444339749] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:10751; }","duration":"815.702321ms","start":"2026-04-01T23:47:00.742144Z","end":"2026-04-01T23:47:01.557846Z","steps":["trace[1444339749] 'agreement among raft nodes before linearized reading'  (duration: 815.543807ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:01.55788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.742129Z","time spent":"815.744741ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-w6nkh\" "}
{"level":"info","ts":"2026-04-01T23:47:01.557894Z","caller":"traceutil/trace.go:171","msg":"trace[1756859760] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:10751; }","duration":"837.060041ms","start":"2026-04-01T23:47:00.720795Z","end":"2026-04-01T23:47:01.557855Z","steps":["trace[1756859760] 'agreement among raft nodes before linearized reading'  (duration: 836.957268ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:01.558038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069256007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:47:01.558159Z","caller":"traceutil/trace.go:171","msg":"trace[1512785705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10751; }","duration":"1.069437263s","start":"2026-04-01T23:47:00.488696Z","end":"2026-04-01T23:47:01.558133Z","steps":["trace[1512785705] 'agreement among raft nodes before linearized reading'  (duration: 1.069059982s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:01.557933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.720771Z","time spent":"837.151813ms","remote":"127.0.0.1:51924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "}
{"level":"warn","ts":"2026-04-01T23:47:01.558252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:00.488673Z","time spent":"1.069562356s","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-01T23:47:03.273653Z","caller":"traceutil/trace.go:171","msg":"trace[2066856904] linearizableReadLoop","detail":"{readStateIndex:11130; appliedIndex:11129; }","duration":"375.712448ms","start":"2026-04-01T23:47:02.897906Z","end":"2026-04-01T23:47:03.273619Z","steps":["trace[2066856904] 'read index received'  (duration: 375.547444ms)","trace[2066856904] 'applied index is now lower than readState.Index'  (duration: 163.854µs)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:47:03.273707Z","caller":"traceutil/trace.go:171","msg":"trace[300058797] transaction","detail":"{read_only:false; response_revision:10759; number_of_response:1; }","duration":"633.145524ms","start":"2026-04-01T23:47:02.640529Z","end":"2026-04-01T23:47:03.273675Z","steps":["trace[300058797] 'process raft request'  (duration: 632.906688ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:03.273857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.936563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-01T23:47:03.274029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:02.640509Z","time spent":"633.268336ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7261,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/staffeln-db-sync-wml2q\" mod_revision:10738 > success:<request_put:<key:\"/registry/pods/openstack/staffeln-db-sync-wml2q\" value_size:7206 >> failure:<request_range:<key:\"/registry/pods/openstack/staffeln-db-sync-wml2q\" > >"}
{"level":"info","ts":"2026-04-01T23:47:03.27409Z","caller":"traceutil/trace.go:171","msg":"trace[775963940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10759; }","duration":"376.218811ms","start":"2026-04-01T23:47:02.897853Z","end":"2026-04-01T23:47:03.274072Z","steps":["trace[775963940] 'agreement among raft nodes before linearized reading'  (duration: 375.932053ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:03.274125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:02.897828Z","time spent":"376.288872ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-01T23:47:03.274691Z","caller":"traceutil/trace.go:171","msg":"trace[927085143] transaction","detail":"{read_only:false; response_revision:10760; number_of_response:1; }","duration":"306.42659ms","start":"2026-04-01T23:47:02.968244Z","end":"2026-04-01T23:47:03.27467Z","steps":["trace[927085143] 'process raft request'  (duration: 306.263066ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:03.274897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:02.96822Z","time spent":"306.599865ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:10716 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"warn","ts":"2026-04-01T23:47:03.274726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.063666ms","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-04-01T23:47:03.275086Z","caller":"traceutil/trace.go:171","msg":"trace[1744312028] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10760; }","duration":"260.451426ms","start":"2026-04-01T23:47:03.01461Z","end":"2026-04-01T23:47:03.275061Z","steps":["trace[1744312028] 'agreement among raft nodes before linearized reading'  (duration: 260.011694ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:47:04.483171Z","caller":"traceutil/trace.go:171","msg":"trace[1368295556] transaction","detail":"{read_only:false; response_revision:10771; number_of_response:1; }","duration":"199.491823ms","start":"2026-04-01T23:47:04.283643Z","end":"2026-04-01T23:47:04.483135Z","steps":["trace[1368295556] 'process raft request'  (duration: 199.064952ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:47:06.550686Z","caller":"traceutil/trace.go:171","msg":"trace[2016551973] transaction","detail":"{read_only:false; response_revision:10781; number_of_response:1; }","duration":"206.35925ms","start":"2026-04-01T23:47:06.344299Z","end":"2026-04-01T23:47:06.550658Z","steps":["trace[2016551973] 'process raft request'  (duration: 206.123624ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.163543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675773924061214,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-04-01T23:47:26.673235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675773924061214,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-04-01T23:47:26.70482Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.062856151s","expected-duration":"1s"}
{"level":"info","ts":"2026-04-01T23:47:26.705187Z","caller":"traceutil/trace.go:171","msg":"trace[1078749474] transaction","detail":"{read_only:false; response_revision:10873; number_of_response:1; }","duration":"1.063302302s","start":"2026-04-01T23:47:25.641865Z","end":"2026-04-01T23:47:26.705167Z","steps":["trace[1078749474] 'process raft request'  (duration: 1.063117927s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.705328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.641841Z","time spent":"1.063407014s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:10864 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-04-01T23:47:26.709964Z","caller":"traceutil/trace.go:171","msg":"trace[493045875] linearizableReadLoop","detail":"{readStateIndex:11250; appliedIndex:11247; }","duration":"1.047197366s","start":"2026-04-01T23:47:25.662746Z","end":"2026-04-01T23:47:26.709944Z","steps":["trace[493045875] 'read index received'  (duration: 1.042220608s)","trace[493045875] 'applied index is now lower than readState.Index'  (duration: 4.975988ms)"],"step_count":2}
{"level":"info","ts":"2026-04-01T23:47:26.709998Z","caller":"traceutil/trace.go:171","msg":"trace[427464811] transaction","detail":"{read_only:false; response_revision:10874; number_of_response:1; }","duration":"915.727211ms","start":"2026-04-01T23:47:25.794255Z","end":"2026-04-01T23:47:26.709982Z","steps":["trace[427464811] 'process raft request'  (duration: 915.501676ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.710103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.794241Z","time spent":"915.792552ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:10843 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"warn","ts":"2026-04-01T23:47:26.710128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04737864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-04-01T23:47:26.710158Z","caller":"traceutil/trace.go:171","msg":"trace[1920564575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10875; }","duration":"1.047439322s","start":"2026-04-01T23:47:25.662711Z","end":"2026-04-01T23:47:26.71015Z","steps":["trace[1920564575] 'agreement among raft nodes before linearized reading'  (duration: 1.047323429s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.710182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.662699Z","time spent":"1.047478553s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-04-01T23:47:26.710308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.020167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-04-01T23:47:26.710038Z","caller":"traceutil/trace.go:171","msg":"trace[1463346925] transaction","detail":"{read_only:false; response_revision:10875; number_of_response:1; }","duration":"522.160391ms","start":"2026-04-01T23:47:26.187839Z","end":"2026-04-01T23:47:26.71Z","steps":["trace[1463346925] 'process raft request'  (duration: 522.070648ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.710362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.631349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:47:26.710384Z","caller":"traceutil/trace.go:171","msg":"trace[479292367] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:10875; }","duration":"535.684111ms","start":"2026-04-01T23:47:26.174694Z","end":"2026-04-01T23:47:26.710378Z","steps":["trace[479292367] 'agreement among raft nodes before linearized reading'  (duration: 535.649649ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.710404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:26.174677Z","time spent":"535.720441ms","remote":"127.0.0.1:51748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "}
{"level":"warn","ts":"2026-04-01T23:47:26.710428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.43713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"}
{"level":"info","ts":"2026-04-01T23:47:26.710481Z","caller":"traceutil/trace.go:171","msg":"trace[239424468] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:10875; }","duration":"524.505342ms","start":"2026-04-01T23:47:26.185962Z","end":"2026-04-01T23:47:26.710468Z","steps":["trace[239424468] 'agreement among raft nodes before linearized reading'  (duration: 524.335907ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.710509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:26.185957Z","time spent":"524.545543ms","remote":"127.0.0.1:42978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5152,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "}
{"level":"warn","ts":"2026-04-01T23:47:26.710528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.490056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-01T23:47:26.710559Z","caller":"traceutil/trace.go:171","msg":"trace[753932084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10875; }","duration":"812.561088ms","start":"2026-04-01T23:47:25.89799Z","end":"2026-04-01T23:47:26.710551Z","steps":["trace[753932084] 'agreement among raft nodes before linearized reading'  (duration: 812.519237ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.710569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.716355ms","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-04-01T23:47:26.710623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.897971Z","time spent":"812.646411ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-01T23:47:26.710693Z","caller":"traceutil/trace.go:171","msg":"trace[1306885777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10875; }","duration":"486.833948ms","start":"2026-04-01T23:47:26.223794Z","end":"2026-04-01T23:47:26.710628Z","steps":["trace[1306885777] 'agreement among raft nodes before linearized reading'  (duration: 486.698405ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.710729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:26.223773Z","time spent":"486.947242ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-04-01T23:47:26.71071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:26.187811Z","time spent":"522.546651ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:10871 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-04-01T23:47:26.710835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010529609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6nkh\" ","response":"range_response_count:1 size:6527"}
{"level":"info","ts":"2026-04-01T23:47:26.712573Z","caller":"traceutil/trace.go:171","msg":"trace[325449057] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6nkh; range_end:; response_count:1; response_revision:10875; }","duration":"1.012295555s","start":"2026-04-01T23:47:25.700263Z","end":"2026-04-01T23:47:26.712559Z","steps":["trace[325449057] 'agreement among raft nodes before linearized reading'  (duration: 1.010516819s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.712666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.700248Z","time spent":"1.012402037s","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-w6nkh\" "}
{"level":"warn","ts":"2026-04-01T23:47:26.710876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023893464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"warn","ts":"2026-04-01T23:47:26.710903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.716016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-04-01T23:47:26.710889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046688092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"}
{"level":"warn","ts":"2026-04-01T23:47:26.710936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045353537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-01T23:47:26.710331Z","caller":"traceutil/trace.go:171","msg":"trace[1893467931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10875; }","duration":"921.041097ms","start":"2026-04-01T23:47:25.789282Z","end":"2026-04-01T23:47:26.710323Z","steps":["trace[1893467931] 'agreement among raft nodes before linearized reading'  (duration: 920.989476ms)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:47:26.712797Z","caller":"traceutil/trace.go:171","msg":"trace[995958055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10875; }","duration":"1.025832824s","start":"2026-04-01T23:47:25.686958Z","end":"2026-04-01T23:47:26.71279Z","steps":["trace[995958055] 'agreement among raft nodes before linearized reading'  (duration: 1.023866593s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.712809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.789265Z","time spent":"923.533023ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-04-01T23:47:26.712825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.686947Z","time spent":"1.025867204s","remote":"127.0.0.1:43008","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":"info","ts":"2026-04-01T23:47:26.712766Z","caller":"traceutil/trace.go:171","msg":"trace[1952655916] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10875; }","duration":"1.047156945s","start":"2026-04-01T23:47:25.665575Z","end":"2026-04-01T23:47:26.712732Z","steps":["trace[1952655916] 'agreement among raft nodes before linearized reading'  (duration: 1.045308117s)"],"step_count":1}
{"level":"info","ts":"2026-04-01T23:47:26.712882Z","caller":"traceutil/trace.go:171","msg":"trace[613254769] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:10875; }","duration":"1.048722525s","start":"2026-04-01T23:47:25.664153Z","end":"2026-04-01T23:47:26.712875Z","steps":["trace[613254769] 'agreement among raft nodes before linearized reading'  (duration: 1.046116487s)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.712892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.66551Z","time spent":"1.0473748s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-04-01T23:47:26.712886Z","caller":"traceutil/trace.go:171","msg":"trace[805801291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10875; }","duration":"923.717348ms","start":"2026-04-01T23:47:25.789152Z","end":"2026-04-01T23:47:26.712869Z","steps":["trace[805801291] 'agreement among raft nodes before linearized reading'  (duration: 921.697805ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-01T23:47:26.712991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.789135Z","time spent":"923.81946ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-01T23:47:26.712909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:47:25.664147Z","time spent":"1.048756035s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"info","ts":"2026-04-01T23:51:19.120068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10531}
{"level":"info","ts":"2026-04-01T23:51:19.206897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10531,"took":"85.182429ms","hash":2097425750,"current-db-size-bytes":57864192,"current-db-size":"58 MB","current-db-size-in-use-bytes":19828736,"current-db-size-in-use":"20 MB"}
{"level":"info","ts":"2026-04-01T23:51:19.206965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2097425750,"revision":10531,"compact-revision":8106}