{"level":"warn","ts":"2026-05-28T12:37:46.589989Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T12:37:46.590137Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-28T12:37:46.590261Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T12:37:46.59028Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-28T12:37:46.590308Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T12:37:46.591153Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-05-28T12:37:46.591281Z","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://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-28T12:37:46.596031Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.429071ms"} {"level":"info","ts":"2026-05-28T12:37:46.603352Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-05-28T12:37:46.603475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T12:37:46.60351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-05-28T12:37:46.603565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T12:37:46.603586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-05-28T12:37:46.603633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-05-28T12:37:46.613452Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T12:37:46.619093Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T12:37:46.620687Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-28T12:37:46.624525Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T12:37:46.624866Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T12:37:46.624939Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T12:37:46.625076Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T12:37:46.625119Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T12:37:46.625992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-05-28T12:37:46.62619Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-28T12:37:46.628831Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T12:37:46.628961Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-28T12:37:46.629049Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-28T12:37:46.629244Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T12:37:46.629284Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T12:37:47.504049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T12:37:47.504208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T12:37:47.504251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-05-28T12:37:47.504276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-05-28T12:37:47.50429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-28T12:37:47.504318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-05-28T12:37:47.50434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-28T12:37:47.505808Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T12:37:47.505816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T12:37:47.505835Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T12:37:47.505883Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T12:37:47.506223Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T12:37:47.506303Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T12:37:47.507185Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T12:37:47.507433Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T12:37:47.507505Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T12:37:47.511698Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T12:37:47.511829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-05-28T12:37:49.198149Z","caller":"traceutil/trace.go:171","msg":"trace[726326886] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"134.944685ms","start":"2026-05-28T12:37:49.063167Z","end":"2026-05-28T12:37:49.198111Z","steps":["trace[726326886] 'process raft request' (duration: 134.788782ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:37:49.198153Z","caller":"traceutil/trace.go:171","msg":"trace[1312016154] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"136.963426ms","start":"2026-05-28T12:37:49.061133Z","end":"2026-05-28T12:37:49.198096Z","steps":["trace[1312016154] 'process raft request' (duration: 68.261533ms)","trace[1312016154] 'compare' (duration: 68.466648ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:37:57.124743Z","caller":"traceutil/trace.go:171","msg":"trace[973755660] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"111.991993ms","start":"2026-05-28T12:37:57.012696Z","end":"2026-05-28T12:37:57.124688Z","steps":["trace[973755660] 'process raft request' (duration: 78.7995ms)","trace[973755660] 'compare' (duration: 32.955998ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:38:02.663578Z","caller":"traceutil/trace.go:171","msg":"trace[2094285160] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"223.67734ms","start":"2026-05-28T12:38:02.439878Z","end":"2026-05-28T12:38:02.663555Z","steps":["trace[2094285160] 'process raft request' (duration: 223.495376ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:38:02.861527Z","caller":"traceutil/trace.go:171","msg":"trace[128074118] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:373; }","duration":"358.982602ms","start":"2026-05-28T12:38:02.502503Z","end":"2026-05-28T12:38:02.861486Z","steps":["trace[128074118] 'read index received' (duration: 160.998391ms)","trace[128074118] 'applied index is now lower than readState.Index' (duration: 197.982701ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:38:02.861613Z","caller":"traceutil/trace.go:171","msg":"trace[1373625688] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"378.32952ms","start":"2026-05-28T12:38:02.483241Z","end":"2026-05-28T12:38:02.86157Z","steps":["trace[1373625688] 'process raft request' (duration: 376.061153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:38:02.86183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.297158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T12:38:02.861938Z","caller":"traceutil/trace.go:171","msg":"trace[989943124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"359.466012ms","start":"2026-05-28T12:38:02.502454Z","end":"2026-05-28T12:38:02.86192Z","steps":["trace[989943124] 'agreement among raft nodes before linearized reading' (duration: 359.284158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:38:02.861874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.85178ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T12:38:02.861996Z","caller":"traceutil/trace.go:171","msg":"trace[1275385611] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:361; }","duration":"217.982892ms","start":"2026-05-28T12:38:02.643996Z","end":"2026-05-28T12:38:02.861979Z","steps":["trace[1275385611] 'agreement among raft nodes before linearized reading' (duration: 217.817219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:38:02.861986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:38:02.502431Z","time spent":"359.542613ms","remote":"127.0.0.1:47748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T12:38:02.862383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:38:02.483197Z","time spent":"378.521914ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T12:38:02.862445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.146637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T12:38:02.862504Z","caller":"traceutil/trace.go:171","msg":"trace[344732827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"133.24024ms","start":"2026-05-28T12:38:02.729249Z","end":"2026-05-28T12:38:02.862489Z","steps":["trace[344732827] 'agreement among raft nodes before linearized reading' (duration: 133.112797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:38:25.128458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.116738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T12:38:25.12889Z","caller":"traceutil/trace.go:171","msg":"trace[695788419] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"150.469259ms","start":"2026-05-28T12:38:24.978396Z","end":"2026-05-28T12:38:25.128865Z","steps":["trace[695788419] 'process raft request' (duration: 150.370737ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:38:25.128892Z","caller":"traceutil/trace.go:171","msg":"trace[337230308] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"152.12457ms","start":"2026-05-28T12:38:24.976745Z","end":"2026-05-28T12:38:25.128869Z","steps":["trace[337230308] 'process raft request' (duration: 19.289188ms)","trace[337230308] 'compare' (duration: 131.970655ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:38:25.301199Z","caller":"traceutil/trace.go:171","msg":"trace[812079348] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"278.204265ms","start":"2026-05-28T12:38:25.022964Z","end":"2026-05-28T12:38:25.301168Z","steps":["trace[812079348] 'process raft request' (duration: 208.30924ms)","trace[812079348] 'compare' (duration: 69.185402ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:38:25.301849Z","caller":"traceutil/trace.go:171","msg":"trace[1507531982] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"234.802691ms","start":"2026-05-28T12:38:25.067013Z","end":"2026-05-28T12:38:25.301816Z","steps":["trace[1507531982] 'process raft request' (duration: 234.551966ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:38:30.295313Z","caller":"traceutil/trace.go:171","msg":"trace[743356516] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"420.94299ms","start":"2026-05-28T12:38:29.87433Z","end":"2026-05-28T12:38:30.295273Z","steps":["trace[743356516] 'process raft request' (duration: 419.616714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:38:30.295526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:38:29.874295Z","time spent":"421.145554ms","remote":"127.0.0.1:48096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T12:38:30.295572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.922052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T12:38:30.295624Z","caller":"traceutil/trace.go:171","msg":"trace[1167413722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"321.978163ms","start":"2026-05-28T12:38:29.973631Z","end":"2026-05-28T12:38:30.295609Z","steps":["trace[1167413722] 'agreement among raft nodes before linearized reading' (duration: 321.737068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:38:30.295662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:38:29.973573Z","time spent":"322.079665ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T12:38:30.295293Z","caller":"traceutil/trace.go:171","msg":"trace[1590612544] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"321.619857ms","start":"2026-05-28T12:38:29.973647Z","end":"2026-05-28T12:38:30.295267Z","steps":["trace[1590612544] 'read index received' (duration: 320.281741ms)","trace[1590612544] 'applied index is now lower than readState.Index' (duration: 1.336756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T12:38:44.775544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.278604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tfmnr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-28T12:38:44.775654Z","caller":"traceutil/trace.go:171","msg":"trace[1293907857] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tfmnr; range_end:; response_count:1; response_revision:750; }","duration":"139.464218ms","start":"2026-05-28T12:38:44.63617Z","end":"2026-05-28T12:38:44.775634Z","steps":["trace[1293907857] 'range keys from in-memory index tree' (duration: 139.063641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:40:00.365088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.241545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-28T12:40:00.365192Z","caller":"traceutil/trace.go:171","msg":"trace[1562552868] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"211.386358ms","start":"2026-05-28T12:40:00.153778Z","end":"2026-05-28T12:40:00.365165Z","steps":["trace[1562552868] 'range keys from in-memory index tree' (duration: 210.999901ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:40:08.099958Z","caller":"traceutil/trace.go:171","msg":"trace[1216302666] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"158.463768ms","start":"2026-05-28T12:40:07.941446Z","end":"2026-05-28T12:40:08.09991Z","steps":["trace[1216302666] 'process raft request' (duration: 78.292336ms)","trace[1216302666] 'compare' (duration: 80.07308ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:40:45.094812Z","caller":"traceutil/trace.go:171","msg":"trace[873566483] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2197; }","duration":"126.3872ms","start":"2026-05-28T12:40:44.968399Z","end":"2026-05-28T12:40:45.094787Z","steps":["trace[873566483] 'read index received' (duration: 126.175116ms)","trace[873566483] 'applied index is now lower than readState.Index' (duration: 208.494µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:40:45.094849Z","caller":"traceutil/trace.go:171","msg":"trace[700993466] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"139.585117ms","start":"2026-05-28T12:40:44.955238Z","end":"2026-05-28T12:40:45.094823Z","steps":["trace[700993466] 'process raft request' (duration: 139.363153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:40:45.095044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.634325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T12:40:45.095083Z","caller":"traceutil/trace.go:171","msg":"trace[1824782124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2117; }","duration":"126.729617ms","start":"2026-05-28T12:40:44.968343Z","end":"2026-05-28T12:40:45.095073Z","steps":["trace[1824782124] 'agreement among raft nodes before linearized reading' (duration: 126.564734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:40:50.972005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.062162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T12:40:50.972112Z","caller":"traceutil/trace.go:171","msg":"trace[731576042] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"241.865037ms","start":"2026-05-28T12:40:50.73023Z","end":"2026-05-28T12:40:50.972095Z","steps":["trace[731576042] 'read index received' (duration: 105.588201ms)","trace[731576042] 'applied index is now lower than readState.Index' (duration: 136.275726ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:40:50.972349Z","caller":"traceutil/trace.go:171","msg":"trace[1890873808] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"271.608663ms","start":"2026-05-28T12:40:50.700702Z","end":"2026-05-28T12:40:50.972311Z","steps":["trace[1890873808] 'process raft request' (duration: 135.117563ms)","trace[1890873808] 'compare' (duration: 135.95158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T12:40:50.972493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.261495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-28T12:40:50.972545Z","caller":"traceutil/trace.go:171","msg":"trace[359194623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2149; }","duration":"242.351406ms","start":"2026-05-28T12:40:50.730181Z","end":"2026-05-28T12:40:50.972533Z","steps":["trace[359194623] 'agreement among raft nodes before linearized reading' (duration: 241.961989ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:40:51.255929Z","caller":"traceutil/trace.go:171","msg":"trace[2078755377] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2232; }","duration":"133.757638ms","start":"2026-05-28T12:40:51.122149Z","end":"2026-05-28T12:40:51.255907Z","steps":["trace[2078755377] 'read index received' (duration: 5.309129ms)","trace[2078755377] 'applied index is now lower than readState.Index' (duration: 128.447749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T12:40:51.256118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.956301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T12:40:51.256085Z","caller":"traceutil/trace.go:171","msg":"trace[1306683789] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"136.045171ms","start":"2026-05-28T12:40:51.119996Z","end":"2026-05-28T12:40:51.256042Z","steps":["trace[1306683789] 'process raft request' (duration: 135.789146ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:40:51.256152Z","caller":"traceutil/trace.go:171","msg":"trace[1676913824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2151; }","duration":"134.054453ms","start":"2026-05-28T12:40:51.122086Z","end":"2026-05-28T12:40:51.256141Z","steps":["trace[1676913824] 'agreement among raft nodes before linearized reading' (duration: 133.917611ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:41:37.768493Z","caller":"traceutil/trace.go:171","msg":"trace[1776587155] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"150.805697ms","start":"2026-05-28T12:41:37.617665Z","end":"2026-05-28T12:41:37.768471Z","steps":["trace[1776587155] 'process raft request' (duration: 150.43394ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:42:12.391652Z","caller":"traceutil/trace.go:171","msg":"trace[1497382006] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"135.354372ms","start":"2026-05-28T12:42:12.25627Z","end":"2026-05-28T12:42:12.391624Z","steps":["trace[1497382006] 'process raft request' (duration: 135.150068ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:42:12.404855Z","caller":"traceutil/trace.go:171","msg":"trace[406681891] linearizableReadLoop","detail":"{readStateIndex:2892; appliedIndex:2890; }","duration":"118.47266ms","start":"2026-05-28T12:42:12.286361Z","end":"2026-05-28T12:42:12.404834Z","steps":["trace[406681891] 'read index received' (duration: 105.080694ms)","trace[406681891] 'applied index is now lower than readState.Index' (duration: 13.391256ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:42:12.404908Z","caller":"traceutil/trace.go:171","msg":"trace[174367991] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"148.521374ms","start":"2026-05-28T12:42:12.256364Z","end":"2026-05-28T12:42:12.404885Z","steps":["trace[174367991] 'process raft request' (duration: 148.353741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:42:12.40496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.591222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T12:42:12.405004Z","caller":"traceutil/trace.go:171","msg":"trace[1707049366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2785; }","duration":"118.675834ms","start":"2026-05-28T12:42:12.286317Z","end":"2026-05-28T12:42:12.404993Z","steps":["trace[1707049366] 'agreement among raft nodes before linearized reading' (duration: 118.602573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:45:55.151286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.271374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-28T12:45:55.151358Z","caller":"traceutil/trace.go:171","msg":"trace[614215261] 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:4765; }","duration":"113.379156ms","start":"2026-05-28T12:45:55.037963Z","end":"2026-05-28T12:45:55.151342Z","steps":["trace[614215261] 'range keys from in-memory index tree' (duration: 113.05455ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:45:55.237207Z","caller":"traceutil/trace.go:171","msg":"trace[268036378] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"121.714431ms","start":"2026-05-28T12:45:55.115449Z","end":"2026-05-28T12:45:55.237163Z","steps":["trace[268036378] 'process raft request' (duration: 121.391745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:46:13.329239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.568912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T12:46:13.329581Z","caller":"traceutil/trace.go:171","msg":"trace[527890556] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"363.79802ms","start":"2026-05-28T12:46:12.965741Z","end":"2026-05-28T12:46:13.329539Z","steps":["trace[527890556] 'process raft request' (duration: 151.780786ms)","trace[527890556] 'compare' (duration: 211.396576ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:46:13.329618Z","caller":"traceutil/trace.go:171","msg":"trace[1061933639] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"363.780579ms","start":"2026-05-28T12:46:12.965795Z","end":"2026-05-28T12:46:13.329575Z","steps":["trace[1061933639] 'process raft request' (duration: 363.608054ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:46:13.32968Z","caller":"traceutil/trace.go:171","msg":"trace[150230502] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"363.523953ms","start":"2026-05-28T12:46:12.96608Z","end":"2026-05-28T12:46:13.329604Z","steps":["trace[150230502] 'process raft request' (duration: 363.43144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:46:13.329776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:46:12.966072Z","time spent":"363.663316ms","remote":"127.0.0.1:47888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":528,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T12:46:13.329781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:46:12.965718Z","time spent":"363.945674ms","remote":"127.0.0.1:48000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T12:46:13.329831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:46:12.965762Z","time spent":"363.951424ms","remote":"127.0.0.1:47888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T12:46:13.329826Z","caller":"traceutil/trace.go:171","msg":"trace[2056281497] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"362.70594ms","start":"2026-05-28T12:46:12.967096Z","end":"2026-05-28T12:46:13.329802Z","steps":["trace[2056281497] 'process raft request' (duration: 362.456064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:46:13.329953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:46:12.967081Z","time spent":"362.807964ms","remote":"127.0.0.1:48000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T12:46:20.771775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.598991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T12:46:20.771921Z","caller":"traceutil/trace.go:171","msg":"trace[1986458208] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:5360; }","duration":"147.764087ms","start":"2026-05-28T12:46:20.624123Z","end":"2026-05-28T12:46:20.771887Z","steps":["trace[1986458208] 'range keys from in-memory index tree' (duration: 147.362086ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:46:21.000263Z","caller":"traceutil/trace.go:171","msg":"trace[1883334071] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"159.693511ms","start":"2026-05-28T12:46:20.840537Z","end":"2026-05-28T12:46:21.00023Z","steps":["trace[1883334071] 'process raft request' (duration: 159.434475ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:46:21.177952Z","caller":"traceutil/trace.go:171","msg":"trace[2081458782] linearizableReadLoop","detail":"{readStateIndex:5563; appliedIndex:5562; }","duration":"140.550421ms","start":"2026-05-28T12:46:21.037376Z","end":"2026-05-28T12:46:21.177927Z","steps":["trace[2081458782] 'read index received' (duration: 62.941162ms)","trace[2081458782] 'applied index is now lower than readState.Index' (duration: 77.607999ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:46:21.178005Z","caller":"traceutil/trace.go:171","msg":"trace[588045811] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"168.732427ms","start":"2026-05-28T12:46:21.009238Z","end":"2026-05-28T12:46:21.17797Z","steps":["trace[588045811] 'process raft request' (duration: 91.145029ms)","trace[588045811] 'compare' (duration: 77.419904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T12:46:21.178182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.801957ms","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-05-28T12:46:21.178227Z","caller":"traceutil/trace.go:171","msg":"trace[561838526] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5364; }","duration":"140.913101ms","start":"2026-05-28T12:46:21.037302Z","end":"2026-05-28T12:46:21.178215Z","steps":["trace[561838526] 'agreement among raft nodes before linearized reading' (duration: 140.761127ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:47:47.537988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2976} {"level":"info","ts":"2026-05-28T12:47:47.620348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2976,"took":"80.596559ms","hash":3295845116,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":34172928,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-28T12:47:47.620415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3295845116,"revision":2976,"compact-revision":-1} {"level":"info","ts":"2026-05-28T12:52:43.938403Z","caller":"traceutil/trace.go:171","msg":"trace[1223143497] linearizableReadLoop","detail":"{readStateIndex:8544; appliedIndex:8543; }","duration":"243.826348ms","start":"2026-05-28T12:52:43.694542Z","end":"2026-05-28T12:52:43.938369Z","steps":["trace[1223143497] 'read index received' (duration: 243.551363ms)","trace[1223143497] 'applied index is now lower than readState.Index' (duration: 273.695µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:52:43.938565Z","caller":"traceutil/trace.go:171","msg":"trace[643017613] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"262.517861ms","start":"2026-05-28T12:52:43.676007Z","end":"2026-05-28T12:52:43.938525Z","steps":["trace[643017613] 'process raft request' (duration: 262.127883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:43.938778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.209495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-28T12:52:43.938881Z","caller":"traceutil/trace.go:171","msg":"trace[1763900262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8252; }","duration":"244.376018ms","start":"2026-05-28T12:52:43.69448Z","end":"2026-05-28T12:52:43.938856Z","steps":["trace[1763900262] 'agreement among raft nodes before linearized reading' (duration: 244.075802ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:52:44.132789Z","caller":"traceutil/trace.go:171","msg":"trace[774848976] linearizableReadLoop","detail":"{readStateIndex:8545; appliedIndex:8544; }","duration":"194.244352ms","start":"2026-05-28T12:52:43.938516Z","end":"2026-05-28T12:52:44.132761Z","steps":["trace[774848976] 'read index received' (duration: 102.646795ms)","trace[774848976] 'applied index is now lower than readState.Index' (duration: 91.596347ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:52:44.132814Z","caller":"traceutil/trace.go:171","msg":"trace[484146207] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"350.519829ms","start":"2026-05-28T12:52:43.782244Z","end":"2026-05-28T12:52:44.132764Z","steps":["trace[484146207] 'process raft request' (duration: 258.915142ms)","trace[484146207] 'compare' (duration: 91.371243ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:52:44.132978Z","caller":"traceutil/trace.go:171","msg":"trace[46973568] transaction","detail":"{read_only:false; response_revision:8254; number_of_response:1; }","duration":"188.640567ms","start":"2026-05-28T12:52:43.944317Z","end":"2026-05-28T12:52:44.132958Z","steps":["trace[46973568] 'process raft request' (duration: 188.392852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:44.133017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:43.782217Z","time spent":"350.687283ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T12:52:44.133085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.712404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-28T12:52:44.133098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.529627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6qwfs\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-28T12:52:44.133196Z","caller":"traceutil/trace.go:171","msg":"trace[1261275613] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6qwfs; range_end:; response_count:1; response_revision:8254; }","duration":"405.6863ms","start":"2026-05-28T12:52:43.727487Z","end":"2026-05-28T12:52:44.133173Z","steps":["trace[1261275613] 'agreement among raft nodes before linearized reading' (duration: 405.359633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:44.133266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:43.727468Z","time spent":"405.785752ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6468,"request content":"key:\"/registry/pods/openstack/keepalived-6qwfs\" "} {"level":"warn","ts":"2026-05-28T12:52:44.133299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.265512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-28T12:52:44.133338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.14044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T12:52:44.133367Z","caller":"traceutil/trace.go:171","msg":"trace[1115412982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8254; }","duration":"388.384574ms","start":"2026-05-28T12:52:43.744967Z","end":"2026-05-28T12:52:44.133351Z","steps":["trace[1115412982] 'agreement among raft nodes before linearized reading' (duration: 387.840673ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:52:44.13339Z","caller":"traceutil/trace.go:171","msg":"trace[1415894526] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8255; }","duration":"169.197111ms","start":"2026-05-28T12:52:43.964179Z","end":"2026-05-28T12:52:44.133376Z","steps":["trace[1415894526] 'agreement among raft nodes before linearized reading' (duration: 169.088699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:44.133424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:43.744935Z","time spent":"388.476685ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-28T12:52:44.133144Z","caller":"traceutil/trace.go:171","msg":"trace[2126484000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8254; }","duration":"412.841986ms","start":"2026-05-28T12:52:43.720286Z","end":"2026-05-28T12:52:44.133128Z","steps":["trace[2126484000] 'agreement among raft nodes before linearized reading' (duration: 412.648951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:44.133533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:43.720254Z","time spent":"413.244253ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T12:52:47.549525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6109} {"level":"info","ts":"2026-05-28T12:52:47.640428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6109,"took":"89.271994ms","hash":2926764109,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":23187456,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-28T12:52:47.640504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2926764109,"revision":6109,"compact-revision":2976} {"level":"info","ts":"2026-05-28T12:52:55.370155Z","caller":"traceutil/trace.go:171","msg":"trace[1797185755] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"244.506101ms","start":"2026-05-28T12:52:55.125618Z","end":"2026-05-28T12:52:55.370124Z","steps":["trace[1797185755] 'process raft request' (duration: 236.877766ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:52:56.53283Z","caller":"traceutil/trace.go:171","msg":"trace[1098520807] linearizableReadLoop","detail":"{readStateIndex:8613; appliedIndex:8610; }","duration":"343.13245ms","start":"2026-05-28T12:52:56.189668Z","end":"2026-05-28T12:52:56.532801Z","steps":["trace[1098520807] 'read index received' (duration: 229.267753ms)","trace[1098520807] 'applied index is now lower than readState.Index' (duration: 113.862017ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:52:56.532977Z","caller":"traceutil/trace.go:171","msg":"trace[147905448] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"347.984551ms","start":"2026-05-28T12:52:56.184919Z","end":"2026-05-28T12:52:56.532904Z","steps":["trace[147905448] 'process raft request' (duration: 347.817738ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:52:56.532988Z","caller":"traceutil/trace.go:171","msg":"trace[1200600882] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"349.501161ms","start":"2026-05-28T12:52:56.183453Z","end":"2026-05-28T12:52:56.532954Z","steps":["trace[1200600882] 'process raft request' (duration: 349.057813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:56.533126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.454657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-28T12:52:56.533156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.011416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-28T12:52:56.533179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:56.183405Z","time spent":"349.668544ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T12:52:56.533197Z","caller":"traceutil/trace.go:171","msg":"trace[836776399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8317; }","duration":"343.698631ms","start":"2026-05-28T12:52:56.189481Z","end":"2026-05-28T12:52:56.53318Z","steps":["trace[836776399] 'agreement among raft nodes before linearized reading' (duration: 343.506698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:56.533196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:56.184904Z","time spent":"348.161975ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T12:52:56.533212Z","caller":"traceutil/trace.go:171","msg":"trace[480755871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8317; }","duration":"328.115357ms","start":"2026-05-28T12:52:56.205081Z","end":"2026-05-28T12:52:56.533197Z","steps":["trace[480755871] 'agreement among raft nodes before linearized reading' (duration: 327.957494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:56.533244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:56.189467Z","time spent":"343.764812ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T12:52:56.533254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:56.205059Z","time spent":"328.184409ms","remote":"127.0.0.1:47994","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-05-28T12:52:56.533376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.143755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6qwfs\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-28T12:52:56.5335Z","caller":"traceutil/trace.go:171","msg":"trace[276883692] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6qwfs; range_end:; response_count:1; response_revision:8317; }","duration":"313.323729ms","start":"2026-05-28T12:52:56.220154Z","end":"2026-05-28T12:52:56.533478Z","steps":["trace[276883692] 'agreement among raft nodes before linearized reading' (duration: 312.918122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:56.533553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:56.220139Z","time spent":"313.40634ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6468,"request content":"key:\"/registry/pods/openstack/keepalived-6qwfs\" "} {"level":"warn","ts":"2026-05-28T12:52:56.533425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.780993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T12:52:56.533605Z","caller":"traceutil/trace.go:171","msg":"trace[521554954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8317; }","duration":"325.999177ms","start":"2026-05-28T12:52:56.207588Z","end":"2026-05-28T12:52:56.533587Z","steps":["trace[521554954] 'agreement among raft nodes before linearized reading' (duration: 325.566629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:52:56.533662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T12:52:56.207553Z","time spent":"326.090099ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T12:52:57.266291Z","caller":"traceutil/trace.go:171","msg":"trace[452604777] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"139.413749ms","start":"2026-05-28T12:52:57.126851Z","end":"2026-05-28T12:52:57.266264Z","steps":["trace[452604777] 'process raft request' (duration: 139.149894ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:52:57.730516Z","caller":"traceutil/trace.go:171","msg":"trace[1108239536] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"233.381141ms","start":"2026-05-28T12:52:57.497107Z","end":"2026-05-28T12:52:57.730488Z","steps":["trace[1108239536] 'process raft request' (duration: 202.309615ms)","trace[1108239536] 'compare' (duration: 30.664188ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:53:26.372775Z","caller":"traceutil/trace.go:171","msg":"trace[409282013] linearizableReadLoop","detail":"{readStateIndex:8837; appliedIndex:8836; }","duration":"197.860242ms","start":"2026-05-28T12:53:26.174885Z","end":"2026-05-28T12:53:26.372745Z","steps":["trace[409282013] 'read index received' (duration: 197.510726ms)","trace[409282013] 'applied index is now lower than readState.Index' (duration: 348.436µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:53:26.373284Z","caller":"traceutil/trace.go:171","msg":"trace[1836057345] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"203.662621ms","start":"2026-05-28T12:53:26.169601Z","end":"2026-05-28T12:53:26.373264Z","steps":["trace[1836057345] 'process raft request' (duration: 202.893916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T12:53:26.37379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.86738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T12:53:26.373889Z","caller":"traceutil/trace.go:171","msg":"trace[1818252481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8532; }","duration":"199.034813ms","start":"2026-05-28T12:53:26.174801Z","end":"2026-05-28T12:53:26.373835Z","steps":["trace[1818252481] 'agreement among raft nodes before linearized reading' (duration: 198.897671ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:53:28.902376Z","caller":"traceutil/trace.go:171","msg":"trace[2104289407] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8851; }","duration":"133.721252ms","start":"2026-05-28T12:53:28.768636Z","end":"2026-05-28T12:53:28.902358Z","steps":["trace[2104289407] 'read index received' (duration: 6.076454ms)","trace[2104289407] 'applied index is now lower than readState.Index' (duration: 127.644108ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T12:53:28.90256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.930055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6qwfs\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-28T12:53:28.902583Z","caller":"traceutil/trace.go:171","msg":"trace[71599845] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6qwfs; range_end:; response_count:1; response_revision:8549; }","duration":"134.028538ms","start":"2026-05-28T12:53:28.768546Z","end":"2026-05-28T12:53:28.902575Z","steps":["trace[71599845] 'agreement among raft nodes before linearized reading' (duration: 133.883925ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:53:28.902726Z","caller":"traceutil/trace.go:171","msg":"trace[1483355827] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"148.063582ms","start":"2026-05-28T12:53:28.754652Z","end":"2026-05-28T12:53:28.902715Z","steps":["trace[1483355827] 'process raft request' (duration: 131.075442ms)","trace[1483355827] 'compare' (duration: 16.248217ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T12:53:28.902927Z","caller":"traceutil/trace.go:171","msg":"trace[1977614863] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"148.176714ms","start":"2026-05-28T12:53:28.75474Z","end":"2026-05-28T12:53:28.902916Z","steps":["trace[1977614863] 'process raft request' (duration: 147.545382ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:53:29.504951Z","caller":"traceutil/trace.go:171","msg":"trace[357293058] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"110.882151ms","start":"2026-05-28T12:53:29.394021Z","end":"2026-05-28T12:53:29.504903Z","steps":["trace[357293058] 'process raft request' (duration: 110.662177ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:54:04.987163Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241f.wal"} {"level":"warn","ts":"2026-05-28T12:55:31.943342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.811186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6qwfs\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-28T12:55:31.943472Z","caller":"traceutil/trace.go:171","msg":"trace[18401315] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6qwfs; range_end:; response_count:1; response_revision:9641; }","duration":"187.035841ms","start":"2026-05-28T12:55:31.756408Z","end":"2026-05-28T12:55:31.943444Z","steps":["trace[18401315] 'range keys from in-memory index tree' (duration: 186.46908ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:55:35.363725Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T12:55:35.374675Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-28T12:55:35.374807Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-28T12:55:51.093044Z","caller":"traceutil/trace.go:171","msg":"trace[459424824] transaction","detail":"{read_only:false; response_revision:9774; number_of_response:1; }","duration":"159.154607ms","start":"2026-05-28T12:55:50.933844Z","end":"2026-05-28T12:55:51.092999Z","steps":["trace[459424824] 'process raft request' (duration: 158.632987ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T12:57:47.574024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8272} {"level":"info","ts":"2026-05-28T12:57:47.747944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8272,"took":"170.34893ms","hash":1265091326,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":24297472,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-28T12:57:47.748026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1265091326,"revision":8272,"compact-revision":6109} {"level":"info","ts":"2026-05-28T13:01:19.223674Z","caller":"traceutil/trace.go:171","msg":"trace[663700245] transaction","detail":"{read_only:false; response_revision:12058; number_of_response:1; }","duration":"148.189232ms","start":"2026-05-28T13:01:19.075451Z","end":"2026-05-28T13:01:19.22364Z","steps":["trace[663700245] 'process raft request' (duration: 145.357778ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:01:19.224007Z","caller":"traceutil/trace.go:171","msg":"trace[1486583066] transaction","detail":"{read_only:false; response_revision:12059; number_of_response:1; }","duration":"147.177903ms","start":"2026-05-28T13:01:19.076803Z","end":"2026-05-28T13:01:19.223981Z","steps":["trace[1486583066] 'process raft request' (duration: 146.767456ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:01:19.223897Z","caller":"traceutil/trace.go:171","msg":"trace[1939439242] linearizableReadLoop","detail":"{readStateIndex:12478; appliedIndex:12475; }","duration":"135.811837ms","start":"2026-05-28T13:01:19.088051Z","end":"2026-05-28T13:01:19.223863Z","steps":["trace[1939439242] 'read index received' (duration: 1.01784ms)","trace[1939439242] 'applied index is now lower than readState.Index' (duration: 134.791127ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:01:19.22463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.51398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-28T13:01:19.224719Z","caller":"traceutil/trace.go:171","msg":"trace[1981222130] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12059; }","duration":"136.696894ms","start":"2026-05-28T13:01:19.088005Z","end":"2026-05-28T13:01:19.224702Z","steps":["trace[1981222130] 'agreement among raft nodes before linearized reading' (duration: 136.103773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:01:19.583306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.197645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T13:01:19.583434Z","caller":"traceutil/trace.go:171","msg":"trace[1213650603] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:12059; }","duration":"204.377498ms","start":"2026-05-28T13:01:19.379023Z","end":"2026-05-28T13:01:19.583401Z","steps":["trace[1213650603] 'range keys from in-memory index tree' (duration: 204.054043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:01:19.583425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.839851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-28T13:01:19.584015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.869852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-28T13:01:19.584123Z","caller":"traceutil/trace.go:171","msg":"trace[899173018] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:12059; }","duration":"238.029516ms","start":"2026-05-28T13:01:19.346066Z","end":"2026-05-28T13:01:19.584095Z","steps":["trace[899173018] 'range keys from in-memory index tree' (duration: 237.605958ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:01:19.583573Z","caller":"traceutil/trace.go:171","msg":"trace[1502226746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12059; }","duration":"259.042755ms","start":"2026-05-28T13:01:19.324497Z","end":"2026-05-28T13:01:19.58354Z","steps":["trace[1502226746] 'range keys from in-memory index tree' (duration: 258.599677ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:01:19.763383Z","caller":"traceutil/trace.go:171","msg":"trace[1569925272] transaction","detail":"{read_only:false; response_revision:12061; number_of_response:1; }","duration":"173.246887ms","start":"2026-05-28T13:01:19.590107Z","end":"2026-05-28T13:01:19.763354Z","steps":["trace[1569925272] 'process raft request' (duration: 129.771262ms)","trace[1569925272] 'compare' (duration: 43.22244ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:01:19.763575Z","caller":"traceutil/trace.go:171","msg":"trace[201979865] linearizableReadLoop","detail":"{readStateIndex:12481; appliedIndex:12478; }","duration":"170.087968ms","start":"2026-05-28T13:01:19.593448Z","end":"2026-05-28T13:01:19.763536Z","steps":["trace[201979865] 'read index received' (duration: 18.46329ms)","trace[201979865] 'applied index is now lower than readState.Index' (duration: 151.622068ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:01:19.763613Z","caller":"traceutil/trace.go:171","msg":"trace[1799450305] transaction","detail":"{read_only:false; response_revision:12062; number_of_response:1; }","duration":"172.997283ms","start":"2026-05-28T13:01:19.590598Z","end":"2026-05-28T13:01:19.763595Z","steps":["trace[1799450305] 'process raft request' (duration: 172.674946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:01:19.763771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.319882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-28T13:01:19.763803Z","caller":"traceutil/trace.go:171","msg":"trace[1660427326] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12062; }","duration":"170.407524ms","start":"2026-05-28T13:01:19.593386Z","end":"2026-05-28T13:01:19.763794Z","steps":["trace[1660427326] 'agreement among raft nodes before linearized reading' (duration: 170.262961ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:01:19.784593Z","caller":"traceutil/trace.go:171","msg":"trace[378775311] transaction","detail":"{read_only:false; response_revision:12063; number_of_response:1; }","duration":"111.69368ms","start":"2026-05-28T13:01:19.672867Z","end":"2026-05-28T13:01:19.784561Z","steps":["trace[378775311] 'process raft request' (duration: 111.443105ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:02:47.58015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10494} {"level":"info","ts":"2026-05-28T13:02:47.666671Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10494,"took":"82.986408ms","hash":2314141453,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":25288704,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-28T13:02:47.666778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2314141453,"revision":10494,"compact-revision":8272} {"level":"info","ts":"2026-05-28T13:05:43.606973Z","caller":"traceutil/trace.go:171","msg":"trace[1158564557] transaction","detail":"{read_only:false; response_revision:13939; number_of_response:1; }","duration":"181.031724ms","start":"2026-05-28T13:05:43.425894Z","end":"2026-05-28T13:05:43.606926Z","steps":["trace[1158564557] 'process raft request' (duration: 87.730847ms)","trace[1158564557] 'compare' (duration: 93.110473ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:07:09.702194Z","caller":"traceutil/trace.go:171","msg":"trace[2052831214] transaction","detail":"{read_only:false; response_revision:14488; number_of_response:1; }","duration":"127.255788ms","start":"2026-05-28T13:07:09.574899Z","end":"2026-05-28T13:07:09.702155Z","steps":["trace[2052831214] 'process raft request' (duration: 126.974472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:07:26.941392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.936191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T13:07:26.941714Z","caller":"traceutil/trace.go:171","msg":"trace[114918644] transaction","detail":"{read_only:false; response_revision:14590; number_of_response:1; }","duration":"380.776661ms","start":"2026-05-28T13:07:26.560915Z","end":"2026-05-28T13:07:26.941692Z","steps":["trace[114918644] 'process raft request' (duration: 125.441752ms)","trace[114918644] 'compare' (duration: 254.84357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:07:26.941796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:07:26.560883Z","time spent":"380.880883ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:07:26.941931Z","caller":"traceutil/trace.go:171","msg":"trace[1232801404] transaction","detail":"{read_only:false; response_revision:14591; number_of_response:1; }","duration":"358.743862ms","start":"2026-05-28T13:07:26.582997Z","end":"2026-05-28T13:07:26.94174Z","steps":["trace[1232801404] 'process raft request' (duration: 358.6511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:07:26.942201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:07:26.582963Z","time spent":"359.14478ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:07:26.976562Z","caller":"traceutil/trace.go:171","msg":"trace[1410059885] transaction","detail":"{read_only:false; response_revision:14592; number_of_response:1; }","duration":"253.592154ms","start":"2026-05-28T13:07:26.722939Z","end":"2026-05-28T13:07:26.976531Z","steps":["trace[1410059885] 'process raft request' (duration: 253.407561ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:07:26.976819Z","caller":"traceutil/trace.go:171","msg":"trace[1962733956] transaction","detail":"{read_only:false; response_revision:14593; number_of_response:1; }","duration":"253.560294ms","start":"2026-05-28T13:07:26.723225Z","end":"2026-05-28T13:07:26.976785Z","steps":["trace[1962733956] 'process raft request' (duration: 253.265159ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:07:36.862339Z","caller":"traceutil/trace.go:171","msg":"trace[740734651] transaction","detail":"{read_only:false; response_revision:14762; number_of_response:1; }","duration":"107.676845ms","start":"2026-05-28T13:07:36.754642Z","end":"2026-05-28T13:07:36.862319Z","steps":["trace[740734651] 'process raft request' (duration: 107.576733ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:07:37.106226Z","caller":"traceutil/trace.go:171","msg":"trace[906294650] transaction","detail":"{read_only:false; response_revision:14763; number_of_response:1; }","duration":"132.611301ms","start":"2026-05-28T13:07:36.973576Z","end":"2026-05-28T13:07:37.106187Z","steps":["trace[906294650] 'process raft request' (duration: 107.775957ms)","trace[906294650] 'compare' (duration: 24.548808ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:07:37.145485Z","caller":"traceutil/trace.go:171","msg":"trace[1050725944] linearizableReadLoop","detail":"{readStateIndex:15275; appliedIndex:15272; }","duration":"125.636835ms","start":"2026-05-28T13:07:37.019828Z","end":"2026-05-28T13:07:37.145464Z","steps":["trace[1050725944] 'read index received' (duration: 61.508987ms)","trace[1050725944] 'applied index is now lower than readState.Index' (duration: 64.127078ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:07:37.145523Z","caller":"traceutil/trace.go:171","msg":"trace[1702436735] transaction","detail":"{read_only:false; response_revision:14764; number_of_response:1; }","duration":"135.241511ms","start":"2026-05-28T13:07:37.010253Z","end":"2026-05-28T13:07:37.145494Z","steps":["trace[1702436735] 'process raft request' (duration: 135.073008ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:07:37.145569Z","caller":"traceutil/trace.go:171","msg":"trace[115646903] transaction","detail":"{read_only:false; response_revision:14765; number_of_response:1; }","duration":"132.337355ms","start":"2026-05-28T13:07:37.013196Z","end":"2026-05-28T13:07:37.145534Z","steps":["trace[115646903] 'process raft request' (duration: 132.239353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:07:37.145647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.800418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T13:07:37.145683Z","caller":"traceutil/trace.go:171","msg":"trace[1455381811] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:14765; }","duration":"125.90025ms","start":"2026-05-28T13:07:37.019771Z","end":"2026-05-28T13:07:37.145671Z","steps":["trace[1455381811] 'agreement among raft nodes before linearized reading' (duration: 125.803998ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:07:37.271731Z","caller":"traceutil/trace.go:171","msg":"trace[209916782] transaction","detail":"{read_only:false; response_revision:14766; number_of_response:1; }","duration":"123.044615ms","start":"2026-05-28T13:07:37.148647Z","end":"2026-05-28T13:07:37.271692Z","steps":["trace[209916782] 'process raft request' (duration: 111.50764ms)","trace[209916782] 'compare' (duration: 11.346731ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:07:47.586217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12626} {"level":"info","ts":"2026-05-28T13:07:47.663239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12626,"took":"74.380388ms","hash":3925752780,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":29270016,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-28T13:07:47.663325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3925752780,"revision":12626,"compact-revision":10494} {"level":"info","ts":"2026-05-28T13:09:17.873212Z","caller":"traceutil/trace.go:171","msg":"trace[337759275] transaction","detail":"{read_only:false; response_revision:15856; number_of_response:1; }","duration":"116.842932ms","start":"2026-05-28T13:09:17.75634Z","end":"2026-05-28T13:09:17.873183Z","steps":["trace[337759275] 'process raft request' (duration: 116.142359ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:12:47.593076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14960} {"level":"info","ts":"2026-05-28T13:12:47.693664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14960,"took":"95.756233ms","hash":434701676,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T13:12:47.69407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":434701676,"revision":14960,"compact-revision":12626} {"level":"warn","ts":"2026-05-28T13:13:22.56664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.591395ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:13:22.566823Z","caller":"traceutil/trace.go:171","msg":"trace[1593970584] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:17818; }","duration":"169.810728ms","start":"2026-05-28T13:13:22.396988Z","end":"2026-05-28T13:13:22.566799Z","steps":["trace[1593970584] 'range keys from in-memory index tree' (duration: 169.574635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:13:22.567286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.797408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T13:13:22.567324Z","caller":"traceutil/trace.go:171","msg":"trace[859017241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17818; }","duration":"162.877249ms","start":"2026-05-28T13:13:22.404437Z","end":"2026-05-28T13:13:22.567314Z","steps":["trace[859017241] 'range keys from in-memory index tree' (duration: 162.651987ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:53.11403Z","caller":"traceutil/trace.go:171","msg":"trace[658915349] transaction","detail":"{read_only:false; response_revision:18645; number_of_response:1; }","duration":"127.969626ms","start":"2026-05-28T13:14:52.98604Z","end":"2026-05-28T13:14:53.11401Z","steps":["trace[658915349] 'process raft request' (duration: 126.08626ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:53.114129Z","caller":"traceutil/trace.go:171","msg":"trace[1013624223] transaction","detail":"{read_only:false; response_revision:18646; number_of_response:1; }","duration":"123.669274ms","start":"2026-05-28T13:14:52.990414Z","end":"2026-05-28T13:14:53.114084Z","steps":["trace[1013624223] 'process raft request' (duration: 123.503821ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:56.738909Z","caller":"traceutil/trace.go:171","msg":"trace[771093394] transaction","detail":"{read_only:false; response_revision:18669; number_of_response:1; }","duration":"194.17006ms","start":"2026-05-28T13:14:56.54471Z","end":"2026-05-28T13:14:56.73888Z","steps":["trace[771093394] 'process raft request' (duration: 193.983286ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:56.739194Z","caller":"traceutil/trace.go:171","msg":"trace[477621302] linearizableReadLoop","detail":"{readStateIndex:19300; appliedIndex:19300; }","duration":"192.659161ms","start":"2026-05-28T13:14:56.546504Z","end":"2026-05-28T13:14:56.739163Z","steps":["trace[477621302] 'read index received' (duration: 192.645261ms)","trace[477621302] 'applied index is now lower than readState.Index' (duration: 10.57µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:14:56.739519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.534543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T13:14:56.739561Z","caller":"traceutil/trace.go:171","msg":"trace[2083280099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18669; }","duration":"188.627425ms","start":"2026-05-28T13:14:56.550921Z","end":"2026-05-28T13:14:56.739549Z","steps":["trace[2083280099] 'agreement among raft nodes before linearized reading' (duration: 188.392491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:56.739807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.595056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T13:14:56.739842Z","caller":"traceutil/trace.go:171","msg":"trace[676278384] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:18669; }","duration":"104.634416ms","start":"2026-05-28T13:14:56.635198Z","end":"2026-05-28T13:14:56.739833Z","steps":["trace[676278384] 'agreement among raft nodes before linearized reading' (duration: 104.573445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:56.739878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.383984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-28T13:14:56.739902Z","caller":"traceutil/trace.go:171","msg":"trace[40814290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18669; }","duration":"193.408674ms","start":"2026-05-28T13:14:56.546485Z","end":"2026-05-28T13:14:56.739894Z","steps":["trace[40814290] 'agreement among raft nodes before linearized reading' (duration: 193.339803ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:58.94259Z","caller":"traceutil/trace.go:171","msg":"trace[369058061] transaction","detail":"{read_only:false; response_revision:18687; number_of_response:1; }","duration":"100.425197ms","start":"2026-05-28T13:14:58.84211Z","end":"2026-05-28T13:14:58.942535Z","steps":["trace[369058061] 'process raft request' (duration: 100.138932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.41021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.809473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:14:59.410336Z","caller":"traceutil/trace.go:171","msg":"trace[1430849857] transaction","detail":"{read_only:false; response_revision:18688; number_of_response:1; }","duration":"464.701914ms","start":"2026-05-28T13:14:58.94561Z","end":"2026-05-28T13:14:59.410312Z","steps":["trace[1430849857] 'process raft request' (duration: 361.687347ms)","trace[1430849857] 'compare' (duration: 102.301223ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:14:59.410414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:58.945587Z","time spent":"464.786435ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20888,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:14:59.449293Z","caller":"traceutil/trace.go:171","msg":"trace[1314351302] linearizableReadLoop","detail":"{readStateIndex:19320; appliedIndex:19318; }","duration":"477.552245ms","start":"2026-05-28T13:14:58.971716Z","end":"2026-05-28T13:14:59.449268Z","steps":["trace[1314351302] 'read index received' (duration: 335.576906ms)","trace[1314351302] 'applied index is now lower than readState.Index' (duration: 141.974559ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:14:59.44939Z","caller":"traceutil/trace.go:171","msg":"trace[1875284269] transaction","detail":"{read_only:false; response_revision:18691; number_of_response:1; }","duration":"308.000889ms","start":"2026-05-28T13:14:59.141383Z","end":"2026-05-28T13:14:59.449384Z","steps":["trace[1875284269] 'process raft request' (duration: 307.824116ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:59.449502Z","caller":"traceutil/trace.go:171","msg":"trace[1083800550] transaction","detail":"{read_only:false; response_revision:18689; number_of_response:1; }","duration":"501.308141ms","start":"2026-05-28T13:14:58.948187Z","end":"2026-05-28T13:14:59.449495Z","steps":["trace[1083800550] 'process raft request' (duration: 500.875043ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:59.449582Z","caller":"traceutil/trace.go:171","msg":"trace[1882659137] transaction","detail":"{read_only:false; response_revision:18692; number_of_response:1; }","duration":"306.891807ms","start":"2026-05-28T13:14:59.142657Z","end":"2026-05-28T13:14:59.449549Z","steps":["trace[1882659137] 'process raft request' (duration: 306.570801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.449624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.141355Z","time spent":"308.154401ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:14:59.449626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:58.948146Z","time spent":"501.401763ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:14:59.44963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.920771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:14:59.449663Z","caller":"traceutil/trace.go:171","msg":"trace[1957845677] transaction","detail":"{read_only:false; response_revision:18693; number_of_response:1; }","duration":"210.930064ms","start":"2026-05-28T13:14:59.238675Z","end":"2026-05-28T13:14:59.449605Z","steps":["trace[1957845677] 'process raft request' (duration: 210.568927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.449737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.142612Z","time spent":"307.03819ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:14:59.449762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.738095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:14:59.449722Z","caller":"traceutil/trace.go:171","msg":"trace[1956993223] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18693; }","duration":"478.173036ms","start":"2026-05-28T13:14:58.971532Z","end":"2026-05-28T13:14:59.449706Z","steps":["trace[1956993223] 'agreement among raft nodes before linearized reading' (duration: 477.909931ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:59.449797Z","caller":"traceutil/trace.go:171","msg":"trace[1331067691] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18693; }","duration":"306.778735ms","start":"2026-05-28T13:14:59.143005Z","end":"2026-05-28T13:14:59.449784Z","steps":["trace[1331067691] 'agreement among raft nodes before linearized reading' (duration: 306.739685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.449826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.142999Z","time spent":"306.821846ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-05-28T13:14:59.449811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.296966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T13:14:59.449713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.988197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:14:59.449901Z","caller":"traceutil/trace.go:171","msg":"trace[1223794029] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18693; }","duration":"306.425949ms","start":"2026-05-28T13:14:59.143456Z","end":"2026-05-28T13:14:59.449882Z","steps":["trace[1223794029] 'agreement among raft nodes before linearized reading' (duration: 306.227196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.449923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.2991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:14:59.449923Z","caller":"traceutil/trace.go:171","msg":"trace[128225051] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18693; }","duration":"475.220611ms","start":"2026-05-28T13:14:58.974686Z","end":"2026-05-28T13:14:59.449907Z","steps":["trace[128225051] 'agreement among raft nodes before linearized reading' (duration: 474.947746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.449877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.050223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:14:59.449959Z","caller":"traceutil/trace.go:171","msg":"trace[1481693039] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18693; }","duration":"469.34101ms","start":"2026-05-28T13:14:58.980606Z","end":"2026-05-28T13:14:59.449947Z","steps":["trace[1481693039] 'agreement among raft nodes before linearized reading' (duration: 469.2988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.449958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.143445Z","time spent":"306.50428ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-05-28T13:14:59.449978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.989969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T13:14:59.449995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:58.980596Z","time spent":"469.393941ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"info","ts":"2026-05-28T13:14:59.449994Z","caller":"traceutil/trace.go:171","msg":"trace[1117374005] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18693; }","duration":"305.212226ms","start":"2026-05-28T13:14:59.14476Z","end":"2026-05-28T13:14:59.449973Z","steps":["trace[1117374005] 'agreement among raft nodes before linearized reading' (duration: 304.968981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.449992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:58.974677Z","time spent":"475.278402ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-05-28T13:14:59.45003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.985072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:4957"} {"level":"info","ts":"2026-05-28T13:14:59.450034Z","caller":"traceutil/trace.go:171","msg":"trace[1340685933] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18693; }","duration":"307.060441ms","start":"2026-05-28T13:14:59.14295Z","end":"2026-05-28T13:14:59.450011Z","steps":["trace[1340685933] 'agreement among raft nodes before linearized reading' (duration: 307.00643ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:59.449434Z","caller":"traceutil/trace.go:171","msg":"trace[164536368] transaction","detail":"{read_only:false; response_revision:18690; number_of_response:1; }","duration":"422.329417ms","start":"2026-05-28T13:14:59.027065Z","end":"2026-05-28T13:14:59.449394Z","steps":["trace[164536368] 'process raft request' (duration: 422.113043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.45002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.420391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-28T13:14:59.450119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.180285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T13:14:59.450123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.7668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:14:59.450152Z","caller":"traceutil/trace.go:171","msg":"trace[1077826599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18693; }","duration":"164.254027ms","start":"2026-05-28T13:14:59.28589Z","end":"2026-05-28T13:14:59.450144Z","steps":["trace[1077826599] 'agreement among raft nodes before linearized reading' (duration: 164.207156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.450042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.144751Z","time spent":"305.283178ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"info","ts":"2026-05-28T13:14:59.45015Z","caller":"traceutil/trace.go:171","msg":"trace[355692084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18693; }","duration":"279.595583ms","start":"2026-05-28T13:14:59.17052Z","end":"2026-05-28T13:14:59.450116Z","steps":["trace[355692084] 'agreement among raft nodes before linearized reading' (duration: 279.181066ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:59.450059Z","caller":"traceutil/trace.go:171","msg":"trace[358013334] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18693; }","duration":"471.067673ms","start":"2026-05-28T13:14:58.978984Z","end":"2026-05-28T13:14:59.450051Z","steps":["trace[358013334] 'agreement among raft nodes before linearized reading' (duration: 470.953631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.450208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:58.978952Z","time spent":"471.247186ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":4980,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-05-28T13:14:59.449831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:58.971503Z","time spent":"478.303379ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"info","ts":"2026-05-28T13:14:59.450157Z","caller":"traceutil/trace.go:171","msg":"trace[510912952] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18693; }","duration":"468.80771ms","start":"2026-05-28T13:14:58.981342Z","end":"2026-05-28T13:14:59.450149Z","steps":["trace[510912952] 'agreement among raft nodes before linearized reading' (duration: 468.762569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.450355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.027042Z","time spent":"423.053181ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:14:59.449928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.407538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T13:14:59.450075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.142939Z","time spent":"307.128762ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"info","ts":"2026-05-28T13:14:59.450423Z","caller":"traceutil/trace.go:171","msg":"trace[827291704] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18693; }","duration":"306.901369ms","start":"2026-05-28T13:14:59.143501Z","end":"2026-05-28T13:14:59.450403Z","steps":["trace[827291704] 'agreement among raft nodes before linearized reading' (duration: 306.387748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.450462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.143496Z","time spent":"306.95522ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-05-28T13:14:59.449877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.124795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:4957"} {"level":"warn","ts":"2026-05-28T13:14:59.449891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.210278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:14:59.450527Z","caller":"traceutil/trace.go:171","msg":"trace[1060035413] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18693; }","duration":"469.881171ms","start":"2026-05-28T13:14:58.980635Z","end":"2026-05-28T13:14:59.450516Z","steps":["trace[1060035413] 'agreement among raft nodes before linearized reading' (duration: 469.174177ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:14:59.450544Z","caller":"traceutil/trace.go:171","msg":"trace[1685303440] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18693; }","duration":"304.765308ms","start":"2026-05-28T13:14:59.145727Z","end":"2026-05-28T13:14:59.450493Z","steps":["trace[1685303440] 'agreement among raft nodes before linearized reading' (duration: 304.025574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:14:59.450564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:58.980632Z","time spent":"469.923052ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-05-28T13:14:59.450606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:59.145713Z","time spent":"304.88368ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":4980,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-05-28T13:14:59.450357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:14:58.981337Z","time spent":"468.985994ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"info","ts":"2026-05-28T13:16:17.312431Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T13:16:17.317403Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-28T13:16:17.317477Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-28T13:17:47.60005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17589} {"level":"info","ts":"2026-05-28T13:17:47.698747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17589,"took":"94.686099ms","hash":2557463139,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":33943552,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-28T13:17:47.698808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2557463139,"revision":17589,"compact-revision":14960} {"level":"info","ts":"2026-05-28T13:19:50.335191Z","caller":"traceutil/trace.go:171","msg":"trace[610649390] transaction","detail":"{read_only:false; response_revision:20792; number_of_response:1; }","duration":"106.97061ms","start":"2026-05-28T13:19:50.228194Z","end":"2026-05-28T13:19:50.335164Z","steps":["trace[610649390] 'process raft request' (duration: 106.763746ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:19:50.387319Z","caller":"traceutil/trace.go:171","msg":"trace[930613008] transaction","detail":"{read_only:false; response_revision:20793; number_of_response:1; }","duration":"103.779113ms","start":"2026-05-28T13:19:50.28351Z","end":"2026-05-28T13:19:50.387289Z","steps":["trace[930613008] 'process raft request' (duration: 103.60854ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:19:50.595276Z","caller":"traceutil/trace.go:171","msg":"trace[978319560] transaction","detail":"{read_only:false; response_revision:20794; number_of_response:1; }","duration":"256.19579ms","start":"2026-05-28T13:19:50.339045Z","end":"2026-05-28T13:19:50.59524Z","steps":["trace[978319560] 'process raft request' (duration: 255.959106ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:19:50.595381Z","caller":"traceutil/trace.go:171","msg":"trace[665006389] linearizableReadLoop","detail":"{readStateIndex:21500; appliedIndex:21498; }","duration":"245.572897ms","start":"2026-05-28T13:19:50.349584Z","end":"2026-05-28T13:19:50.595157Z","steps":["trace[665006389] 'read index received' (duration: 37.521644ms)","trace[665006389] 'applied index is now lower than readState.Index' (duration: 208.050283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:19:50.595502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.896092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-28T13:19:50.595558Z","caller":"traceutil/trace.go:171","msg":"trace[1521982949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20794; }","duration":"245.987674ms","start":"2026-05-28T13:19:50.349557Z","end":"2026-05-28T13:19:50.595545Z","steps":["trace[1521982949] 'agreement among raft nodes before linearized reading' (duration: 245.77716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:19:50.595617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.088693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T13:19:50.59566Z","caller":"traceutil/trace.go:171","msg":"trace[378403885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20794; }","duration":"236.164995ms","start":"2026-05-28T13:19:50.359484Z","end":"2026-05-28T13:19:50.595649Z","steps":["trace[378403885] 'agreement among raft nodes before linearized reading' (duration: 236.010333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:19:50.904746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.911498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:19:50.905067Z","caller":"traceutil/trace.go:171","msg":"trace[73294476] transaction","detail":"{read_only:false; response_revision:20796; number_of_response:1; }","duration":"305.55796ms","start":"2026-05-28T13:19:50.599491Z","end":"2026-05-28T13:19:50.905049Z","steps":["trace[73294476] 'process raft request' (duration: 305.355997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:19:50.905192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:19:50.599481Z","time spent":"305.630241ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:19:50.905286Z","caller":"traceutil/trace.go:171","msg":"trace[1675900593] transaction","detail":"{read_only:false; response_revision:20795; number_of_response:1; }","duration":"305.900397ms","start":"2026-05-28T13:19:50.599336Z","end":"2026-05-28T13:19:50.905237Z","steps":["trace[1675900593] 'process raft request' (duration: 62.415028ms)","trace[1675900593] 'compare' (duration: 242.700045ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:19:50.905346Z","caller":"traceutil/trace.go:171","msg":"trace[1518270811] transaction","detail":"{read_only:false; response_revision:20797; number_of_response:1; }","duration":"294.02573ms","start":"2026-05-28T13:19:50.611281Z","end":"2026-05-28T13:19:50.905307Z","steps":["trace[1518270811] 'process raft request' (duration: 293.723415ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:19:50.90543Z","caller":"traceutil/trace.go:171","msg":"trace[1215038569] transaction","detail":"{read_only:false; response_revision:20798; number_of_response:1; }","duration":"294.002909ms","start":"2026-05-28T13:19:50.611386Z","end":"2026-05-28T13:19:50.905389Z","steps":["trace[1215038569] 'process raft request' (duration: 293.719594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:19:50.905469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:19:50.599316Z","time spent":"306.063039ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:19:50.905391Z","caller":"traceutil/trace.go:171","msg":"trace[1135441486] linearizableReadLoop","detail":"{readStateIndex:21505; appliedIndex:21500; }","duration":"246.944411ms","start":"2026-05-28T13:19:50.658431Z","end":"2026-05-28T13:19:50.905375Z","steps":["trace[1135441486] 'read index received' (duration: 3.30253ms)","trace[1135441486] 'applied index is now lower than readState.Index' (duration: 243.640711ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:19:50.905502Z","caller":"traceutil/trace.go:171","msg":"trace[1294353006] transaction","detail":"{read_only:false; response_revision:20799; number_of_response:1; }","duration":"256.983856ms","start":"2026-05-28T13:19:50.648466Z","end":"2026-05-28T13:19:50.905449Z","steps":["trace[1294353006] 'process raft request' (duration: 256.69103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:19:50.906213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.761817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-28T13:19:50.90637Z","caller":"traceutil/trace.go:171","msg":"trace[788329827] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20799; }","duration":"247.96164ms","start":"2026-05-28T13:19:50.658387Z","end":"2026-05-28T13:19:50.906349Z","steps":["trace[788329827] 'agreement among raft nodes before linearized reading' (duration: 247.039843ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:19:50.915301Z","caller":"traceutil/trace.go:171","msg":"trace[828930966] transaction","detail":"{read_only:false; response_revision:20800; number_of_response:1; }","duration":"234.847531ms","start":"2026-05-28T13:19:50.68044Z","end":"2026-05-28T13:19:50.915287Z","steps":["trace[828930966] 'process raft request' (duration: 234.629088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:19:50.91537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.500413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-28T13:19:50.915427Z","caller":"traceutil/trace.go:171","msg":"trace[1027032392] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:20800; }","duration":"179.598084ms","start":"2026-05-28T13:19:50.735814Z","end":"2026-05-28T13:19:50.915412Z","steps":["trace[1027032392] 'agreement among raft nodes before linearized reading' (duration: 179.38908ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:19:51.075158Z","caller":"traceutil/trace.go:171","msg":"trace[1541738561] linearizableReadLoop","detail":"{readStateIndex:21508; appliedIndex:21507; }","duration":"123.862919ms","start":"2026-05-28T13:19:50.951268Z","end":"2026-05-28T13:19:51.075131Z","steps":["trace[1541738561] 'read index received' (duration: 122.138057ms)","trace[1541738561] 'applied index is now lower than readState.Index' (duration: 1.723772ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:19:51.075337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.063553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-28T13:19:51.075366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.857959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-28T13:19:51.075387Z","caller":"traceutil/trace.go:171","msg":"trace[858314907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20801; }","duration":"124.157895ms","start":"2026-05-28T13:19:50.951215Z","end":"2026-05-28T13:19:51.075373Z","steps":["trace[858314907] 'agreement among raft nodes before linearized reading' (duration: 123.988262ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:19:51.075394Z","caller":"traceutil/trace.go:171","msg":"trace[1537761592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20801; }","duration":"111.929161ms","start":"2026-05-28T13:19:50.963455Z","end":"2026-05-28T13:19:51.075385Z","steps":["trace[1537761592] 'agreement among raft nodes before linearized reading' (duration: 111.798418ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:22:47.605343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19961} {"level":"info","ts":"2026-05-28T13:22:47.724769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19961,"took":"107.770655ms","hash":3876762745,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":31014912,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T13:22:47.72487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3876762745,"revision":19961,"compact-revision":17589} {"level":"info","ts":"2026-05-28T13:23:39.758709Z","caller":"traceutil/trace.go:171","msg":"trace[479584656] transaction","detail":"{read_only:false; response_revision:23211; number_of_response:1; }","duration":"254.730246ms","start":"2026-05-28T13:23:39.503956Z","end":"2026-05-28T13:23:39.758687Z","steps":["trace[479584656] 'process raft request' (duration: 254.566003ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:23:39.978393Z","caller":"traceutil/trace.go:171","msg":"trace[428627071] transaction","detail":"{read_only:false; response_revision:23212; number_of_response:1; }","duration":"260.357542ms","start":"2026-05-28T13:23:39.718Z","end":"2026-05-28T13:23:39.978358Z","steps":["trace[428627071] 'process raft request' (duration: 218.242831ms)","trace[428627071] 'compare' (duration: 41.811575ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:23:39.978495Z","caller":"traceutil/trace.go:171","msg":"trace[286252528] linearizableReadLoop","detail":"{readStateIndex:23991; appliedIndex:23989; }","duration":"250.627349ms","start":"2026-05-28T13:23:39.727832Z","end":"2026-05-28T13:23:39.97846Z","steps":["trace[286252528] 'read index received' (duration: 30.82917ms)","trace[286252528] 'applied index is now lower than readState.Index' (duration: 219.795309ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:23:39.978655Z","caller":"traceutil/trace.go:171","msg":"trace[1775002888] transaction","detail":"{read_only:false; response_revision:23215; number_of_response:1; }","duration":"167.858634ms","start":"2026-05-28T13:23:39.810777Z","end":"2026-05-28T13:23:39.978636Z","steps":["trace[1775002888] 'process raft request' (duration: 167.700951ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:23:39.978597Z","caller":"traceutil/trace.go:171","msg":"trace[1147649773] transaction","detail":"{read_only:false; response_revision:23213; number_of_response:1; }","duration":"193.119839ms","start":"2026-05-28T13:23:39.785446Z","end":"2026-05-28T13:23:39.978566Z","steps":["trace[1147649773] 'process raft request' (duration: 192.894615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:23:39.978836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.980595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-28T13:23:39.9789Z","caller":"traceutil/trace.go:171","msg":"trace[132942772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23215; }","duration":"251.096197ms","start":"2026-05-28T13:23:39.727789Z","end":"2026-05-28T13:23:39.978885Z","steps":["trace[132942772] 'agreement among raft nodes before linearized reading' (duration: 250.855922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:23:39.979179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.316054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/load-balancer.18b3bd6edc7f9490\" ","response":"range_response_count:1 size:689"} {"level":"warn","ts":"2026-05-28T13:23:39.979195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.544298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:23:39.979235Z","caller":"traceutil/trace.go:171","msg":"trace[35586449] range","detail":"{range_begin:/registry/events/openstack/load-balancer.18b3bd6edc7f9490; range_end:; response_count:1; response_revision:23215; }","duration":"216.404556ms","start":"2026-05-28T13:23:39.762816Z","end":"2026-05-28T13:23:39.979221Z","steps":["trace[35586449] 'agreement among raft nodes before linearized reading' (duration: 216.262083ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:23:39.979269Z","caller":"traceutil/trace.go:171","msg":"trace[1271976883] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:23215; }","duration":"101.66191ms","start":"2026-05-28T13:23:39.877581Z","end":"2026-05-28T13:23:39.979243Z","steps":["trace[1271976883] 'agreement among raft nodes before linearized reading' (duration: 101.515558ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:23:39.979244Z","caller":"traceutil/trace.go:171","msg":"trace[1137947962] transaction","detail":"{read_only:false; response_revision:23214; number_of_response:1; }","duration":"191.09596ms","start":"2026-05-28T13:23:39.787423Z","end":"2026-05-28T13:23:39.978519Z","steps":["trace[1137947962] 'process raft request' (duration: 191.007318ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:24:58.888332Z","caller":"traceutil/trace.go:171","msg":"trace[1495490144] transaction","detail":"{read_only:false; response_revision:24082; number_of_response:1; }","duration":"112.852693ms","start":"2026-05-28T13:24:58.775435Z","end":"2026-05-28T13:24:58.888287Z","steps":["trace[1495490144] 'process raft request' (duration: 53.988376ms)","trace[1495490144] 'compare' (duration: 58.220136ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:26:59.894555Z","caller":"traceutil/trace.go:171","msg":"trace[1168740351] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"118.292455ms","start":"2026-05-28T13:26:59.776237Z","end":"2026-05-28T13:26:59.894529Z","steps":["trace[1168740351] 'process raft request' (duration: 118.138702ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:05.085922Z","caller":"traceutil/trace.go:171","msg":"trace[252163801] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"112.501447ms","start":"2026-05-28T13:27:04.973393Z","end":"2026-05-28T13:27:05.085894Z","steps":["trace[252163801] 'process raft request' (duration: 112.333094ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:05.415149Z","caller":"traceutil/trace.go:171","msg":"trace[231252715] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"414.199421ms","start":"2026-05-28T13:27:05.00093Z","end":"2026-05-28T13:27:05.415129Z","steps":["trace[231252715] 'process raft request' (duration: 413.854534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:05.415287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:05.000898Z","time spent":"414.307692ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:05.415295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.458565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-28T13:27:05.415336Z","caller":"traceutil/trace.go:171","msg":"trace[287177146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25297; }","duration":"318.545397ms","start":"2026-05-28T13:27:05.09678Z","end":"2026-05-28T13:27:05.415326Z","steps":["trace[287177146] 'agreement among raft nodes before linearized reading' (duration: 318.410335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:05.415368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:05.096763Z","time spent":"318.597708ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T13:27:05.415144Z","caller":"traceutil/trace.go:171","msg":"trace[68869057] linearizableReadLoop","detail":"{readStateIndex:26131; appliedIndex:26130; }","duration":"318.261792ms","start":"2026-05-28T13:27:05.096849Z","end":"2026-05-28T13:27:05.41511Z","steps":["trace[68869057] 'read index received' (duration: 317.893186ms)","trace[68869057] 'applied index is now lower than readState.Index' (duration: 367.626µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:05.425942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.812822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-28T13:27:05.426053Z","caller":"traceutil/trace.go:171","msg":"trace[1108504902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25299; }","duration":"318.950155ms","start":"2026-05-28T13:27:05.107089Z","end":"2026-05-28T13:27:05.426039Z","steps":["trace[1108504902] 'agreement among raft nodes before linearized reading' (duration: 318.735651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:05.426104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:05.107078Z","time spent":"319.017166ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T13:27:05.426147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.227544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:05.426199Z","caller":"traceutil/trace.go:171","msg":"trace[57485097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25299; }","duration":"140.323686ms","start":"2026-05-28T13:27:05.285865Z","end":"2026-05-28T13:27:05.426189Z","steps":["trace[57485097] 'agreement among raft nodes before linearized reading' (duration: 140.241674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:05.426407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.517003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T13:27:05.42648Z","caller":"traceutil/trace.go:171","msg":"trace[687224163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25299; }","duration":"262.636875ms","start":"2026-05-28T13:27:05.163826Z","end":"2026-05-28T13:27:05.426463Z","steps":["trace[687224163] 'agreement among raft nodes before linearized reading' (duration: 262.468922ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:05.661517Z","caller":"traceutil/trace.go:171","msg":"trace[1184055374] linearizableReadLoop","detail":"{readStateIndex:26134; appliedIndex:26133; }","duration":"234.525621ms","start":"2026-05-28T13:27:05.426968Z","end":"2026-05-28T13:27:05.661493Z","steps":["trace[1184055374] 'read index received' (duration: 231.77577ms)","trace[1184055374] 'applied index is now lower than readState.Index' (duration: 2.748711ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:05.661613Z","caller":"traceutil/trace.go:171","msg":"trace[828479356] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"241.871859ms","start":"2026-05-28T13:27:05.41971Z","end":"2026-05-28T13:27:05.661582Z","steps":["trace[828479356] 'process raft request' (duration: 238.953525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:05.661691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.704345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:05.661769Z","caller":"traceutil/trace.go:171","msg":"trace[1502835613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25300; }","duration":"234.813146ms","start":"2026-05-28T13:27:05.426944Z","end":"2026-05-28T13:27:05.661757Z","steps":["trace[1502835613] 'agreement among raft nodes before linearized reading' (duration: 234.657713ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:05.664168Z","caller":"traceutil/trace.go:171","msg":"trace[197915498] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"233.935431ms","start":"2026-05-28T13:27:05.430217Z","end":"2026-05-28T13:27:05.664153Z","steps":["trace[197915498] 'process raft request' (duration: 233.851499ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:05.664182Z","caller":"traceutil/trace.go:171","msg":"trace[1226253739] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"234.719825ms","start":"2026-05-28T13:27:05.429445Z","end":"2026-05-28T13:27:05.664165Z","steps":["trace[1226253739] 'process raft request' (duration: 234.587073ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:05.664176Z","caller":"traceutil/trace.go:171","msg":"trace[248631121] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"235.185744ms","start":"2026-05-28T13:27:05.428954Z","end":"2026-05-28T13:27:05.66414Z","steps":["trace[248631121] 'process raft request' (duration: 234.960319ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:05.664381Z","caller":"traceutil/trace.go:171","msg":"trace[867023115] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"233.736837ms","start":"2026-05-28T13:27:05.430634Z","end":"2026-05-28T13:27:05.664371Z","steps":["trace[867023115] 'process raft request' (duration: 233.468312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:05.664442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.439664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18746"} {"level":"info","ts":"2026-05-28T13:27:05.664472Z","caller":"traceutil/trace.go:171","msg":"trace[265303680] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25304; }","duration":"106.505235ms","start":"2026-05-28T13:27:05.55796Z","end":"2026-05-28T13:27:05.664465Z","steps":["trace[265303680] 'agreement among raft nodes before linearized reading' (duration: 106.366152ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:05.971282Z","caller":"traceutil/trace.go:171","msg":"trace[2047298106] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"139.054531ms","start":"2026-05-28T13:27:05.832201Z","end":"2026-05-28T13:27:05.971255Z","steps":["trace[2047298106] 'process raft request' (duration: 56.008304ms)","trace[2047298106] 'compare' (duration: 82.951655ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:06.056366Z","caller":"traceutil/trace.go:171","msg":"trace[1361996798] linearizableReadLoop","detail":"{readStateIndex:26140; appliedIndex:26138; }","duration":"217.225539ms","start":"2026-05-28T13:27:05.839107Z","end":"2026-05-28T13:27:06.056332Z","steps":["trace[1361996798] 'read index received' (duration: 49.037775ms)","trace[1361996798] 'applied index is now lower than readState.Index' (duration: 168.185994ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:06.056684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.551065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T13:27:06.056751Z","caller":"traceutil/trace.go:171","msg":"trace[2055429305] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25305; }","duration":"217.679488ms","start":"2026-05-28T13:27:05.839057Z","end":"2026-05-28T13:27:06.056737Z","steps":["trace[2055429305] 'agreement among raft nodes before linearized reading' (duration: 217.446744ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:06.261671Z","caller":"traceutil/trace.go:171","msg":"trace[1411411171] linearizableReadLoop","detail":"{readStateIndex:26141; appliedIndex:26140; }","duration":"205.116643ms","start":"2026-05-28T13:27:06.056536Z","end":"2026-05-28T13:27:06.261652Z","steps":["trace[1411411171] 'read index received' (duration: 204.96925ms)","trace[1411411171] 'applied index is now lower than readState.Index' (duration: 146.653µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:06.261762Z","caller":"traceutil/trace.go:171","msg":"trace[1581693262] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"349.167188ms","start":"2026-05-28T13:27:05.912587Z","end":"2026-05-28T13:27:06.261754Z","steps":["trace[1581693262] 'process raft request' (duration: 348.878942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:06.261846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:05.912566Z","time spent":"349.216459ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:06.261952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.071826ms","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:6"} {"level":"info","ts":"2026-05-28T13:27:06.262029Z","caller":"traceutil/trace.go:171","msg":"trace[443597736] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25306; }","duration":"261.195067ms","start":"2026-05-28T13:27:06.000816Z","end":"2026-05-28T13:27:06.262011Z","steps":["trace[443597736] 'agreement among raft nodes before linearized reading' (duration: 261.046635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:06.585711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.074905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:27:06.585829Z","caller":"traceutil/trace.go:171","msg":"trace[678118951] linearizableReadLoop","detail":"{readStateIndex:26142; appliedIndex:26141; }","duration":"277.016594ms","start":"2026-05-28T13:27:06.308792Z","end":"2026-05-28T13:27:06.585809Z","steps":["trace[678118951] 'read index received' (duration: 124.756415ms)","trace[678118951] 'applied index is now lower than readState.Index' (duration: 152.258369ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:06.58587Z","caller":"traceutil/trace.go:171","msg":"trace[215909561] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"318.571218ms","start":"2026-05-28T13:27:06.267248Z","end":"2026-05-28T13:27:06.585819Z","steps":["trace[215909561] 'process raft request' (duration: 166.290249ms)","trace[215909561] 'compare' (duration: 151.630296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:06.585996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.595517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:06.586057Z","caller":"traceutil/trace.go:171","msg":"trace[485045812] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25307; }","duration":"201.693299ms","start":"2026-05-28T13:27:06.384347Z","end":"2026-05-28T13:27:06.586041Z","steps":["trace[485045812] 'agreement among raft nodes before linearized reading' (duration: 201.567937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:06.586024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.211537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T13:27:06.586261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:06.267231Z","time spent":"318.69525ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:06.58633Z","caller":"traceutil/trace.go:171","msg":"trace[1850463116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25307; }","duration":"277.523583ms","start":"2026-05-28T13:27:06.308781Z","end":"2026-05-28T13:27:06.586304Z","steps":["trace[1850463116] 'agreement among raft nodes before linearized reading' (duration: 277.129025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:06.912546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.658042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:06.912625Z","caller":"traceutil/trace.go:171","msg":"trace[1444399121] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25307; }","duration":"198.838696ms","start":"2026-05-28T13:27:06.713768Z","end":"2026-05-28T13:27:06.912606Z","steps":["trace[1444399121] 'count revisions from in-memory index tree' (duration: 198.560951ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.046131Z","caller":"traceutil/trace.go:171","msg":"trace[1998975980] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"228.496889ms","start":"2026-05-28T13:27:06.817603Z","end":"2026-05-28T13:27:07.0461Z","steps":["trace[1998975980] 'process raft request' (duration: 228.300405ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.358059Z","caller":"traceutil/trace.go:171","msg":"trace[80316315] linearizableReadLoop","detail":"{readStateIndex:26146; appliedIndex:26142; }","duration":"415.059136ms","start":"2026-05-28T13:27:06.942972Z","end":"2026-05-28T13:27:07.358031Z","steps":["trace[80316315] 'read index received' (duration: 102.956399ms)","trace[80316315] 'applied index is now lower than readState.Index' (duration: 312.101927ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:07.358282Z","caller":"traceutil/trace.go:171","msg":"trace[1014317875] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"536.914617ms","start":"2026-05-28T13:27:06.821334Z","end":"2026-05-28T13:27:07.358248Z","steps":["trace[1014317875] 'process raft request' (duration: 536.56773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:07.358442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.469424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:07.358498Z","caller":"traceutil/trace.go:171","msg":"trace[1031945290] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25311; }","duration":"415.584986ms","start":"2026-05-28T13:27:06.942897Z","end":"2026-05-28T13:27:07.358482Z","steps":["trace[1031945290] 'agreement among raft nodes before linearized reading' (duration: 415.491554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:07.358551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:06.942863Z","time spent":"415.675077ms","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-28T13:27:07.358561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:06.821293Z","time spent":"537.11273ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:07.35891Z","caller":"traceutil/trace.go:171","msg":"trace[2126076201] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"537.715242ms","start":"2026-05-28T13:27:06.821176Z","end":"2026-05-28T13:27:07.358891Z","steps":["trace[2126076201] 'process raft request' (duration: 517.934553ms)","trace[2126076201] 'compare' (duration: 18.542236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:07.359038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:06.821145Z","time spent":"537.846854ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:07.358359Z","caller":"traceutil/trace.go:171","msg":"trace[1632620385] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"533.873449ms","start":"2026-05-28T13:27:06.824472Z","end":"2026-05-28T13:27:07.358346Z","steps":["trace[1632620385] 'process raft request' (duration: 533.520833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:07.359292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:06.824458Z","time spent":"534.720626ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:07.361045Z","caller":"traceutil/trace.go:171","msg":"trace[1955915855] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"269.486602ms","start":"2026-05-28T13:27:07.091534Z","end":"2026-05-28T13:27:07.36102Z","steps":["trace[1955915855] 'process raft request' (duration: 269.047534ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.692426Z","caller":"traceutil/trace.go:171","msg":"trace[1560044880] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"262.676206ms","start":"2026-05-28T13:27:07.429714Z","end":"2026-05-28T13:27:07.69239Z","steps":["trace[1560044880] 'process raft request' (duration: 230.462685ms)","trace[1560044880] 'compare' (duration: 32.062278ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:07.699629Z","caller":"traceutil/trace.go:171","msg":"trace[255084931] linearizableReadLoop","detail":"{readStateIndex:26149; appliedIndex:26147; }","duration":"158.762848ms","start":"2026-05-28T13:27:07.540848Z","end":"2026-05-28T13:27:07.699611Z","steps":["trace[255084931] 'read index received' (duration: 119.414675ms)","trace[255084931] 'applied index is now lower than readState.Index' (duration: 39.337293ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:07.699749Z","caller":"traceutil/trace.go:171","msg":"trace[266886708] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"269.092425ms","start":"2026-05-28T13:27:07.430583Z","end":"2026-05-28T13:27:07.699675Z","steps":["trace[266886708] 'process raft request' (duration: 268.871451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:07.699828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.953342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-28T13:27:07.699866Z","caller":"traceutil/trace.go:171","msg":"trace[529450350] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25315; }","duration":"159.046864ms","start":"2026-05-28T13:27:07.540812Z","end":"2026-05-28T13:27:07.699859Z","steps":["trace[529450350] 'agreement among raft nodes before linearized reading' (duration: 158.894212ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.700284Z","caller":"traceutil/trace.go:171","msg":"trace[1125309802] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"147.964418ms","start":"2026-05-28T13:27:07.551764Z","end":"2026-05-28T13:27:07.699729Z","steps":["trace[1125309802] 'process raft request' (duration: 147.802545ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.887532Z","caller":"traceutil/trace.go:171","msg":"trace[387953874] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"183.755024ms","start":"2026-05-28T13:27:07.703726Z","end":"2026-05-28T13:27:07.887481Z","steps":["trace[387953874] 'process raft request' (duration: 183.585081ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.903329Z","caller":"traceutil/trace.go:171","msg":"trace[5410213] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"199.514988ms","start":"2026-05-28T13:27:07.703797Z","end":"2026-05-28T13:27:07.903312Z","steps":["trace[5410213] 'process raft request' (duration: 199.456637ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.903569Z","caller":"traceutil/trace.go:171","msg":"trace[1353360081] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"198.618431ms","start":"2026-05-28T13:27:07.704943Z","end":"2026-05-28T13:27:07.903562Z","steps":["trace[1353360081] 'process raft request' (duration: 198.586721ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.903616Z","caller":"traceutil/trace.go:171","msg":"trace[1070210655] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"199.542409ms","start":"2026-05-28T13:27:07.704039Z","end":"2026-05-28T13:27:07.903582Z","steps":["trace[1070210655] 'process raft request' (duration: 199.246923ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.903341Z","caller":"traceutil/trace.go:171","msg":"trace[1166309417] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"199.522509ms","start":"2026-05-28T13:27:07.703788Z","end":"2026-05-28T13:27:07.903311Z","steps":["trace[1166309417] 'process raft request' (duration: 199.356826ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:07.903874Z","caller":"traceutil/trace.go:171","msg":"trace[1520648807] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"199.181971ms","start":"2026-05-28T13:27:07.704666Z","end":"2026-05-28T13:27:07.903848Z","steps":["trace[1520648807] 'process raft request' (duration: 198.770844ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:08.586818Z","caller":"traceutil/trace.go:171","msg":"trace[142981518] linearizableReadLoop","detail":"{readStateIndex:26157; appliedIndex:26156; }","duration":"149.098769ms","start":"2026-05-28T13:27:08.4377Z","end":"2026-05-28T13:27:08.586799Z","steps":["trace[142981518] 'read index received' (duration: 148.905415ms)","trace[142981518] 'applied index is now lower than readState.Index' (duration: 192.354µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:08.58695Z","caller":"traceutil/trace.go:171","msg":"trace[831322036] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"317.992277ms","start":"2026-05-28T13:27:08.268923Z","end":"2026-05-28T13:27:08.586916Z","steps":["trace[831322036] 'process raft request' (duration: 317.729562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:08.587113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:08.26889Z","time spent":"318.13977ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:08.586988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.273362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-28T13:27:08.587165Z","caller":"traceutil/trace.go:171","msg":"trace[1350593466] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25322; }","duration":"149.496386ms","start":"2026-05-28T13:27:08.437657Z","end":"2026-05-28T13:27:08.587154Z","steps":["trace[1350593466] 'agreement among raft nodes before linearized reading' (duration: 149.212791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:23.145438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.061361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:23.145771Z","caller":"traceutil/trace.go:171","msg":"trace[2140284282] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25484; }","duration":"110.455489ms","start":"2026-05-28T13:27:23.035291Z","end":"2026-05-28T13:27:23.145746Z","steps":["trace[2140284282] 'count revisions from in-memory index tree' (duration: 109.960679ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:23.380814Z","caller":"traceutil/trace.go:171","msg":"trace[334441804] transaction","detail":"{read_only:false; response_revision:25485; number_of_response:1; }","duration":"228.939007ms","start":"2026-05-28T13:27:23.151844Z","end":"2026-05-28T13:27:23.380783Z","steps":["trace[334441804] 'process raft request' (duration: 228.704193ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:23.400564Z","caller":"traceutil/trace.go:171","msg":"trace[1723261021] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"234.379969ms","start":"2026-05-28T13:27:23.166158Z","end":"2026-05-28T13:27:23.400538Z","steps":["trace[1723261021] 'process raft request' (duration: 234.239776ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:23.696566Z","caller":"traceutil/trace.go:171","msg":"trace[1642094026] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"258.313566ms","start":"2026-05-28T13:27:23.438217Z","end":"2026-05-28T13:27:23.696531Z","steps":["trace[1642094026] 'process raft request' (duration: 232.713258ms)","trace[1642094026] 'compare' (duration: 25.454395ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:23.916766Z","caller":"traceutil/trace.go:171","msg":"trace[1111045021] transaction","detail":"{read_only:false; response_revision:25488; number_of_response:1; }","duration":"475.596794ms","start":"2026-05-28T13:27:23.441135Z","end":"2026-05-28T13:27:23.916732Z","steps":["trace[1111045021] 'process raft request' (duration: 456.401086ms)","trace[1111045021] 'compare' (duration: 18.564046ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:23.916821Z","caller":"traceutil/trace.go:171","msg":"trace[1581396771] linearizableReadLoop","detail":"{readStateIndex:26329; appliedIndex:26326; }","duration":"204.524282ms","start":"2026-05-28T13:27:23.712272Z","end":"2026-05-28T13:27:23.916796Z","steps":["trace[1581396771] 'read index received' (duration: 185.241153ms)","trace[1581396771] 'applied index is now lower than readState.Index' (duration: 19.282149ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:23.916929Z","caller":"traceutil/trace.go:171","msg":"trace[1013899465] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"473.79026ms","start":"2026-05-28T13:27:23.443124Z","end":"2026-05-28T13:27:23.916915Z","steps":["trace[1013899465] 'process raft request' (duration: 473.290421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:23.91695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:23.441103Z","time spent":"475.737387ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:23.917036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:23.443117Z","time spent":"473.838421ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:23.917079Z","caller":"traceutil/trace.go:171","msg":"trace[380477141] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"466.545385ms","start":"2026-05-28T13:27:23.450515Z","end":"2026-05-28T13:27:23.91706Z","steps":["trace[380477141] 'process raft request' (duration: 466.072716ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:23.917102Z","caller":"traceutil/trace.go:171","msg":"trace[1775047047] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"167.245566ms","start":"2026-05-28T13:27:23.749836Z","end":"2026-05-28T13:27:23.917082Z","steps":["trace[1775047047] 'process raft request' (duration: 166.92341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:23.917172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.901879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-05-28T13:27:23.917181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:23.450496Z","time spent":"466.631736ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:23.917211Z","caller":"traceutil/trace.go:171","msg":"trace[583843401] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d; range_end:; response_count:1; response_revision:25490; }","duration":"204.96829ms","start":"2026-05-28T13:27:23.712232Z","end":"2026-05-28T13:27:23.9172Z","steps":["trace[583843401] 'agreement among raft nodes before linearized reading' (duration: 204.655265ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:23.917298Z","caller":"traceutil/trace.go:171","msg":"trace[975754636] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"141.523397ms","start":"2026-05-28T13:27:23.775762Z","end":"2026-05-28T13:27:23.917286Z","steps":["trace[975754636] 'process raft request' (duration: 141.260382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:24.228225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.001587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:27:24.228361Z","caller":"traceutil/trace.go:171","msg":"trace[1133991732] linearizableReadLoop","detail":"{readStateIndex:26332; appliedIndex:26331; }","duration":"282.936283ms","start":"2026-05-28T13:27:23.945405Z","end":"2026-05-28T13:27:24.228341Z","steps":["trace[1133991732] 'read index received' (duration: 126.795264ms)","trace[1133991732] 'applied index is now lower than readState.Index' (duration: 156.139929ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:24.228477Z","caller":"traceutil/trace.go:171","msg":"trace[1544155413] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"303.505027ms","start":"2026-05-28T13:27:23.924961Z","end":"2026-05-28T13:27:24.228466Z","steps":["trace[1544155413] 'process raft request' (duration: 147.137453ms)","trace[1544155413] 'compare' (duration: 155.813324ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:24.228532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:23.924944Z","time spent":"303.561787ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:24.228739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.987796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T13:27:24.228833Z","caller":"traceutil/trace.go:171","msg":"trace[616332049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25493; }","duration":"253.130148ms","start":"2026-05-28T13:27:23.97568Z","end":"2026-05-28T13:27:24.22881Z","steps":["trace[616332049] 'agreement among raft nodes before linearized reading' (duration: 252.888394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:24.228883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.567101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T13:27:24.228916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.559579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-28T13:27:24.228903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.463724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-28T13:27:24.228947Z","caller":"traceutil/trace.go:171","msg":"trace[1414318204] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:25493; }","duration":"140.703513ms","start":"2026-05-28T13:27:24.088225Z","end":"2026-05-28T13:27:24.228928Z","steps":["trace[1414318204] 'agreement among raft nodes before linearized reading' (duration: 140.596191ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:24.228964Z","caller":"traceutil/trace.go:171","msg":"trace[879637624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25493; }","duration":"244.631521ms","start":"2026-05-28T13:27:23.98432Z","end":"2026-05-28T13:27:24.228951Z","steps":["trace[879637624] 'agreement among raft nodes before linearized reading' (duration: 244.454097ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:24.229011Z","caller":"traceutil/trace.go:171","msg":"trace[2072659242] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d; range_end:; response_count:1; response_revision:25493; }","duration":"283.627187ms","start":"2026-05-28T13:27:23.945355Z","end":"2026-05-28T13:27:24.228982Z","steps":["trace[2072659242] 'agreement among raft nodes before linearized reading' (duration: 283.423783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:24.229031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.769821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-28T13:27:24.229066Z","caller":"traceutil/trace.go:171","msg":"trace[642449657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25493; }","duration":"245.816211ms","start":"2026-05-28T13:27:23.983239Z","end":"2026-05-28T13:27:24.229055Z","steps":["trace[642449657] 'agreement among raft nodes before linearized reading' (duration: 245.708839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:24.229118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.908816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T13:27:24.229146Z","caller":"traceutil/trace.go:171","msg":"trace[324712367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25493; }","duration":"244.994627ms","start":"2026-05-28T13:27:23.984143Z","end":"2026-05-28T13:27:24.229138Z","steps":["trace[324712367] 'agreement among raft nodes before linearized reading' (duration: 244.922156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:24.229312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.638898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-28T13:27:24.229419Z","caller":"traceutil/trace.go:171","msg":"trace[90797405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25493; }","duration":"252.815452ms","start":"2026-05-28T13:27:23.976578Z","end":"2026-05-28T13:27:24.229393Z","steps":["trace[90797405] 'agreement among raft nodes before linearized reading' (duration: 252.461055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:24.501901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.137082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:27:24.502011Z","caller":"traceutil/trace.go:171","msg":"trace[1913058948] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"267.901464ms","start":"2026-05-28T13:27:24.234087Z","end":"2026-05-28T13:27:24.501988Z","steps":["trace[1913058948] 'process raft request' (duration: 127.600799ms)","trace[1913058948] 'compare' (duration: 139.820826ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:24.505709Z","caller":"traceutil/trace.go:171","msg":"trace[1822276975] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"271.518591ms","start":"2026-05-28T13:27:24.234131Z","end":"2026-05-28T13:27:24.505649Z","steps":["trace[1822276975] 'process raft request' (duration: 271.241006ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:24.505988Z","caller":"traceutil/trace.go:171","msg":"trace[1645811277] linearizableReadLoop","detail":"{readStateIndex:26337; appliedIndex:26332; }","duration":"223.014836ms","start":"2026-05-28T13:27:24.282936Z","end":"2026-05-28T13:27:24.505951Z","steps":["trace[1645811277] 'read index received' (duration: 78.774998ms)","trace[1645811277] 'applied index is now lower than readState.Index' (duration: 144.238438ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:24.50613Z","caller":"traceutil/trace.go:171","msg":"trace[1870280003] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"271.923558ms","start":"2026-05-28T13:27:24.234192Z","end":"2026-05-28T13:27:24.506116Z","steps":["trace[1870280003] 'process raft request' (duration: 271.399458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:24.506139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.19875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:24.506177Z","caller":"traceutil/trace.go:171","msg":"trace[2082440058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25498; }","duration":"223.254851ms","start":"2026-05-28T13:27:24.28291Z","end":"2026-05-28T13:27:24.506165Z","steps":["trace[2082440058] 'agreement among raft nodes before linearized reading' (duration: 223.17006ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:24.506144Z","caller":"traceutil/trace.go:171","msg":"trace[1945818535] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"271.224075ms","start":"2026-05-28T13:27:24.234861Z","end":"2026-05-28T13:27:24.506085Z","steps":["trace[1945818535] 'process raft request' (duration: 270.915568ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:24.506344Z","caller":"traceutil/trace.go:171","msg":"trace[1443784012] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"270.93452ms","start":"2026-05-28T13:27:24.235375Z","end":"2026-05-28T13:27:24.506309Z","steps":["trace[1443784012] 'process raft request' (duration: 270.528212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:24.79642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.684326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:27:24.796642Z","caller":"traceutil/trace.go:171","msg":"trace[430436273] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"156.001677ms","start":"2026-05-28T13:27:24.64062Z","end":"2026-05-28T13:27:24.796622Z","steps":["trace[430436273] 'process raft request' (duration: 26.004205ms)","trace[430436273] 'compare' (duration: 129.474902ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:25.509417Z","caller":"traceutil/trace.go:171","msg":"trace[1585431228] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"103.053111ms","start":"2026-05-28T13:27:25.406318Z","end":"2026-05-28T13:27:25.509372Z","steps":["trace[1585431228] 'process raft request' (duration: 102.748035ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:25.840796Z","caller":"traceutil/trace.go:171","msg":"trace[1728914566] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"139.182754ms","start":"2026-05-28T13:27:25.701587Z","end":"2026-05-28T13:27:25.84077Z","steps":["trace[1728914566] 'process raft request' (duration: 139.026481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.555237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.978834ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-28T13:27:26.55567Z","caller":"traceutil/trace.go:171","msg":"trace[1279599192] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"633.828192ms","start":"2026-05-28T13:27:25.921809Z","end":"2026-05-28T13:27:26.555637Z","steps":["trace[1279599192] 'process raft request' (duration: 633.520857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.555856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:25.921782Z","time spent":"633.981826ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:26.581764Z","caller":"traceutil/trace.go:171","msg":"trace[1367351652] linearizableReadLoop","detail":"{readStateIndex:26346; appliedIndex:26340; }","duration":"440.205036ms","start":"2026-05-28T13:27:26.14154Z","end":"2026-05-28T13:27:26.581745Z","steps":["trace[1367351652] 'read index received' (duration: 406.048148ms)","trace[1367351652] 'applied index is now lower than readState.Index' (duration: 34.156028ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:26.581915Z","caller":"traceutil/trace.go:171","msg":"trace[490874624] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"657.78923ms","start":"2026-05-28T13:27:25.924096Z","end":"2026-05-28T13:27:26.581885Z","steps":["trace[490874624] 'process raft request' (duration: 657.617077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.582037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:25.924071Z","time spent":"657.880772ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:26.582084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.545152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b3bdb02114a4f9\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-28T13:27:26.582121Z","caller":"traceutil/trace.go:171","msg":"trace[1301785434] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b3bdb02114a4f9; range_end:; response_count:1; response_revision:25506; }","duration":"440.618042ms","start":"2026-05-28T13:27:26.14149Z","end":"2026-05-28T13:27:26.582108Z","steps":["trace[1301785434] 'agreement among raft nodes before linearized reading' (duration: 440.48727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.582147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:26.141444Z","time spent":"440.696034ms","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1033,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b3bdb02114a4f9\" "} {"level":"info","ts":"2026-05-28T13:27:26.582224Z","caller":"traceutil/trace.go:171","msg":"trace[150809564] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"660.167354ms","start":"2026-05-28T13:27:25.922043Z","end":"2026-05-28T13:27:26.582211Z","steps":["trace[150809564] 'process raft request' (duration: 633.580468ms)","trace[150809564] 'compare' (duration: 25.935233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:26.582292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:25.922015Z","time spent":"660.242276ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:26.582429Z","caller":"traceutil/trace.go:171","msg":"trace[1639678757] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"659.796958ms","start":"2026-05-28T13:27:25.922621Z","end":"2026-05-28T13:27:26.582418Z","steps":["trace[1639678757] 'process raft request' (duration: 659.034673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.582482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:25.92261Z","time spent":"659.843269ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:26.582555Z","caller":"traceutil/trace.go:171","msg":"trace[34294224] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"659.673635ms","start":"2026-05-28T13:27:25.922872Z","end":"2026-05-28T13:27:26.582546Z","steps":["trace[34294224] 'process raft request' (duration: 658.81591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.582618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:25.922862Z","time spent":"659.727926ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:26.582657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.317837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-28T13:27:26.582718Z","caller":"traceutil/trace.go:171","msg":"trace[1778049407] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d; range_end:; response_count:1; response_revision:25506; }","duration":"344.407389ms","start":"2026-05-28T13:27:26.238295Z","end":"2026-05-28T13:27:26.582703Z","steps":["trace[1778049407] 'agreement among raft nodes before linearized reading' (duration: 344.210906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.582761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:26.238272Z","time spent":"344.48055ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d\" "} {"level":"warn","ts":"2026-05-28T13:27:26.583141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.088199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T13:27:26.583187Z","caller":"traceutil/trace.go:171","msg":"trace[1569029743] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25506; }","duration":"134.166951ms","start":"2026-05-28T13:27:26.449008Z","end":"2026-05-28T13:27:26.583175Z","steps":["trace[1569029743] 'agreement among raft nodes before linearized reading' (duration: 134.079799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.583243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.839263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:26.583311Z","caller":"traceutil/trace.go:171","msg":"trace[1759979844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25506; }","duration":"296.951914ms","start":"2026-05-28T13:27:26.286348Z","end":"2026-05-28T13:27:26.5833Z","steps":["trace[1759979844] 'agreement among raft nodes before linearized reading' (duration: 296.854743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.583484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.588741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:26.58352Z","caller":"traceutil/trace.go:171","msg":"trace[851820157] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25506; }","duration":"102.655783ms","start":"2026-05-28T13:27:26.480853Z","end":"2026-05-28T13:27:26.583509Z","steps":["trace[851820157] 'agreement among raft nodes before linearized reading' (duration: 102.602562ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:26.74757Z","caller":"traceutil/trace.go:171","msg":"trace[1294297914] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"159.030604ms","start":"2026-05-28T13:27:26.588502Z","end":"2026-05-28T13:27:26.747533Z","steps":["trace[1294297914] 'process raft request' (duration: 126.163962ms)","trace[1294297914] 'compare' (duration: 32.464165ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:26.748176Z","caller":"traceutil/trace.go:171","msg":"trace[268199205] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"158.981534ms","start":"2026-05-28T13:27:26.589159Z","end":"2026-05-28T13:27:26.748141Z","steps":["trace[268199205] 'process raft request' (duration: 158.694838ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:26.748615Z","caller":"traceutil/trace.go:171","msg":"trace[877380875] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"159.280689ms","start":"2026-05-28T13:27:26.589311Z","end":"2026-05-28T13:27:26.748592Z","steps":["trace[877380875] 'process raft request' (duration: 158.933942ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:26.748609Z","caller":"traceutil/trace.go:171","msg":"trace[1168999708] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"159.233878ms","start":"2026-05-28T13:27:26.589359Z","end":"2026-05-28T13:27:26.748593Z","steps":["trace[1168999708] 'process raft request' (duration: 159.098625ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:26.748695Z","caller":"traceutil/trace.go:171","msg":"trace[315176201] linearizableReadLoop","detail":"{readStateIndex:26352; appliedIndex:26346; }","duration":"117.131823ms","start":"2026-05-28T13:27:26.631538Z","end":"2026-05-28T13:27:26.74867Z","steps":["trace[315176201] 'read index received' (duration: 83.14113ms)","trace[315176201] 'applied index is now lower than readState.Index' (duration: 33.989593ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:26.748731Z","caller":"traceutil/trace.go:171","msg":"trace[1109456394] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"159.306389ms","start":"2026-05-28T13:27:26.589402Z","end":"2026-05-28T13:27:26.748709Z","steps":["trace[1109456394] 'process raft request' (duration: 159.123616ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:26.748399Z","caller":"traceutil/trace.go:171","msg":"trace[1250328843] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"159.145057ms","start":"2026-05-28T13:27:26.58922Z","end":"2026-05-28T13:27:26.748365Z","steps":["trace[1250328843] 'process raft request' (duration: 158.963153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:26.748943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.388048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-28T13:27:26.748978Z","caller":"traceutil/trace.go:171","msg":"trace[626695394] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25512; }","duration":"117.4655ms","start":"2026-05-28T13:27:26.631502Z","end":"2026-05-28T13:27:26.748967Z","steps":["trace[626695394] 'agreement among raft nodes before linearized reading' (duration: 117.278186ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:27.71673Z","caller":"traceutil/trace.go:171","msg":"trace[361826177] linearizableReadLoop","detail":"{readStateIndex:26355; appliedIndex:26354; }","duration":"128.539406ms","start":"2026-05-28T13:27:27.588162Z","end":"2026-05-28T13:27:27.716702Z","steps":["trace[361826177] 'read index received' (duration: 128.394043ms)","trace[361826177] 'applied index is now lower than readState.Index' (duration: 143.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:27.71687Z","caller":"traceutil/trace.go:171","msg":"trace[61532764] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"201.74874ms","start":"2026-05-28T13:27:27.51511Z","end":"2026-05-28T13:27:27.716859Z","steps":["trace[61532764] 'process raft request' (duration: 201.391213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:27.717146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.979814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-05-28T13:27:27.71719Z","caller":"traceutil/trace.go:171","msg":"trace[350731253] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c15e231-9ce6-4ff0-a600-f2339c2d7a3d; range_end:; response_count:1; response_revision:25515; }","duration":"129.087366ms","start":"2026-05-28T13:27:27.58809Z","end":"2026-05-28T13:27:27.717177Z","steps":["trace[350731253] 'agreement among raft nodes before linearized reading' (duration: 128.928193ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:27.92325Z","caller":"traceutil/trace.go:171","msg":"trace[1980847250] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"190.043401ms","start":"2026-05-28T13:27:27.733188Z","end":"2026-05-28T13:27:27.923232Z","steps":["trace[1980847250] 'process raft request' (duration: 182.210797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:33.055007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.51921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:27:33.055355Z","caller":"traceutil/trace.go:171","msg":"trace[1852597047] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"231.513015ms","start":"2026-05-28T13:27:32.823818Z","end":"2026-05-28T13:27:33.055331Z","steps":["trace[1852597047] 'process raft request' (duration: 231.456173ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:33.055363Z","caller":"traceutil/trace.go:171","msg":"trace[1527778313] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"448.285435ms","start":"2026-05-28T13:27:32.607052Z","end":"2026-05-28T13:27:33.055337Z","steps":["trace[1527778313] 'process raft request' (duration: 448.05311ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:33.055369Z","caller":"traceutil/trace.go:171","msg":"trace[579947724] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"447.026031ms","start":"2026-05-28T13:27:32.608319Z","end":"2026-05-28T13:27:33.055345Z","steps":["trace[579947724] 'process raft request' (duration: 446.913729ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:33.055385Z","caller":"traceutil/trace.go:171","msg":"trace[1955602570] linearizableReadLoop","detail":"{readStateIndex:26415; appliedIndex:26411; }","duration":"408.044954ms","start":"2026-05-28T13:27:32.647316Z","end":"2026-05-28T13:27:33.055361Z","steps":["trace[1955602570] 'read index received' (duration: 253.004515ms)","trace[1955602570] 'applied index is now lower than readState.Index' (duration: 155.036759ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:33.0554Z","caller":"traceutil/trace.go:171","msg":"trace[773039029] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"448.0083ms","start":"2026-05-28T13:27:32.607357Z","end":"2026-05-28T13:27:33.055365Z","steps":["trace[773039029] 'process raft request' (duration: 447.834637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:33.055479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.60703Z","time spent":"448.388577ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:33.055479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.608284Z","time spent":"447.137033ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:33.05555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.607338Z","time spent":"448.117872ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:33.055578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.254608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-mw26f\" ","response":"range_response_count:1 size:4906"} {"level":"info","ts":"2026-05-28T13:27:33.055613Z","caller":"traceutil/trace.go:171","msg":"trace[805787290] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-mw26f; range_end:; response_count:1; response_revision:25574; }","duration":"408.290308ms","start":"2026-05-28T13:27:32.647311Z","end":"2026-05-28T13:27:33.055602Z","steps":["trace[805787290] 'agreement among raft nodes before linearized reading' (duration: 408.149246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:33.055633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.64727Z","time spent":"408.35773ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4929,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-mw26f\" "} {"level":"info","ts":"2026-05-28T13:27:33.055385Z","caller":"traceutil/trace.go:171","msg":"trace[1894815383] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"451.999124ms","start":"2026-05-28T13:27:32.603343Z","end":"2026-05-28T13:27:33.055342Z","steps":["trace[1894815383] 'process raft request' (duration: 297.025436ms)","trace[1894815383] 'compare' (duration: 154.337516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:33.055902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.603317Z","time spent":"452.481014ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:33.303015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.545292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:27:33.303228Z","caller":"traceutil/trace.go:171","msg":"trace[1235049135] linearizableReadLoop","detail":"{readStateIndex:26417; appliedIndex:26416; }","duration":"247.759348ms","start":"2026-05-28T13:27:33.055442Z","end":"2026-05-28T13:27:33.303201Z","steps":["trace[1235049135] 'read index received' (duration: 137.807329ms)","trace[1235049135] 'applied index is now lower than readState.Index' (duration: 109.950109ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:33.30324Z","caller":"traceutil/trace.go:171","msg":"trace[1384286947] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"374.184195ms","start":"2026-05-28T13:27:32.929019Z","end":"2026-05-28T13:27:33.303204Z","steps":["trace[1384286947] 'process raft request' (duration: 264.256946ms)","trace[1384286947] 'compare' (duration: 109.139174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:33.303353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.698164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-28T13:27:33.303359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.819006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-28T13:27:33.303388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.928991Z","time spent":"374.308757ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:33.303409Z","caller":"traceutil/trace.go:171","msg":"trace[1402201785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25575; }","duration":"528.817416ms","start":"2026-05-28T13:27:32.774579Z","end":"2026-05-28T13:27:33.303396Z","steps":["trace[1402201785] 'agreement among raft nodes before linearized reading' (duration: 528.674274ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:33.303416Z","caller":"traceutil/trace.go:171","msg":"trace[1518523989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25575; }","duration":"528.911988ms","start":"2026-05-28T13:27:32.774492Z","end":"2026-05-28T13:27:33.303404Z","steps":["trace[1518523989] 'agreement among raft nodes before linearized reading' (duration: 528.736264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:33.303437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.774545Z","time spent":"528.887427ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T13:27:33.303449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.774486Z","time spent":"528.956309ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T13:27:33.303353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.963289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-28T13:27:33.303508Z","caller":"traceutil/trace.go:171","msg":"trace[759660444] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25575; }","duration":"375.183413ms","start":"2026-05-28T13:27:32.928316Z","end":"2026-05-28T13:27:33.303499Z","steps":["trace[759660444] 'agreement among raft nodes before linearized reading' (duration: 374.929789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:33.303486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.489869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T13:27:33.303519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.703132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-28T13:27:33.303536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.562384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T13:27:33.303524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.928287Z","time spent":"375.232434ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-05-28T13:27:33.303574Z","caller":"traceutil/trace.go:171","msg":"trace[752321313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25575; }","duration":"529.782904ms","start":"2026-05-28T13:27:32.773773Z","end":"2026-05-28T13:27:33.303556Z","steps":["trace[752321313] 'agreement among raft nodes before linearized reading' (duration: 529.56886ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:33.30361Z","caller":"traceutil/trace.go:171","msg":"trace[1418781847] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25575; }","duration":"130.721857ms","start":"2026-05-28T13:27:33.172874Z","end":"2026-05-28T13:27:33.303596Z","steps":["trace[1418781847] 'agreement among raft nodes before linearized reading' (duration: 130.525283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:33.303626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.773759Z","time spent":"529.858515ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T13:27:33.303565Z","caller":"traceutil/trace.go:171","msg":"trace[529796522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25575; }","duration":"529.618071ms","start":"2026-05-28T13:27:32.77393Z","end":"2026-05-28T13:27:33.303548Z","steps":["trace[529796522] 'agreement among raft nodes before linearized reading' (duration: 529.371607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:33.30374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.773901Z","time spent":"529.810394ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T13:27:33.303898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.971657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-28T13:27:33.304027Z","caller":"traceutil/trace.go:171","msg":"trace[461156254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25575; }","duration":"530.130471ms","start":"2026-05-28T13:27:32.773882Z","end":"2026-05-28T13:27:33.304012Z","steps":["trace[461156254] 'agreement among raft nodes before linearized reading' (duration: 529.865426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:33.304121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:32.773855Z","time spent":"530.255432ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-28T13:27:44.839495Z","caller":"traceutil/trace.go:171","msg":"trace[1609288577] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"154.805685ms","start":"2026-05-28T13:27:44.684655Z","end":"2026-05-28T13:27:44.839461Z","steps":["trace[1609288577] 'process raft request' (duration: 154.674053ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:45.208649Z","caller":"traceutil/trace.go:171","msg":"trace[175196627] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"105.036728ms","start":"2026-05-28T13:27:45.10359Z","end":"2026-05-28T13:27:45.208627Z","steps":["trace[175196627] 'process raft request' (duration: 105.002037ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:45.208661Z","caller":"traceutil/trace.go:171","msg":"trace[734445373] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"107.551676ms","start":"2026-05-28T13:27:45.101074Z","end":"2026-05-28T13:27:45.208625Z","steps":["trace[734445373] 'process raft request' (duration: 107.26491ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:45.208702Z","caller":"traceutil/trace.go:171","msg":"trace[1003944139] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"106.523695ms","start":"2026-05-28T13:27:45.102141Z","end":"2026-05-28T13:27:45.208664Z","steps":["trace[1003944139] 'process raft request' (duration: 106.377723ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:45.208821Z","caller":"traceutil/trace.go:171","msg":"trace[464526291] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"106.102398ms","start":"2026-05-28T13:27:45.102683Z","end":"2026-05-28T13:27:45.208785Z","steps":["trace[464526291] 'process raft request' (duration: 105.869413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:45.398444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.614329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:45.398527Z","caller":"traceutil/trace.go:171","msg":"trace[2057033148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25675; }","duration":"111.746723ms","start":"2026-05-28T13:27:45.286763Z","end":"2026-05-28T13:27:45.398509Z","steps":["trace[2057033148] 'range keys from in-memory index tree' (duration: 111.526138ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:45.708828Z","caller":"traceutil/trace.go:171","msg":"trace[1514733822] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"305.782919ms","start":"2026-05-28T13:27:45.403022Z","end":"2026-05-28T13:27:45.708805Z","steps":["trace[1514733822] 'process raft request' (duration: 305.621536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:45.70901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:45.403003Z","time spent":"305.898021ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:45.831249Z","caller":"traceutil/trace.go:171","msg":"trace[236222664] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"427.748803ms","start":"2026-05-28T13:27:45.403462Z","end":"2026-05-28T13:27:45.831211Z","steps":["trace[236222664] 'process raft request' (duration: 426.252875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:45.831808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:45.403438Z","time spent":"428.17838ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:45.832281Z","caller":"traceutil/trace.go:171","msg":"trace[1283025522] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"428.510167ms","start":"2026-05-28T13:27:45.40373Z","end":"2026-05-28T13:27:45.83224Z","steps":["trace[1283025522] 'process raft request' (duration: 427.142422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:45.832681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:45.403722Z","time spent":"428.649449ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:45.833409Z","caller":"traceutil/trace.go:171","msg":"trace[1331666139] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"429.039616ms","start":"2026-05-28T13:27:45.404349Z","end":"2026-05-28T13:27:45.833389Z","steps":["trace[1331666139] 'process raft request' (duration: 428.652949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:45.833495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:45.404339Z","time spent":"429.117098ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T13:27:45.833429Z","caller":"traceutil/trace.go:171","msg":"trace[656888044] linearizableReadLoop","detail":"{readStateIndex:26524; appliedIndex:26519; }","duration":"347.736581ms","start":"2026-05-28T13:27:45.485669Z","end":"2026-05-28T13:27:45.833406Z","steps":["trace[656888044] 'read index received' (duration: 222.975996ms)","trace[656888044] 'applied index is now lower than readState.Index' (duration: 124.757955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:45.833947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.040367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:45.834125Z","caller":"traceutil/trace.go:171","msg":"trace[368970732] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25681; }","duration":"150.259601ms","start":"2026-05-28T13:27:45.683842Z","end":"2026-05-28T13:27:45.834102Z","steps":["trace[368970732] 'agreement among raft nodes before linearized reading' (duration: 149.918185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:45.83541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.716707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:987"} {"level":"info","ts":"2026-05-28T13:27:45.835504Z","caller":"traceutil/trace.go:171","msg":"trace[1174191147] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:25681; }","duration":"349.883102ms","start":"2026-05-28T13:27:45.485606Z","end":"2026-05-28T13:27:45.835489Z","steps":["trace[1174191147] 'agreement among raft nodes before linearized reading' (duration: 348.013706ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:45.836132Z","caller":"traceutil/trace.go:171","msg":"trace[1291103982] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"429.637588ms","start":"2026-05-28T13:27:45.403815Z","end":"2026-05-28T13:27:45.833452Z","steps":["trace[1291103982] 'process raft request' (duration: 429.061338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:27:45.836597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:45.403794Z","time spent":"432.408761ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T13:27:45.836769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T13:27:45.485579Z","time spent":"349.961582ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1010,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"info","ts":"2026-05-28T13:27:46.279016Z","caller":"traceutil/trace.go:171","msg":"trace[535195555] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"280.367435ms","start":"2026-05-28T13:27:45.998622Z","end":"2026-05-28T13:27:46.278989Z","steps":["trace[535195555] 'process raft request' (duration: 280.145041ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:47.359527Z","caller":"traceutil/trace.go:171","msg":"trace[940898216] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"144.84041ms","start":"2026-05-28T13:27:47.21467Z","end":"2026-05-28T13:27:47.35951Z","steps":["trace[940898216] 'process raft request' (duration: 144.667226ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:47.359761Z","caller":"traceutil/trace.go:171","msg":"trace[154196360] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"144.994843ms","start":"2026-05-28T13:27:47.21476Z","end":"2026-05-28T13:27:47.359754Z","steps":["trace[154196360] 'process raft request' (duration: 144.713157ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:47.360034Z","caller":"traceutil/trace.go:171","msg":"trace[1301324190] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"144.87498ms","start":"2026-05-28T13:27:47.215134Z","end":"2026-05-28T13:27:47.360009Z","steps":["trace[1301324190] 'process raft request' (duration: 144.595135ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:47.612298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22287} {"level":"info","ts":"2026-05-28T13:27:47.72339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22287,"took":"106.233859ms","hash":1093843884,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":43753472,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-05-28T13:27:47.723437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1093843884,"revision":22287,"compact-revision":19961} {"level":"info","ts":"2026-05-28T13:27:48.475523Z","caller":"traceutil/trace.go:171","msg":"trace[1132979004] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"152.559344ms","start":"2026-05-28T13:27:48.322914Z","end":"2026-05-28T13:27:48.475473Z","steps":["trace[1132979004] 'process raft request' (duration: 106.129378ms)","trace[1132979004] 'compare' (duration: 46.065449ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:49.319372Z","caller":"traceutil/trace.go:171","msg":"trace[1061328561] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"104.359365ms","start":"2026-05-28T13:27:49.214941Z","end":"2026-05-28T13:27:49.3193Z","steps":["trace[1061328561] 'process raft request' (duration: 103.827985ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:50.353851Z","caller":"traceutil/trace.go:171","msg":"trace[966540273] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"100.169546ms","start":"2026-05-28T13:27:50.253658Z","end":"2026-05-28T13:27:50.353827Z","steps":["trace[966540273] 'process raft request' (duration: 83.770761ms)","trace[966540273] 'compare' (duration: 16.284373ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:27:50.412423Z","caller":"traceutil/trace.go:171","msg":"trace[20662983] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"157.967383ms","start":"2026-05-28T13:27:50.254419Z","end":"2026-05-28T13:27:50.412386Z","steps":["trace[20662983] 'process raft request' (duration: 157.713509ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:50.412528Z","caller":"traceutil/trace.go:171","msg":"trace[1708599294] linearizableReadLoop","detail":"{readStateIndex:26560; appliedIndex:26556; }","duration":"126.890294ms","start":"2026-05-28T13:27:50.285612Z","end":"2026-05-28T13:27:50.412502Z","steps":["trace[1708599294] 'read index received' (duration: 51.909888ms)","trace[1708599294] 'applied index is now lower than readState.Index' (duration: 74.979586ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:27:50.41292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.737785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-05-28T13:27:50.412941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.320934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:27:50.412969Z","caller":"traceutil/trace.go:171","msg":"trace[430219447] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25716; }","duration":"124.834287ms","start":"2026-05-28T13:27:50.288123Z","end":"2026-05-28T13:27:50.412957Z","steps":["trace[430219447] 'agreement among raft nodes before linearized reading' (duration: 124.673713ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:50.412964Z","caller":"traceutil/trace.go:171","msg":"trace[1708020131] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"157.235131ms","start":"2026-05-28T13:27:50.2557Z","end":"2026-05-28T13:27:50.412935Z","steps":["trace[1708020131] 'process raft request' (duration: 156.590489ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:50.413007Z","caller":"traceutil/trace.go:171","msg":"trace[1195969342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25716; }","duration":"127.443186ms","start":"2026-05-28T13:27:50.285549Z","end":"2026-05-28T13:27:50.412992Z","steps":["trace[1195969342] 'agreement among raft nodes before linearized reading' (duration: 127.326594ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:50.41321Z","caller":"traceutil/trace.go:171","msg":"trace[2061739758] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"144.171288ms","start":"2026-05-28T13:27:50.269002Z","end":"2026-05-28T13:27:50.413173Z","steps":["trace[2061739758] 'process raft request' (duration: 143.358452ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:27:55.452993Z","caller":"traceutil/trace.go:171","msg":"trace[1053806492] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"108.797808ms","start":"2026-05-28T13:27:55.344176Z","end":"2026-05-28T13:27:55.452974Z","steps":["trace[1053806492] 'process raft request' (duration: 108.249327ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:08.620189Z","caller":"traceutil/trace.go:171","msg":"trace[1613060778] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"128.846611ms","start":"2026-05-28T13:28:08.491302Z","end":"2026-05-28T13:28:08.620149Z","steps":["trace[1613060778] 'process raft request' (duration: 128.672257ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:08.648623Z","caller":"traceutil/trace.go:171","msg":"trace[1472036480] linearizableReadLoop","detail":"{readStateIndex:26709; appliedIndex:26707; }","duration":"130.424459ms","start":"2026-05-28T13:28:08.518159Z","end":"2026-05-28T13:28:08.648584Z","steps":["trace[1472036480] 'read index received' (duration: 101.883972ms)","trace[1472036480] 'applied index is now lower than readState.Index' (duration: 28.538477ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:28:08.64871Z","caller":"traceutil/trace.go:171","msg":"trace[2087589749] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"147.776058ms","start":"2026-05-28T13:28:08.50088Z","end":"2026-05-28T13:28:08.648656Z","steps":["trace[2087589749] 'process raft request' (duration: 147.477112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:28:08.649007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.805146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"warn","ts":"2026-05-28T13:28:08.649072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.563352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:28:08.649168Z","caller":"traceutil/trace.go:171","msg":"trace[1839763608] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25859; }","duration":"104.728705ms","start":"2026-05-28T13:28:08.544416Z","end":"2026-05-28T13:28:08.649145Z","steps":["trace[1839763608] 'agreement among raft nodes before linearized reading' (duration: 104.542531ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:08.64917Z","caller":"traceutil/trace.go:171","msg":"trace[1998768754] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:25859; }","duration":"131.041341ms","start":"2026-05-28T13:28:08.518091Z","end":"2026-05-28T13:28:08.649132Z","steps":["trace[1998768754] 'agreement among raft nodes before linearized reading' (duration: 130.666804ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:13.765695Z","caller":"traceutil/trace.go:171","msg":"trace[389094896] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"191.965611ms","start":"2026-05-28T13:28:13.573707Z","end":"2026-05-28T13:28:13.765672Z","steps":["trace[389094896] 'process raft request' (duration: 191.89164ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:13.765703Z","caller":"traceutil/trace.go:171","msg":"trace[1318277104] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"192.015252ms","start":"2026-05-28T13:28:13.573662Z","end":"2026-05-28T13:28:13.765677Z","steps":["trace[1318277104] 'process raft request' (duration: 191.801098ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:13.765874Z","caller":"traceutil/trace.go:171","msg":"trace[1800595082] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"192.042823ms","start":"2026-05-28T13:28:13.573812Z","end":"2026-05-28T13:28:13.765855Z","steps":["trace[1800595082] 'process raft request' (duration: 191.827739ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:13.76606Z","caller":"traceutil/trace.go:171","msg":"trace[708146560] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"190.617175ms","start":"2026-05-28T13:28:13.575434Z","end":"2026-05-28T13:28:13.766052Z","steps":["trace[708146560] 'process raft request' (duration: 190.554524ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:13.766075Z","caller":"traceutil/trace.go:171","msg":"trace[1090935628] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"191.516022ms","start":"2026-05-28T13:28:13.574537Z","end":"2026-05-28T13:28:13.766053Z","steps":["trace[1090935628] 'process raft request' (duration: 191.268058ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:13.787091Z","caller":"traceutil/trace.go:171","msg":"trace[790810744] transaction","detail":"{read_only:false; response_revision:25895; number_of_response:1; }","duration":"132.159923ms","start":"2026-05-28T13:28:13.654902Z","end":"2026-05-28T13:28:13.787061Z","steps":["trace[790810744] 'process raft request' (duration: 132.029091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:28:20.062155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.520357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:28:20.062612Z","caller":"traceutil/trace.go:171","msg":"trace[1169456702] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"259.79026ms","start":"2026-05-28T13:28:19.802791Z","end":"2026-05-28T13:28:20.062581Z","steps":["trace[1169456702] 'process raft request' (duration: 259.586165ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.06265Z","caller":"traceutil/trace.go:171","msg":"trace[184016907] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"261.569205ms","start":"2026-05-28T13:28:19.801046Z","end":"2026-05-28T13:28:20.062615Z","steps":["trace[184016907] 'process raft request' (duration: 127.484266ms)","trace[184016907] 'compare' (duration: 133.271763ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:28:20.062649Z","caller":"traceutil/trace.go:171","msg":"trace[1728776732] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"259.722139ms","start":"2026-05-28T13:28:19.802897Z","end":"2026-05-28T13:28:20.062619Z","steps":["trace[1728776732] 'process raft request' (duration: 259.606387ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.062763Z","caller":"traceutil/trace.go:171","msg":"trace[357098395] linearizableReadLoop","detail":"{readStateIndex:26788; appliedIndex:26783; }","duration":"241.184409ms","start":"2026-05-28T13:28:19.821565Z","end":"2026-05-28T13:28:20.062749Z","steps":["trace[357098395] 'read index received' (duration: 4.034356ms)","trace[357098395] 'applied index is now lower than readState.Index' (duration: 237.148583ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:28:20.06283Z","caller":"traceutil/trace.go:171","msg":"trace[610316707] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"257.65697ms","start":"2026-05-28T13:28:19.805162Z","end":"2026-05-28T13:28:20.062819Z","steps":["trace[610316707] 'process raft request' (duration: 257.398685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:28:20.062913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.339823ms","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:6"} {"level":"info","ts":"2026-05-28T13:28:20.062943Z","caller":"traceutil/trace.go:171","msg":"trace[1644272855] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25937; }","duration":"241.410515ms","start":"2026-05-28T13:28:19.82152Z","end":"2026-05-28T13:28:20.062931Z","steps":["trace[1644272855] 'agreement among raft nodes before linearized reading' (duration: 241.324763ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.062903Z","caller":"traceutil/trace.go:171","msg":"trace[1371168234] transaction","detail":"{read_only:false; response_revision:25937; number_of_response:1; }","duration":"107.118402ms","start":"2026-05-28T13:28:19.955744Z","end":"2026-05-28T13:28:20.062863Z","steps":["trace[1371168234] 'process raft request' (duration: 106.960439ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.329105Z","caller":"traceutil/trace.go:171","msg":"trace[1231534908] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"259.819451ms","start":"2026-05-28T13:28:20.069261Z","end":"2026-05-28T13:28:20.329081Z","steps":["trace[1231534908] 'process raft request' (duration: 259.706339ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.329208Z","caller":"traceutil/trace.go:171","msg":"trace[943072128] transaction","detail":"{read_only:false; response_revision:25939; number_of_response:1; }","duration":"259.982954ms","start":"2026-05-28T13:28:20.069173Z","end":"2026-05-28T13:28:20.329156Z","steps":["trace[943072128] 'process raft request' (duration: 259.633008ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.329703Z","caller":"traceutil/trace.go:171","msg":"trace[105084304] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"260.337671ms","start":"2026-05-28T13:28:20.069273Z","end":"2026-05-28T13:28:20.329611Z","steps":["trace[105084304] 'process raft request' (duration: 259.76036ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.331257Z","caller":"traceutil/trace.go:171","msg":"trace[1886758272] transaction","detail":"{read_only:false; response_revision:25942; number_of_response:1; }","duration":"261.841178ms","start":"2026-05-28T13:28:20.069375Z","end":"2026-05-28T13:28:20.331216Z","steps":["trace[1886758272] 'process raft request' (duration: 260.117226ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.980306Z","caller":"traceutil/trace.go:171","msg":"trace[1287474317] linearizableReadLoop","detail":"{readStateIndex:26797; appliedIndex:26796; }","duration":"127.727728ms","start":"2026-05-28T13:28:20.852543Z","end":"2026-05-28T13:28:20.980271Z","steps":["trace[1287474317] 'read index received' (duration: 35.964427ms)","trace[1287474317] 'applied index is now lower than readState.Index' (duration: 91.761271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:28:20.980499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.918952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T13:28:20.980544Z","caller":"traceutil/trace.go:171","msg":"trace[1438435937] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25944; }","duration":"128.033754ms","start":"2026-05-28T13:28:20.8525Z","end":"2026-05-28T13:28:20.980534Z","steps":["trace[1438435937] 'agreement among raft nodes before linearized reading' (duration: 127.894221ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:20.991722Z","caller":"traceutil/trace.go:171","msg":"trace[159760401] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"111.427012ms","start":"2026-05-28T13:28:20.880245Z","end":"2026-05-28T13:28:20.991672Z","steps":["trace[159760401] 'process raft request' (duration: 111.204398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:28:21.2262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.757061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T13:28:21.226413Z","caller":"traceutil/trace.go:171","msg":"trace[187328023] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"100.654529ms","start":"2026-05-28T13:28:21.125742Z","end":"2026-05-28T13:28:21.226396Z","steps":["trace[187328023] 'process raft request' (duration: 100.579747ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:21.226615Z","caller":"traceutil/trace.go:171","msg":"trace[725153762] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"245.050472ms","start":"2026-05-28T13:28:20.981516Z","end":"2026-05-28T13:28:21.226567Z","steps":["trace[725153762] 'process raft request' (duration: 135.691309ms)","trace[725153762] 'compare' (duration: 108.614029ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:28:21.295973Z","caller":"traceutil/trace.go:171","msg":"trace[185284359] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"130.327899ms","start":"2026-05-28T13:28:21.165624Z","end":"2026-05-28T13:28:21.295952Z","steps":["trace[185284359] 'process raft request' (duration: 130.173106ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:21.443257Z","caller":"traceutil/trace.go:171","msg":"trace[1226770677] linearizableReadLoop","detail":"{readStateIndex:26802; appliedIndex:26801; }","duration":"147.313518ms","start":"2026-05-28T13:28:21.295911Z","end":"2026-05-28T13:28:21.443224Z","steps":["trace[1226770677] 'read index received' (duration: 130.776616ms)","trace[1226770677] 'applied index is now lower than readState.Index' (duration: 16.535142ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:28:21.44359Z","caller":"traceutil/trace.go:171","msg":"trace[1559962491] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"174.788487ms","start":"2026-05-28T13:28:21.26878Z","end":"2026-05-28T13:28:21.443569Z","steps":["trace[1559962491] 'process raft request' (duration: 157.923078ms)","trace[1559962491] 'compare' (duration: 16.141775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:28:21.444266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.289337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:28:21.444309Z","caller":"traceutil/trace.go:171","msg":"trace[406062249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25949; }","duration":"157.388159ms","start":"2026-05-28T13:28:21.28691Z","end":"2026-05-28T13:28:21.444298Z","steps":["trace[406062249] 'agreement among raft nodes before linearized reading' (duration: 156.627234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:28:21.444514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.964152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-28T13:28:21.444579Z","caller":"traceutil/trace.go:171","msg":"trace[404520230] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:25950; }","duration":"147.059384ms","start":"2026-05-28T13:28:21.297504Z","end":"2026-05-28T13:28:21.444563Z","steps":["trace[404520230] 'agreement among raft nodes before linearized reading' (duration: 146.8436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:28:21.688209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.22257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1115"} {"level":"info","ts":"2026-05-28T13:28:21.688285Z","caller":"traceutil/trace.go:171","msg":"trace[524639215] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:25950; }","duration":"109.336613ms","start":"2026-05-28T13:28:21.578931Z","end":"2026-05-28T13:28:21.688268Z","steps":["trace[524639215] 'range keys from in-memory index tree' (duration: 109.060118ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:21.930801Z","caller":"traceutil/trace.go:171","msg":"trace[1910179330] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"238.805464ms","start":"2026-05-28T13:28:21.691972Z","end":"2026-05-28T13:28:21.930778Z","steps":["trace[1910179330] 'process raft request' (duration: 229.927007ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:21.948235Z","caller":"traceutil/trace.go:171","msg":"trace[376086165] linearizableReadLoop","detail":"{readStateIndex:26806; appliedIndex:26804; }","duration":"111.621436ms","start":"2026-05-28T13:28:21.836597Z","end":"2026-05-28T13:28:21.948218Z","steps":["trace[376086165] 'read index received' (duration: 85.305179ms)","trace[376086165] 'applied index is now lower than readState.Index' (duration: 26.315037ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T13:28:21.948294Z","caller":"traceutil/trace.go:171","msg":"trace[13696352] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"117.6949ms","start":"2026-05-28T13:28:21.830562Z","end":"2026-05-28T13:28:21.948257Z","steps":["trace[13696352] 'process raft request' (duration: 117.551727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:28:21.948433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.82879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-zhz9q\" ","response":"range_response_count:1 size:3397"} {"level":"info","ts":"2026-05-28T13:28:21.948489Z","caller":"traceutil/trace.go:171","msg":"trace[1175072909] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-zhz9q; range_end:; response_count:1; response_revision:25953; }","duration":"111.934342ms","start":"2026-05-28T13:28:21.836546Z","end":"2026-05-28T13:28:21.94848Z","steps":["trace[1175072909] 'agreement among raft nodes before linearized reading' (duration: 111.759119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T13:28:45.452247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.235865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:28:45.452361Z","caller":"traceutil/trace.go:171","msg":"trace[1507712556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26277; }","duration":"166.393669ms","start":"2026-05-28T13:28:45.285942Z","end":"2026-05-28T13:28:45.452336Z","steps":["trace[1507712556] 'range keys from in-memory index tree' (duration: 166.149423ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:45.565972Z","caller":"traceutil/trace.go:171","msg":"trace[537453257] linearizableReadLoop","detail":"{readStateIndex:27137; appliedIndex:27136; }","duration":"111.567023ms","start":"2026-05-28T13:28:45.454381Z","end":"2026-05-28T13:28:45.565949Z","steps":["trace[537453257] 'read index received' (duration: 111.330879ms)","trace[537453257] 'applied index is now lower than readState.Index' (duration: 235.284µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T13:28:45.566125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.744678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T13:28:45.566157Z","caller":"traceutil/trace.go:171","msg":"trace[1938072826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26278; }","duration":"111.83483ms","start":"2026-05-28T13:28:45.454313Z","end":"2026-05-28T13:28:45.566147Z","steps":["trace[1938072826] 'agreement among raft nodes before linearized reading' (duration: 111.731927ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:28:45.566295Z","caller":"traceutil/trace.go:171","msg":"trace[234912598] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"180.101718ms","start":"2026-05-28T13:28:45.386156Z","end":"2026-05-28T13:28:45.566258Z","steps":["trace[234912598] 'process raft request' (duration: 179.628008ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T13:29:34.33487Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ba7.wal"} {"level":"info","ts":"2026-05-28T13:32:47.618843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25689} {"level":"info","ts":"2026-05-28T13:32:47.776621Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25689,"took":"154.086812ms","hash":2509784429,"current-db-size-bytes":59805696,"current-db-size":"60 MB","current-db-size-in-use-bytes":35946496,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-28T13:32:47.776707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2509784429,"revision":25689,"compact-revision":22287} {"level":"info","ts":"2026-05-28T13:33:53.461741Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T13:33:53.467241Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-28T13:33:53.46732Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-28T13:37:47.630087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28581} {"level":"info","ts":"2026-05-28T13:37:47.772391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28581,"took":"137.556418ms","hash":3175392239,"current-db-size-bytes":59805696,"current-db-size":"60 MB","current-db-size-in-use-bytes":31678464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T13:37:47.772474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3175392239,"revision":28581,"compact-revision":25689} {"level":"info","ts":"2026-05-28T13:42:47.63543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30811} {"level":"info","ts":"2026-05-28T13:42:47.761591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30811,"took":"122.4314ms","hash":443634657,"current-db-size-bytes":59805696,"current-db-size":"60 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T13:42:47.761659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":443634657,"revision":30811,"compact-revision":28581} {"level":"info","ts":"2026-05-28T13:47:47.642708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33075} {"level":"info","ts":"2026-05-28T13:47:47.805472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33075,"took":"157.865476ms","hash":2387679192,"current-db-size-bytes":59805696,"current-db-size":"60 MB","current-db-size-in-use-bytes":32169984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T13:47:47.805739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2387679192,"revision":33075,"compact-revision":30811} {"level":"info","ts":"2026-05-28T13:52:47.648366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35366} {"level":"info","ts":"2026-05-28T13:52:47.792906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35366,"took":"140.261612ms","hash":1759909049,"current-db-size-bytes":60276736,"current-db-size":"60 MB","current-db-size-in-use-bytes":31473664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T13:52:47.792978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1759909049,"revision":35366,"compact-revision":33075} {"level":"info","ts":"2026-05-28T13:55:20.369263Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T13:55:20.37463Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-28T13:55:20.374733Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-28T13:57:47.655828Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37591} {"level":"info","ts":"2026-05-28T13:57:47.793847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37591,"took":"134.622559ms","hash":589818797,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":32251904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T13:57:47.793906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":589818797,"revision":37591,"compact-revision":35366}