{"level":"warn","ts":"2026-06-02T17:27:58.561577Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T17:27:58.561675Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-06-02T17:27:58.561741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T17:27:58.561753Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-06-02T17:27:58.561774Z","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-06-02T17:27:58.562439Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-06-02T17:27:58.562527Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-06-02T17:27:58.564984Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.223514ms"} {"level":"info","ts":"2026-06-02T17:27:58.570994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-06-02T17:27:58.571238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T17:27:58.57127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-06-02T17:27:58.5713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T17:27:58.571317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-06-02T17:27:58.571359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-06-02T17:27:58.576466Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T17:27:58.579246Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T17:27:58.580628Z","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-06-02T17:27:58.582814Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T17:27:58.582986Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T17:27:58.583135Z","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-06-02T17:27:58.583301Z","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-06-02T17:27:58.583331Z","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-06-02T17:27:58.583899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-06-02T17:27:58.584069Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-06-02T17:27:58.585938Z","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-06-02T17:27:58.586075Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-06-02T17:27:58.586152Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T17:27:58.586175Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-06-02T17:27:58.586199Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T17:27:58.872557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T17:27:58.872611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T17:27:58.872636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-06-02T17:27:58.872647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-06-02T17:27:58.872652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-06-02T17:27:58.872661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-06-02T17:27:58.872667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-06-02T17:27:58.874826Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:27:58.878127Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T17:27:58.878296Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T17:27:58.878431Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T17:27:58.880077Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:27:58.880148Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:27:58.880177Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:27:58.880199Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T17:27:58.880206Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T17:27:58.882479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-06-02T17:27:58.882623Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-02T17:28:17.874967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.20997ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T17:28:35.665086Z","caller":"traceutil/trace.go:171","msg":"trace[468461228] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"127.663946ms","start":"2026-06-02T17:28:35.537393Z","end":"2026-06-02T17:28:35.665057Z","steps":["trace[468461228] 'process raft request' (duration: 127.358948ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:35.670035Z","caller":"traceutil/trace.go:171","msg":"trace[1849522352] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"129.003148ms","start":"2026-06-02T17:28:35.54099Z","end":"2026-06-02T17:28:35.669993Z","steps":["trace[1849522352] 'process raft request' (duration: 128.773912ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:35.670365Z","caller":"traceutil/trace.go:171","msg":"trace[1335108590] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"120.309958ms","start":"2026-06-02T17:28:35.55004Z","end":"2026-06-02T17:28:35.67035Z","steps":["trace[1335108590] 'process raft request' (duration: 119.944939ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:35.670481Z","caller":"traceutil/trace.go:171","msg":"trace[1510694514] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"129.459559ms","start":"2026-06-02T17:28:35.541002Z","end":"2026-06-02T17:28:35.670461Z","steps":["trace[1510694514] 'process raft request' (duration: 128.870835ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:35.670529Z","caller":"traceutil/trace.go:171","msg":"trace[1304159617] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"119.315564ms","start":"2026-06-02T17:28:35.551206Z","end":"2026-06-02T17:28:35.670522Z","steps":["trace[1304159617] 'process raft request' (duration: 118.911625ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:35.670497Z","caller":"traceutil/trace.go:171","msg":"trace[1262519069] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"123.120916ms","start":"2026-06-02T17:28:35.54736Z","end":"2026-06-02T17:28:35.670481Z","steps":["trace[1262519069] 'process raft request' (duration: 122.581483ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:35.670644Z","caller":"traceutil/trace.go:171","msg":"trace[165949372] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"120.235796ms","start":"2026-06-02T17:28:35.550401Z","end":"2026-06-02T17:28:35.670636Z","steps":["trace[165949372] 'process raft request' (duration: 119.625161ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:35.670662Z","caller":"traceutil/trace.go:171","msg":"trace[888328485] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"119.510438ms","start":"2026-06-02T17:28:35.55113Z","end":"2026-06-02T17:28:35.67064Z","steps":["trace[888328485] 'process raft request' (duration: 118.950655ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:42.162392Z","caller":"traceutil/trace.go:171","msg":"trace[213572309] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"323.479794ms","start":"2026-06-02T17:28:41.838886Z","end":"2026-06-02T17:28:42.162366Z","steps":["trace[213572309] 'process raft request' (duration: 323.229978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:42.162897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:28:41.838855Z","time spent":"323.630568ms","remote":"127.0.0.1:41288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T17:28:42.677516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.131511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:28:42.677799Z","caller":"traceutil/trace.go:171","msg":"trace[476088322] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"475.761262ms","start":"2026-06-02T17:28:42.202Z","end":"2026-06-02T17:28:42.677761Z","steps":["trace[476088322] 'process raft request' (duration: 319.214301ms)","trace[476088322] 'compare' (duration: 155.996688ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:28:42.677976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:28:42.201958Z","time spent":"475.932146ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T17:28:42.678428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.70411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-02T17:28:42.678412Z","caller":"traceutil/trace.go:171","msg":"trace[811480544] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"350.552355ms","start":"2026-06-02T17:28:42.327724Z","end":"2026-06-02T17:28:42.678276Z","steps":["trace[811480544] 'read index received' (duration: 193.43322ms)","trace[811480544] 'applied index is now lower than readState.Index' (duration: 157.103825ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:28:42.678491Z","caller":"traceutil/trace.go:171","msg":"trace[611323873] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:636; }","duration":"350.804421ms","start":"2026-06-02T17:28:42.327668Z","end":"2026-06-02T17:28:42.678473Z","steps":["trace[611323873] 'agreement among raft nodes before linearized reading' (duration: 350.696639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:42.678726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:28:42.327645Z","time spent":"351.035987ms","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"warn","ts":"2026-06-02T17:28:42.678776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.474454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:28:42.679049Z","caller":"traceutil/trace.go:171","msg":"trace[804420211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"267.7718ms","start":"2026-06-02T17:28:42.411253Z","end":"2026-06-02T17:28:42.679024Z","steps":["trace[804420211] 'agreement among raft nodes before linearized reading' (duration: 267.463663ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:30:16.503294Z","caller":"traceutil/trace.go:171","msg":"trace[1388456925] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"248.83427ms","start":"2026-06-02T17:30:16.25442Z","end":"2026-06-02T17:30:16.503254Z","steps":["trace[1388456925] 'process raft request' (duration: 205.827554ms)","trace[1388456925] 'compare' (duration: 42.842262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:30:16.850614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.719874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T17:30:16.850662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.878809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-94f69fb7-1ec0-41c3-bcd7-4086a99daa05\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-06-02T17:30:16.850703Z","caller":"traceutil/trace.go:171","msg":"trace[1610135480] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1879; }","duration":"133.871228ms","start":"2026-06-02T17:30:16.716812Z","end":"2026-06-02T17:30:16.850683Z","steps":["trace[1610135480] 'count revisions from in-memory index tree' (duration: 133.54267ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:30:16.850741Z","caller":"traceutil/trace.go:171","msg":"trace[584349838] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-94f69fb7-1ec0-41c3-bcd7-4086a99daa05; range_end:; response_count:1; response_revision:1879; }","duration":"122.027633ms","start":"2026-06-02T17:30:16.728694Z","end":"2026-06-02T17:30:16.850722Z","steps":["trace[584349838] 'range keys from in-memory index tree' (duration: 121.676654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:31:01.622919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.646948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:31:01.623036Z","caller":"traceutil/trace.go:171","msg":"trace[1893197192] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2251; }","duration":"124.96855ms","start":"2026-06-02T17:31:01.49805Z","end":"2026-06-02T17:31:01.623019Z","steps":["trace[1893197192] 'read index received' (duration: 11.049526ms)","trace[1893197192] 'applied index is now lower than readState.Index' (duration: 113.917664ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:31:01.623096Z","caller":"traceutil/trace.go:171","msg":"trace[55012507] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"166.191242ms","start":"2026-06-02T17:31:01.456878Z","end":"2026-06-02T17:31:01.62307Z","steps":["trace[55012507] 'process raft request' (duration: 52.308109ms)","trace[55012507] 'compare' (duration: 113.535765ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:31:01.623283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.229816ms","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-06-02T17:31:01.623327Z","caller":"traceutil/trace.go:171","msg":"trace[659059582] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2170; }","duration":"125.316458ms","start":"2026-06-02T17:31:01.497997Z","end":"2026-06-02T17:31:01.623314Z","steps":["trace[659059582] 'agreement among raft nodes before linearized reading' (duration: 125.100503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:31:07.590366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.147564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-06-02T17:31:07.590446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.172489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T17:31:07.590458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.646267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:31:07.590505Z","caller":"traceutil/trace.go:171","msg":"trace[1937672295] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2202; }","duration":"121.294822ms","start":"2026-06-02T17:31:07.469194Z","end":"2026-06-02T17:31:07.590489Z","steps":["trace[1937672295] 'count revisions from in-memory index tree' (duration: 121.067876ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:31:07.590503Z","caller":"traceutil/trace.go:171","msg":"trace[1265483175] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2202; }","duration":"142.354308ms","start":"2026-06-02T17:31:07.448119Z","end":"2026-06-02T17:31:07.590473Z","steps":["trace[1265483175] 'range keys from in-memory index tree' (duration: 141.885788ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:31:07.590568Z","caller":"traceutil/trace.go:171","msg":"trace[816943499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2202; }","duration":"179.807951ms","start":"2026-06-02T17:31:07.410729Z","end":"2026-06-02T17:31:07.590537Z","steps":["trace[816943499] 'range keys from in-memory index tree' (duration: 179.558915ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:31:07.716613Z","caller":"traceutil/trace.go:171","msg":"trace[181113908] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"121.483155ms","start":"2026-06-02T17:31:07.595097Z","end":"2026-06-02T17:31:07.71658Z","steps":["trace[181113908] 'process raft request' (duration: 121.29495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:31:07.948734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.113951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:31:07.949044Z","caller":"traceutil/trace.go:171","msg":"trace[89692928] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"222.56423ms","start":"2026-06-02T17:31:07.726461Z","end":"2026-06-02T17:31:07.949025Z","steps":["trace[89692928] 'process raft request' (duration: 222.404737ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:31:07.949035Z","caller":"traceutil/trace.go:171","msg":"trace[89058547] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"222.54123ms","start":"2026-06-02T17:31:07.72646Z","end":"2026-06-02T17:31:07.949001Z","steps":["trace[89058547] 'process raft request' (duration: 115.024199ms)","trace[89058547] 'compare' (duration: 106.977708ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:31:07.949266Z","caller":"traceutil/trace.go:171","msg":"trace[1877525265] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"222.486028ms","start":"2026-06-02T17:31:07.726755Z","end":"2026-06-02T17:31:07.949241Z","steps":["trace[1877525265] 'process raft request' (duration: 222.18385ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:31:07.949466Z","caller":"traceutil/trace.go:171","msg":"trace[376294221] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"222.638522ms","start":"2026-06-02T17:31:07.726817Z","end":"2026-06-02T17:31:07.949455Z","steps":["trace[376294221] 'process raft request' (duration: 222.341645ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:31:07.952404Z","caller":"traceutil/trace.go:171","msg":"trace[451878235] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"144.586583ms","start":"2026-06-02T17:31:07.807802Z","end":"2026-06-02T17:31:07.952389Z","steps":["trace[451878235] 'process raft request' (duration: 144.486911ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:32:02.983851Z","caller":"traceutil/trace.go:171","msg":"trace[1232069189] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"191.683043ms","start":"2026-06-02T17:32:02.792141Z","end":"2026-06-02T17:32:02.983824Z","steps":["trace[1232069189] 'process raft request' (duration: 191.522058ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:32:36.610707Z","caller":"traceutil/trace.go:171","msg":"trace[2145970257] linearizableReadLoop","detail":"{readStateIndex:2986; appliedIndex:2984; }","duration":"121.670581ms","start":"2026-06-02T17:32:36.489013Z","end":"2026-06-02T17:32:36.610683Z","steps":["trace[2145970257] 'read index received' (duration: 79.054138ms)","trace[2145970257] 'applied index is now lower than readState.Index' (duration: 42.615013ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:32:36.610725Z","caller":"traceutil/trace.go:171","msg":"trace[210952524] transaction","detail":"{read_only:false; response_revision:2875; number_of_response:1; }","duration":"198.966337ms","start":"2026-06-02T17:32:36.411704Z","end":"2026-06-02T17:32:36.61067Z","steps":["trace[210952524] 'process raft request' (duration: 156.385055ms)","trace[210952524] 'compare' (duration: 42.374447ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:32:36.610764Z","caller":"traceutil/trace.go:171","msg":"trace[1862571654] transaction","detail":"{read_only:false; response_revision:2876; number_of_response:1; }","duration":"150.79336ms","start":"2026-06-02T17:32:36.459932Z","end":"2026-06-02T17:32:36.610726Z","steps":["trace[1862571654] 'process raft request' (duration: 150.670208ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:32:36.610906Z","caller":"traceutil/trace.go:171","msg":"trace[2145566120] transaction","detail":"{read_only:false; response_revision:2877; number_of_response:1; }","duration":"117.107522ms","start":"2026-06-02T17:32:36.493787Z","end":"2026-06-02T17:32:36.610895Z","steps":["trace[2145566120] 'process raft request' (duration: 116.867017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:32:36.611117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.421744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T17:32:36.61118Z","caller":"traceutil/trace.go:171","msg":"trace[2125437547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2877; }","duration":"198.572398ms","start":"2026-06-02T17:32:36.412593Z","end":"2026-06-02T17:32:36.611165Z","steps":["trace[2125437547] 'agreement among raft nodes before linearized reading' (duration: 198.186839ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:31.84511Z","caller":"traceutil/trace.go:171","msg":"trace[618049735] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"132.726064ms","start":"2026-06-02T17:36:31.712356Z","end":"2026-06-02T17:36:31.845082Z","steps":["trace[618049735] 'process raft request' (duration: 128.511264ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:31.852461Z","caller":"traceutil/trace.go:171","msg":"trace[909536834] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4962; }","duration":"118.062122ms","start":"2026-06-02T17:36:31.734383Z","end":"2026-06-02T17:36:31.852445Z","steps":["trace[909536834] 'read index received' (duration: 106.521876ms)","trace[909536834] 'applied index is now lower than readState.Index' (duration: 11.539136ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:31.852574Z","caller":"traceutil/trace.go:171","msg":"trace[544286463] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"138.983235ms","start":"2026-06-02T17:36:31.71358Z","end":"2026-06-02T17:36:31.852563Z","steps":["trace[544286463] 'process raft request' (duration: 138.76461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:31.852859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.461102ms","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-06-02T17:36:31.852981Z","caller":"traceutil/trace.go:171","msg":"trace[1844702753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4780; }","duration":"118.633646ms","start":"2026-06-02T17:36:31.73431Z","end":"2026-06-02T17:36:31.852944Z","steps":["trace[1844702753] 'agreement among raft nodes before linearized reading' (duration: 118.37741ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:37.385133Z","caller":"traceutil/trace.go:171","msg":"trace[780730941] linearizableReadLoop","detail":"{readStateIndex:5137; appliedIndex:5136; }","duration":"144.235781ms","start":"2026-06-02T17:36:37.240862Z","end":"2026-06-02T17:36:37.385098Z","steps":["trace[780730941] 'read index received' (duration: 143.872783ms)","trace[780730941] 'applied index is now lower than readState.Index' (duration: 361.808µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:37.385219Z","caller":"traceutil/trace.go:171","msg":"trace[963447752] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"199.419545ms","start":"2026-06-02T17:36:37.185749Z","end":"2026-06-02T17:36:37.385168Z","steps":["trace[963447752] 'process raft request' (duration: 199.104687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:37.38539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.504637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-06-02T17:36:37.385621Z","caller":"traceutil/trace.go:171","msg":"trace[1644531359] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4949; }","duration":"144.788474ms","start":"2026-06-02T17:36:37.240815Z","end":"2026-06-02T17:36:37.385603Z","steps":["trace[1644531359] 'agreement among raft nodes before linearized reading' (duration: 144.412665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:41.294753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.205258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:36:41.294828Z","caller":"traceutil/trace.go:171","msg":"trace[1683971264] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5086; }","duration":"156.364411ms","start":"2026-06-02T17:36:41.138447Z","end":"2026-06-02T17:36:41.294811Z","steps":["trace[1683971264] 'range keys from in-memory index tree' (duration: 156.069864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:41.294993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.406931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2e674a6f-5772-4b16-a3fb-b508ea5a5c78\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-06-02T17:36:41.295286Z","caller":"traceutil/trace.go:171","msg":"trace[554002098] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2e674a6f-5772-4b16-a3fb-b508ea5a5c78; range_end:; response_count:1; response_revision:5086; }","duration":"143.636697ms","start":"2026-06-02T17:36:41.151491Z","end":"2026-06-02T17:36:41.295128Z","steps":["trace[554002098] 'range keys from in-memory index tree' (duration: 143.149015ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:41.72247Z","caller":"traceutil/trace.go:171","msg":"trace[250970561] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"103.382691ms","start":"2026-06-02T17:36:41.619062Z","end":"2026-06-02T17:36:41.722445Z","steps":["trace[250970561] 'process raft request' (duration: 99.852266ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:55.70792Z","caller":"traceutil/trace.go:171","msg":"trace[186875694] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"110.585443ms","start":"2026-06-02T17:36:55.597309Z","end":"2026-06-02T17:36:55.707894Z","steps":["trace[186875694] 'process raft request' (duration: 110.525622ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:55.708009Z","caller":"traceutil/trace.go:171","msg":"trace[799377919] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"111.29506ms","start":"2026-06-02T17:36:55.59667Z","end":"2026-06-02T17:36:55.707965Z","steps":["trace[799377919] 'process raft request' (duration: 110.976662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:56.506919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.495248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:36:56.507294Z","caller":"traceutil/trace.go:171","msg":"trace[1707266468] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"552.393193ms","start":"2026-06-02T17:36:55.954732Z","end":"2026-06-02T17:36:56.507125Z","steps":["trace[1707266468] 'process raft request' (duration: 299.596748ms)","trace[1707266468] 'compare' (duration: 252.184021ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:36:56.507432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:36:55.954718Z","time spent":"552.67192ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8039,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T17:36:56.529842Z","caller":"traceutil/trace.go:171","msg":"trace[1479527181] linearizableReadLoop","detail":"{readStateIndex:5579; appliedIndex:5577; }","duration":"536.408191ms","start":"2026-06-02T17:36:55.993409Z","end":"2026-06-02T17:36:56.529817Z","steps":["trace[1479527181] 'read index received' (duration: 260.935612ms)","trace[1479527181] 'applied index is now lower than readState.Index' (duration: 275.471339ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:56.530007Z","caller":"traceutil/trace.go:171","msg":"trace[695293551] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"563.982821ms","start":"2026-06-02T17:36:55.966001Z","end":"2026-06-02T17:36:56.529984Z","steps":["trace[695293551] 'process raft request' (duration: 563.683114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:56.530526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:36:55.965969Z","time spent":"564.471373ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2585,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T17:36:56.530255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.830351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T17:36:56.530918Z","caller":"traceutil/trace.go:171","msg":"trace[1056085813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5378; }","duration":"537.541627ms","start":"2026-06-02T17:36:55.993336Z","end":"2026-06-02T17:36:56.530878Z","steps":["trace[1056085813] 'agreement among raft nodes before linearized reading' (duration: 536.676787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:56.531431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:36:55.993302Z","time spent":"538.108541ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T17:36:56.54258Z","caller":"traceutil/trace.go:171","msg":"trace[592514226] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"232.294034ms","start":"2026-06-02T17:36:56.310264Z","end":"2026-06-02T17:36:56.542558Z","steps":["trace[592514226] 'process raft request' (duration: 232.100429ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:56.542871Z","caller":"traceutil/trace.go:171","msg":"trace[728611546] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"153.626757ms","start":"2026-06-02T17:36:56.389232Z","end":"2026-06-02T17:36:56.542859Z","steps":["trace[728611546] 'process raft request' (duration: 153.284438ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:56.542943Z","caller":"traceutil/trace.go:171","msg":"trace[1727930956] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"141.320971ms","start":"2026-06-02T17:36:56.401594Z","end":"2026-06-02T17:36:56.542915Z","steps":["trace[1727930956] 'process raft request' (duration: 141.119715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:56.543468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.282305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T17:36:56.543518Z","caller":"traceutil/trace.go:171","msg":"trace[818816433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5385; }","duration":"548.352507ms","start":"2026-06-02T17:36:55.995153Z","end":"2026-06-02T17:36:56.543506Z","steps":["trace[818816433] 'agreement among raft nodes before linearized reading' (duration: 548.149912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:56.543469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.992157ms","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-06-02T17:36:56.543584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:36:55.995124Z","time spent":"548.443159ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T17:36:56.543647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.838605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8thdt\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-06-02T17:36:56.543684Z","caller":"traceutil/trace.go:171","msg":"trace[1325705830] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8thdt; range_end:; response_count:1; response_revision:5385; }","duration":"174.900847ms","start":"2026-06-02T17:36:56.368771Z","end":"2026-06-02T17:36:56.543672Z","steps":["trace[1325705830] 'agreement among raft nodes before linearized reading' (duration: 174.817405ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:56.543666Z","caller":"traceutil/trace.go:171","msg":"trace[377876348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5385; }","duration":"550.204342ms","start":"2026-06-02T17:36:55.993404Z","end":"2026-06-02T17:36:56.543609Z","steps":["trace[377876348] 'agreement among raft nodes before linearized reading' (duration: 549.983217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:56.543782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:36:55.993358Z","time spent":"550.412027ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T17:36:56.543471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.575096ms","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-06-02T17:36:56.543904Z","caller":"traceutil/trace.go:171","msg":"trace[569605478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5385; }","duration":"550.016917ms","start":"2026-06-02T17:36:55.993869Z","end":"2026-06-02T17:36:56.543885Z","steps":["trace[569605478] 'agreement among raft nodes before linearized reading' (duration: 549.514115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:56.543975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:36:55.993863Z","time spent":"550.086828ms","remote":"127.0.0.1:41070","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-06-02T17:37:59.259782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-06-02T17:37:59.335984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"73.806508ms","hash":4052351934,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":33148928,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-02T17:37:59.336043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4052351934,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-06-02T17:42:59.267212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6052} {"level":"info","ts":"2026-06-02T17:42:59.366516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6052,"took":"96.283604ms","hash":1115317929,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":21749760,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-02T17:42:59.366603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1115317929,"revision":6052,"compact-revision":3000} {"level":"info","ts":"2026-06-02T17:43:16.876236Z","caller":"traceutil/trace.go:171","msg":"trace[1932422348] linearizableReadLoop","detail":"{readStateIndex:8689; appliedIndex:8688; }","duration":"101.60353ms","start":"2026-06-02T17:43:16.774603Z","end":"2026-06-02T17:43:16.876207Z","steps":["trace[1932422348] 'read index received' (duration: 101.427156ms)","trace[1932422348] 'applied index is now lower than readState.Index' (duration: 174.914µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:43:16.876294Z","caller":"traceutil/trace.go:171","msg":"trace[15180567] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"126.016812ms","start":"2026-06-02T17:43:16.750243Z","end":"2026-06-02T17:43:16.87626Z","steps":["trace[15180567] 'process raft request' (duration: 125.764416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:43:16.876468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.845056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8thdt\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-06-02T17:43:16.876508Z","caller":"traceutil/trace.go:171","msg":"trace[240610884] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8thdt; range_end:; response_count:1; response_revision:8392; }","duration":"101.965279ms","start":"2026-06-02T17:43:16.774534Z","end":"2026-06-02T17:43:16.876499Z","steps":["trace[240610884] 'agreement among raft nodes before linearized reading' (duration: 101.786995ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:43:17.892369Z","caller":"traceutil/trace.go:171","msg":"trace[797241307] linearizableReadLoop","detail":"{readStateIndex:8691; appliedIndex:8690; }","duration":"144.445022ms","start":"2026-06-02T17:43:17.747883Z","end":"2026-06-02T17:43:17.892328Z","steps":["trace[797241307] 'read index received' (duration: 89.167584ms)","trace[797241307] 'applied index is now lower than readState.Index' (duration: 55.275218ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:43:17.892547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.611217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:43:17.892627Z","caller":"traceutil/trace.go:171","msg":"trace[1588543429] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8393; }","duration":"144.7777ms","start":"2026-06-02T17:43:17.747829Z","end":"2026-06-02T17:43:17.892607Z","steps":["trace[1588543429] 'agreement among raft nodes before linearized reading' (duration: 144.583985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:43:46.582388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.381277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:43:46.582647Z","caller":"traceutil/trace.go:171","msg":"trace[1333465526] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"406.172158ms","start":"2026-06-02T17:43:46.17645Z","end":"2026-06-02T17:43:46.582623Z","steps":["trace[1333465526] 'process raft request' (duration: 406.076646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:43:46.582758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:43:46.176415Z","time spent":"406.291431ms","remote":"127.0.0.1:41070","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-06-02T17:43:46.582911Z","caller":"traceutil/trace.go:171","msg":"trace[108482669] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"440.938886ms","start":"2026-06-02T17:43:46.141955Z","end":"2026-06-02T17:43:46.582894Z","steps":["trace[108482669] 'process raft request' (duration: 268.030337ms)","trace[108482669] 'compare' (duration: 171.791873ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:43:46.582988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:43:46.141937Z","time spent":"441.011218ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T17:44:00.422754Z","caller":"traceutil/trace.go:171","msg":"trace[889793192] linearizableReadLoop","detail":"{readStateIndex:9030; appliedIndex:9029; }","duration":"135.62234ms","start":"2026-06-02T17:44:00.287072Z","end":"2026-06-02T17:44:00.422694Z","steps":["trace[889793192] 'read index received' (duration: 135.355963ms)","trace[889793192] 'applied index is now lower than readState.Index' (duration: 265.237µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:44:00.423016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.934327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-06-02T17:44:00.423062Z","caller":"traceutil/trace.go:171","msg":"trace[597081928] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8719; }","duration":"136.0469ms","start":"2026-06-02T17:44:00.287001Z","end":"2026-06-02T17:44:00.423047Z","steps":["trace[597081928] 'agreement among raft nodes before linearized reading' (duration: 135.842565ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:44:00.423261Z","caller":"traceutil/trace.go:171","msg":"trace[473760916] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"176.411166ms","start":"2026-06-02T17:44:00.246722Z","end":"2026-06-02T17:44:00.423133Z","steps":["trace[473760916] 'process raft request' (duration: 175.778701ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:44:42.653794Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d9.wal"} {"level":"info","ts":"2026-06-02T17:45:42.708716Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T17:45:42.717974Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-02T17:45:42.718177Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-02T17:47:59.27338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8161} {"level":"info","ts":"2026-06-02T17:47:59.335176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8161,"took":"59.050434ms","hash":3185544210,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":29720576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T17:47:59.335253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3185544210,"revision":8161,"compact-revision":6052} {"level":"info","ts":"2026-06-02T17:48:56.863652Z","caller":"traceutil/trace.go:171","msg":"trace[964044561] linearizableReadLoop","detail":"{readStateIndex:11686; appliedIndex:11685; }","duration":"239.267783ms","start":"2026-06-02T17:48:56.624353Z","end":"2026-06-02T17:48:56.863621Z","steps":["trace[964044561] 'read index received' (duration: 239.008166ms)","trace[964044561] 'applied index is now lower than readState.Index' (duration: 258.107µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:48:56.863698Z","caller":"traceutil/trace.go:171","msg":"trace[2095418941] transaction","detail":"{read_only:false; response_revision:11300; number_of_response:1; }","duration":"242.48923ms","start":"2026-06-02T17:48:56.621173Z","end":"2026-06-02T17:48:56.863662Z","steps":["trace[2095418941] 'process raft request' (duration: 242.264564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:48:56.863952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.56826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T17:48:56.864055Z","caller":"traceutil/trace.go:171","msg":"trace[2016229899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11300; }","duration":"239.756075ms","start":"2026-06-02T17:48:56.624276Z","end":"2026-06-02T17:48:56.864032Z","steps":["trace[2016229899] 'agreement among raft nodes before linearized reading' (duration: 239.479808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:48:56.863983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.889805ms","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-06-02T17:48:56.864166Z","caller":"traceutil/trace.go:171","msg":"trace[1119446960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11300; }","duration":"239.141601ms","start":"2026-06-02T17:48:56.625014Z","end":"2026-06-02T17:48:56.864156Z","steps":["trace[1119446960] 'agreement among raft nodes before linearized reading' (duration: 238.799493ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:52:59.280104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10785} {"level":"info","ts":"2026-06-02T17:52:59.378182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10785,"took":"95.059432ms","hash":2391743980,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":27983872,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T17:52:59.378261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2391743980,"revision":10785,"compact-revision":8161} {"level":"info","ts":"2026-06-02T17:55:11.071751Z","caller":"traceutil/trace.go:171","msg":"trace[1641279634] transaction","detail":"{read_only:false; response_revision:13819; number_of_response:1; }","duration":"151.965749ms","start":"2026-06-02T17:55:10.919766Z","end":"2026-06-02T17:55:11.071732Z","steps":["trace[1641279634] 'process raft request' (duration: 151.748744ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:55:27.615457Z","caller":"traceutil/trace.go:171","msg":"trace[1644390137] linearizableReadLoop","detail":"{readStateIndex:14400; appliedIndex:14399; }","duration":"203.394072ms","start":"2026-06-02T17:55:27.412039Z","end":"2026-06-02T17:55:27.615433Z","steps":["trace[1644390137] 'read index received' (duration: 203.213847ms)","trace[1644390137] 'applied index is now lower than readState.Index' (duration: 179.355µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:55:27.615504Z","caller":"traceutil/trace.go:171","msg":"trace[1650722525] transaction","detail":"{read_only:false; response_revision:13922; number_of_response:1; }","duration":"231.500506ms","start":"2026-06-02T17:55:27.383977Z","end":"2026-06-02T17:55:27.615477Z","steps":["trace[1650722525] 'process raft request' (duration: 231.299471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:55:27.615665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.599636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:55:27.615694Z","caller":"traceutil/trace.go:171","msg":"trace[224146923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13922; }","duration":"203.669878ms","start":"2026-06-02T17:55:27.412019Z","end":"2026-06-02T17:55:27.615689Z","steps":["trace[224146923] 'agreement among raft nodes before linearized reading' (duration: 203.537975ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:55:27.821957Z","caller":"traceutil/trace.go:171","msg":"trace[520279025] transaction","detail":"{read_only:false; response_revision:13923; number_of_response:1; }","duration":"352.170773ms","start":"2026-06-02T17:55:27.469733Z","end":"2026-06-02T17:55:27.821904Z","steps":["trace[520279025] 'process raft request' (duration: 351.64113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:55:27.822286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:55:27.469693Z","time spent":"352.45979ms","remote":"127.0.0.1:41070","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-06-02T17:55:27.822265Z","caller":"traceutil/trace.go:171","msg":"trace[1333294443] transaction","detail":"{read_only:false; response_revision:13924; number_of_response:1; }","duration":"307.546632ms","start":"2026-06-02T17:55:27.514671Z","end":"2026-06-02T17:55:27.822217Z","steps":["trace[1333294443] 'process raft request' (duration: 306.908187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:55:27.822624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:55:27.51463Z","time spent":"307.86291ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T17:55:27.823753Z","caller":"traceutil/trace.go:171","msg":"trace[807003744] transaction","detail":"{read_only:false; response_revision:13925; number_of_response:1; }","duration":"216.492397ms","start":"2026-06-02T17:55:27.607197Z","end":"2026-06-02T17:55:27.823689Z","steps":["trace[807003744] 'process raft request' (duration: 216.124687ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:55:27.823841Z","caller":"traceutil/trace.go:171","msg":"trace[1298578463] linearizableReadLoop","detail":"{readStateIndex:14404; appliedIndex:14400; }","duration":"208.27658ms","start":"2026-06-02T17:55:27.61553Z","end":"2026-06-02T17:55:27.823807Z","steps":["trace[1298578463] 'read index received' (duration: 205.846471ms)","trace[1298578463] 'applied index is now lower than readState.Index' (duration: 2.428509ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:55:27.824302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.630621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-06-02T17:55:27.824318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.699755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:55:27.824368Z","caller":"traceutil/trace.go:171","msg":"trace[131083575] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13926; }","duration":"396.740884ms","start":"2026-06-02T17:55:27.42761Z","end":"2026-06-02T17:55:27.824351Z","steps":["trace[131083575] 'agreement among raft nodes before linearized reading' (duration: 396.530149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:55:27.824411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:55:27.427582Z","time spent":"396.822165ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-02T17:55:27.824436Z","caller":"traceutil/trace.go:171","msg":"trace[1686789654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13926; }","duration":"207.820788ms","start":"2026-06-02T17:55:27.616549Z","end":"2026-06-02T17:55:27.82437Z","steps":["trace[1686789654] 'agreement among raft nodes before linearized reading' (duration: 207.688895ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:55:49.958422Z","caller":"traceutil/trace.go:171","msg":"trace[1144929756] transaction","detail":"{read_only:false; response_revision:14233; number_of_response:1; }","duration":"258.411952ms","start":"2026-06-02T17:55:49.699974Z","end":"2026-06-02T17:55:49.958386Z","steps":["trace[1144929756] 'process raft request' (duration: 258.147406ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:57:59.286351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12862} {"level":"info","ts":"2026-06-02T17:57:59.367464Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12862,"took":"77.558885ms","hash":354360819,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30232576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T17:57:59.367868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":354360819,"revision":12862,"compact-revision":10785} {"level":"warn","ts":"2026-06-02T18:02:01.207103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311772221286592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T18:02:01.707292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311772221286592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T18:02:01.789732Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.108127169s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T18:02:01.790242Z","caller":"traceutil/trace.go:171","msg":"trace[347966] linearizableReadLoop","detail":"{readStateIndex:17817; appliedIndex:17816; }","duration":"1.084114912s","start":"2026-06-02T18:02:00.706097Z","end":"2026-06-02T18:02:01.790212Z","steps":["trace[347966] 'read index received' (duration: 1.083864526s)","trace[347966] 'applied index is now lower than readState.Index' (duration: 249.296µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:02:01.790423Z","caller":"traceutil/trace.go:171","msg":"trace[2082052067] transaction","detail":"{read_only:false; response_revision:17235; number_of_response:1; }","duration":"1.108910648s","start":"2026-06-02T18:02:00.681497Z","end":"2026-06-02T18:02:01.790408Z","steps":["trace[2082052067] 'process raft request' (duration: 1.108473908s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.790565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:00.681477Z","time spent":"1.108972519s","remote":"127.0.0.1:41070","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-06-02T18:02:01.790707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.292195ms","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-06-02T18:02:01.790785Z","caller":"traceutil/trace.go:171","msg":"trace[762799995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17235; }","duration":"772.428288ms","start":"2026-06-02T18:02:01.018331Z","end":"2026-06-02T18:02:01.790759Z","steps":["trace[762799995] 'agreement among raft nodes before linearized reading' (duration: 772.176702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.790847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.01831Z","time spent":"772.52815ms","remote":"127.0.0.1:41070","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-06-02T18:02:01.790982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.08279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:02:01.79103Z","caller":"traceutil/trace.go:171","msg":"trace[1511630669] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:17235; }","duration":"381.158232ms","start":"2026-06-02T18:02:01.40986Z","end":"2026-06-02T18:02:01.791019Z","steps":["trace[1511630669] 'agreement among raft nodes before linearized reading' (duration: 381.07111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.791055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.409845Z","time spent":"381.202983ms","remote":"127.0.0.1:52636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-06-02T18:02:01.791151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.902652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8thdt\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-02T18:02:01.791188Z","caller":"traceutil/trace.go:171","msg":"trace[1963722537] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8thdt; range_end:; response_count:1; response_revision:17235; }","duration":"404.981044ms","start":"2026-06-02T18:02:01.386196Z","end":"2026-06-02T18:02:01.791177Z","steps":["trace[1963722537] 'agreement among raft nodes before linearized reading' (duration: 404.859391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.791218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.386174Z","time spent":"405.036756ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-8thdt\" "} {"level":"warn","ts":"2026-06-02T18:02:01.791464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.967374ms","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-06-02T18:02:01.791502Z","caller":"traceutil/trace.go:171","msg":"trace[1789635212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17235; }","duration":"450.005705ms","start":"2026-06-02T18:02:01.341487Z","end":"2026-06-02T18:02:01.791493Z","steps":["trace[1789635212] 'agreement among raft nodes before linearized reading' (duration: 449.902132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.791547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.341373Z","time spent":"450.167279ms","remote":"127.0.0.1:41070","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-06-02T18:02:01.791749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085664578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kscsd\" ","response":"range_response_count:1 size:20763"} {"level":"warn","ts":"2026-06-02T18:02:01.791776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.231569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-8zj76\" ","response":"range_response_count:1 size:18671"} {"level":"info","ts":"2026-06-02T18:02:01.791815Z","caller":"traceutil/trace.go:171","msg":"trace[156546858] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-8zj76; range_end:; response_count:1; response_revision:17235; }","duration":"487.319042ms","start":"2026-06-02T18:02:01.304485Z","end":"2026-06-02T18:02:01.791804Z","steps":["trace[156546858] 'agreement among raft nodes before linearized reading' (duration: 487.185279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.791853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.30446Z","time spent":"487.386873ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18694,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-8zj76\" "} {"level":"warn","ts":"2026-06-02T18:02:01.791859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.610213ms","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-06-02T18:02:01.792046Z","caller":"traceutil/trace.go:171","msg":"trace[637147291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17235; }","duration":"361.810147ms","start":"2026-06-02T18:02:01.430219Z","end":"2026-06-02T18:02:01.79203Z","steps":["trace[637147291] 'agreement among raft nodes before linearized reading' (duration: 361.582402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.792074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.430205Z","time spent":"361.861029ms","remote":"127.0.0.1:41070","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":"warn","ts":"2026-06-02T18:02:01.792113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.310891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-vpbnf\" ","response":"range_response_count:1 size:18646"} {"level":"info","ts":"2026-06-02T18:02:01.792157Z","caller":"traceutil/trace.go:171","msg":"trace[443959673] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-vpbnf; range_end:; response_count:1; response_revision:17235; }","duration":"492.399233ms","start":"2026-06-02T18:02:01.299747Z","end":"2026-06-02T18:02:01.792146Z","steps":["trace[443959673] 'agreement among raft nodes before linearized reading' (duration: 492.231979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.792198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.299726Z","time spent":"492.465104ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18669,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-vpbnf\" "} {"level":"warn","ts":"2026-06-02T18:02:01.792235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.342178ms","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-06-02T18:02:01.792266Z","caller":"traceutil/trace.go:171","msg":"trace[622028627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17235; }","duration":"369.383169ms","start":"2026-06-02T18:02:01.422873Z","end":"2026-06-02T18:02:01.792256Z","steps":["trace[622028627] 'agreement among raft nodes before linearized reading' (duration: 369.303307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.792289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.422868Z","time spent":"369.416469ms","remote":"127.0.0.1:41070","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":"warn","ts":"2026-06-02T18:02:01.792439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.215571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-02T18:02:01.79245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.20769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-xnlcs\" ","response":"range_response_count:1 size:14277"} {"level":"info","ts":"2026-06-02T18:02:01.792465Z","caller":"traceutil/trace.go:171","msg":"trace[1119848463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17235; }","duration":"370.252381ms","start":"2026-06-02T18:02:01.422206Z","end":"2026-06-02T18:02:01.792458Z","steps":["trace[1119848463] 'agreement among raft nodes before linearized reading' (duration: 370.183539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.792485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.422198Z","time spent":"370.282812ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T18:02:01.792506Z","caller":"traceutil/trace.go:171","msg":"trace[752850530] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-xnlcs; range_end:; response_count:1; response_revision:17235; }","duration":"537.296212ms","start":"2026-06-02T18:02:01.25518Z","end":"2026-06-02T18:02:01.792477Z","steps":["trace[752850530] 'agreement among raft nodes before linearized reading' (duration: 537.152677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.792558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.255151Z","time spent":"537.394624ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14300,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-xnlcs\" "} {"level":"warn","ts":"2026-06-02T18:02:01.792588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.947452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:02:01.792611Z","caller":"traceutil/trace.go:171","msg":"trace[566565423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17235; }","duration":"382.004673ms","start":"2026-06-02T18:02:01.410597Z","end":"2026-06-02T18:02:01.792602Z","steps":["trace[566565423] 'agreement among raft nodes before linearized reading' (duration: 381.963132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.792633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.41058Z","time spent":"382.050124ms","remote":"127.0.0.1:40786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T18:02:01.79178Z","caller":"traceutil/trace.go:171","msg":"trace[873778993] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-kscsd; range_end:; response_count:1; response_revision:17235; }","duration":"1.08573754s","start":"2026-06-02T18:02:00.706034Z","end":"2026-06-02T18:02:01.791772Z","steps":["trace[873778993] 'agreement among raft nodes before linearized reading' (duration: 1.085602157s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.792812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:00.706011Z","time spent":"1.086789536s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20786,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kscsd\" "} {"level":"info","ts":"2026-06-02T18:02:59.304217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15324} {"level":"info","ts":"2026-06-02T18:02:59.402876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15324,"took":"94.94191ms","hash":4108570794,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T18:02:59.402938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4108570794,"revision":15324,"compact-revision":12862} {"level":"info","ts":"2026-06-02T18:03:04.388232Z","caller":"traceutil/trace.go:171","msg":"trace[75580707] linearizableReadLoop","detail":"{readStateIndex:18269; appliedIndex:18268; }","duration":"151.585439ms","start":"2026-06-02T18:03:04.236619Z","end":"2026-06-02T18:03:04.388204Z","steps":["trace[75580707] 'read index received' (duration: 151.308323ms)","trace[75580707] 'applied index is now lower than readState.Index' (duration: 276.106µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:03:04.388511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.870407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-06-02T18:03:04.388561Z","caller":"traceutil/trace.go:171","msg":"trace[1841564850] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:17670; }","duration":"151.964438ms","start":"2026-06-02T18:03:04.23658Z","end":"2026-06-02T18:03:04.388544Z","steps":["trace[1841564850] 'agreement among raft nodes before linearized reading' (duration: 151.741243ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:03:04.388926Z","caller":"traceutil/trace.go:171","msg":"trace[461672771] transaction","detail":"{read_only:false; response_revision:17670; number_of_response:1; }","duration":"154.013608ms","start":"2026-06-02T18:03:04.234899Z","end":"2026-06-02T18:03:04.388913Z","steps":["trace[461672771] 'process raft request' (duration: 153.143167ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:03:04.702099Z","caller":"traceutil/trace.go:171","msg":"trace[551227524] linearizableReadLoop","detail":"{readStateIndex:18270; appliedIndex:18269; }","duration":"153.959157ms","start":"2026-06-02T18:03:04.548116Z","end":"2026-06-02T18:03:04.702075Z","steps":["trace[551227524] 'read index received' (duration: 153.706591ms)","trace[551227524] 'applied index is now lower than readState.Index' (duration: 251.396µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:03:04.702165Z","caller":"traceutil/trace.go:171","msg":"trace[1807083581] transaction","detail":"{read_only:false; response_revision:17671; number_of_response:1; }","duration":"162.169755ms","start":"2026-06-02T18:03:04.539972Z","end":"2026-06-02T18:03:04.702141Z","steps":["trace[1807083581] 'process raft request' (duration: 161.922119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:03:04.702368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.209573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:03:04.702501Z","caller":"traceutil/trace.go:171","msg":"trace[7217906] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:17671; }","duration":"154.385147ms","start":"2026-06-02T18:03:04.548087Z","end":"2026-06-02T18:03:04.702472Z","steps":["trace[7217906] 'agreement among raft nodes before linearized reading' (duration: 154.09068ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:03:53.37829Z","caller":"traceutil/trace.go:171","msg":"trace[785650453] transaction","detail":"{read_only:false; response_revision:18287; number_of_response:1; }","duration":"104.427108ms","start":"2026-06-02T18:03:53.273827Z","end":"2026-06-02T18:03:53.378254Z","steps":["trace[785650453] 'process raft request' (duration: 10.272116ms)","trace[785650453] 'compare' (duration: 93.862785ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:03:53.378449Z","caller":"traceutil/trace.go:171","msg":"trace[1863489821] transaction","detail":"{read_only:false; response_revision:18288; number_of_response:1; }","duration":"102.510412ms","start":"2026-06-02T18:03:53.275875Z","end":"2026-06-02T18:03:53.378386Z","steps":["trace[1863489821] 'process raft request' (duration: 102.317847ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:06:22.021893Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T18:06:22.028752Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-02T18:06:22.028848Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-02T18:07:59.314699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17641} {"level":"info","ts":"2026-06-02T18:07:59.416938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17641,"took":"99.14998ms","hash":708674228,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":34787328,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-02T18:07:59.417031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":708674228,"revision":17641,"compact-revision":15324} {"level":"warn","ts":"2026-06-02T18:08:43.396583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.463084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-02T18:08:43.39669Z","caller":"traceutil/trace.go:171","msg":"trace[875963955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20360; }","duration":"116.621089ms","start":"2026-06-02T18:08:43.280043Z","end":"2026-06-02T18:08:43.396664Z","steps":["trace[875963955] 'range keys from in-memory index tree' (duration: 116.239999ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:08:43.539041Z","caller":"traceutil/trace.go:171","msg":"trace[1095338296] transaction","detail":"{read_only:false; response_revision:20361; number_of_response:1; }","duration":"136.895965ms","start":"2026-06-02T18:08:43.402111Z","end":"2026-06-02T18:08:43.539007Z","steps":["trace[1095338296] 'process raft request' (duration: 136.633039ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:08:59.735701Z","caller":"traceutil/trace.go:171","msg":"trace[879906128] transaction","detail":"{read_only:false; response_revision:20444; number_of_response:1; }","duration":"111.423534ms","start":"2026-06-02T18:08:59.624249Z","end":"2026-06-02T18:08:59.735672Z","steps":["trace[879906128] 'process raft request' (duration: 111.205948ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:09:00.54777Z","caller":"traceutil/trace.go:171","msg":"trace[612119573] transaction","detail":"{read_only:false; response_revision:20447; number_of_response:1; }","duration":"155.090421ms","start":"2026-06-02T18:09:00.392656Z","end":"2026-06-02T18:09:00.547746Z","steps":["trace[612119573] 'process raft request' (duration: 154.995859ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:09:00.547786Z","caller":"traceutil/trace.go:171","msg":"trace[683837678] transaction","detail":"{read_only:false; response_revision:20446; number_of_response:1; }","duration":"156.798782ms","start":"2026-06-02T18:09:00.390937Z","end":"2026-06-02T18:09:00.547736Z","steps":["trace[683837678] 'process raft request' (duration: 68.640786ms)","trace[683837678] 'compare' (duration: 87.9077ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:09:00.547751Z","caller":"traceutil/trace.go:171","msg":"trace[1749685367] linearizableReadLoop","detail":"{readStateIndex:21137; appliedIndex:21135; }","duration":"136.494045ms","start":"2026-06-02T18:09:00.41122Z","end":"2026-06-02T18:09:00.547714Z","steps":["trace[1749685367] 'read index received' (duration: 48.33658ms)","trace[1749685367] 'applied index is now lower than readState.Index' (duration: 88.155605ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:09:00.54788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.646619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:09:00.547986Z","caller":"traceutil/trace.go:171","msg":"trace[1938275709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20447; }","duration":"136.832063ms","start":"2026-06-02T18:09:00.411142Z","end":"2026-06-02T18:09:00.547974Z","steps":["trace[1938275709] 'agreement among raft nodes before linearized reading' (duration: 136.67798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:09:00.54827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.799608ms","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-06-02T18:09:00.5484Z","caller":"traceutil/trace.go:171","msg":"trace[925977701] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20447; }","duration":"108.002382ms","start":"2026-06-02T18:09:00.440366Z","end":"2026-06-02T18:09:00.548368Z","steps":["trace[925977701] 'agreement among raft nodes before linearized reading' (duration: 107.736835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:12:48.1526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.78537ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-02T18:12:59.327712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20134} {"level":"info","ts":"2026-06-02T18:12:59.439626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20134,"took":"106.891779ms","hash":3042892787,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":40787968,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-06-02T18:12:59.439696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3042892787,"revision":20134,"compact-revision":17641} 2026/06/02 18:13:33 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T18:14:27.517168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.018648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:27.517282Z","caller":"traceutil/trace.go:171","msg":"trace[1552794475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23852; }","duration":"103.193912ms","start":"2026-06-02T18:14:27.414066Z","end":"2026-06-02T18:14:27.51726Z","steps":["trace[1552794475] 'range keys from in-memory index tree' (duration: 102.863614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:27.517653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.497809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:27.517674Z","caller":"traceutil/trace.go:171","msg":"trace[120452868] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:23852; }","duration":"103.563971ms","start":"2026-06-02T18:14:27.4141Z","end":"2026-06-02T18:14:27.517664Z","steps":["trace[120452868] 'count revisions from in-memory index tree' (duration: 103.412237ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:09.622128Z","caller":"traceutil/trace.go:171","msg":"trace[1490475193] transaction","detail":"{read_only:false; response_revision:24270; number_of_response:1; }","duration":"119.641855ms","start":"2026-06-02T18:15:09.502447Z","end":"2026-06-02T18:15:09.622089Z","steps":["trace[1490475193] 'process raft request' (duration: 119.020831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:09.622714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.869609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-02T18:15:09.622769Z","caller":"traceutil/trace.go:171","msg":"trace[1355251271] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24270; }","duration":"101.937251ms","start":"2026-06-02T18:15:09.520809Z","end":"2026-06-02T18:15:09.622747Z","steps":["trace[1355251271] 'agreement among raft nodes before linearized reading' (duration: 101.755767ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:09.621727Z","caller":"traceutil/trace.go:171","msg":"trace[473253467] linearizableReadLoop","detail":"{readStateIndex:25065; appliedIndex:25064; }","duration":"100.882685ms","start":"2026-06-02T18:15:09.520818Z","end":"2026-06-02T18:15:09.6217Z","steps":["trace[473253467] 'read index received' (duration: 100.631749ms)","trace[473253467] 'applied index is now lower than readState.Index' (duration: 249.586µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:31.900601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.360462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:31.900759Z","caller":"traceutil/trace.go:171","msg":"trace[366572355] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"314.625216ms","start":"2026-06-02T18:16:31.58611Z","end":"2026-06-02T18:16:31.900735Z","steps":["trace[366572355] 'process raft request' (duration: 207.018558ms)","trace[366572355] 'compare' (duration: 107.010424ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:31.90088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:31.586097Z","time spent":"314.695897ms","remote":"127.0.0.1:41070","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-06-02T18:16:31.904915Z","caller":"traceutil/trace.go:171","msg":"trace[812403106] linearizableReadLoop","detail":"{readStateIndex:25871; appliedIndex:25869; }","duration":"141.103769ms","start":"2026-06-02T18:16:31.763784Z","end":"2026-06-02T18:16:31.904888Z","steps":["trace[812403106] 'read index received' (duration: 29.373103ms)","trace[812403106] 'applied index is now lower than readState.Index' (duration: 111.729665ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:31.905082Z","caller":"traceutil/trace.go:171","msg":"trace[1052519494] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"222.391235ms","start":"2026-06-02T18:16:31.682656Z","end":"2026-06-02T18:16:31.905048Z","steps":["trace[1052519494] 'process raft request' (duration: 222.054157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:31.905307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.516169ms","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-06-02T18:16:31.905389Z","caller":"traceutil/trace.go:171","msg":"trace[145127037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25053; }","duration":"141.675642ms","start":"2026-06-02T18:16:31.7637Z","end":"2026-06-02T18:16:31.905376Z","steps":["trace[145127037] 'agreement among raft nodes before linearized reading' (duration: 141.410016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:32.221231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.12599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:32.221433Z","caller":"traceutil/trace.go:171","msg":"trace[230573103] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"311.680184ms","start":"2026-06-02T18:16:31.909736Z","end":"2026-06-02T18:16:32.221416Z","steps":["trace[230573103] 'process raft request' (duration: 311.606752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:32.221487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:31.909716Z","time spent":"311.745546ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:16:32.221589Z","caller":"traceutil/trace.go:171","msg":"trace[1769796251] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"263.165403ms","start":"2026-06-02T18:16:31.958319Z","end":"2026-06-02T18:16:32.221484Z","steps":["trace[1769796251] 'process raft request' (duration: 263.06222ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:32.22166Z","caller":"traceutil/trace.go:171","msg":"trace[1749483091] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"381.136438ms","start":"2026-06-02T18:16:31.840469Z","end":"2026-06-02T18:16:32.221606Z","steps":["trace[1749483091] 'process raft request' (duration: 201.539087ms)","trace[1749483091] 'compare' (duration: 178.967496ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:32.221821Z","caller":"traceutil/trace.go:171","msg":"trace[579517395] linearizableReadLoop","detail":"{readStateIndex:25872; appliedIndex:25871; }","duration":"316.713335ms","start":"2026-06-02T18:16:31.905074Z","end":"2026-06-02T18:16:32.221787Z","steps":["trace[579517395] 'read index received' (duration: 136.94438ms)","trace[579517395] 'applied index is now lower than readState.Index' (duration: 179.763575ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:32.22196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:31.840446Z","time spent":"381.348713ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:32.221991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.66364ms","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-06-02T18:16:32.222034Z","caller":"traceutil/trace.go:171","msg":"trace[110128993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25056; }","duration":"376.705841ms","start":"2026-06-02T18:16:31.845316Z","end":"2026-06-02T18:16:32.222021Z","steps":["trace[110128993] 'agreement among raft nodes before linearized reading' (duration: 376.500717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:32.222006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.40314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T18:16:32.222022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.288987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:32.222105Z","caller":"traceutil/trace.go:171","msg":"trace[1467069774] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25056; }","duration":"390.551684ms","start":"2026-06-02T18:16:31.831534Z","end":"2026-06-02T18:16:32.222086Z","steps":["trace[1467069774] 'agreement among raft nodes before linearized reading' (duration: 390.368179ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:32.222127Z","caller":"traceutil/trace.go:171","msg":"trace[2056757716] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25056; }","duration":"365.439352ms","start":"2026-06-02T18:16:31.856663Z","end":"2026-06-02T18:16:32.222103Z","steps":["trace[2056757716] 'agreement among raft nodes before linearized reading' (duration: 365.150665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:32.222142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:31.831507Z","time spent":"390.628026ms","remote":"127.0.0.1:47426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-06-02T18:16:32.222074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:31.845289Z","time spent":"376.778354ms","remote":"127.0.0.1:41070","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":"warn","ts":"2026-06-02T18:16:32.222185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:31.856656Z","time spent":"365.516394ms","remote":"127.0.0.1:56392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-06-02T18:16:32.616789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.653858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:32.617068Z","caller":"traceutil/trace.go:171","msg":"trace[1140937787] linearizableReadLoop","detail":"{readStateIndex:25875; appliedIndex:25874; }","duration":"395.137273ms","start":"2026-06-02T18:16:32.22189Z","end":"2026-06-02T18:16:32.617027Z","steps":["trace[1140937787] 'read index received' (duration: 259.158837ms)","trace[1140937787] 'applied index is now lower than readState.Index' (duration: 135.976586ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:32.617409Z","caller":"traceutil/trace.go:171","msg":"trace[1744904654] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"227.082458ms","start":"2026-06-02T18:16:32.390315Z","end":"2026-06-02T18:16:32.617398Z","steps":["trace[1744904654] 'process raft request' (duration: 226.721339ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:32.617467Z","caller":"traceutil/trace.go:171","msg":"trace[1196976447] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"389.377785ms","start":"2026-06-02T18:16:32.228074Z","end":"2026-06-02T18:16:32.617451Z","steps":["trace[1196976447] 'process raft request' (duration: 388.835492ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:32.617188Z","caller":"traceutil/trace.go:171","msg":"trace[1613919228] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"137.014702ms","start":"2026-06-02T18:16:32.480152Z","end":"2026-06-02T18:16:32.617167Z","steps":["trace[1613919228] 'process raft request' (duration: 136.95826ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:32.617364Z","caller":"traceutil/trace.go:171","msg":"trace[1759014879] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"383.724659ms","start":"2026-06-02T18:16:32.233594Z","end":"2026-06-02T18:16:32.617319Z","steps":["trace[1759014879] 'process raft request' (duration: 383.403741ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:32.617591Z","caller":"traceutil/trace.go:171","msg":"trace[86268076] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"463.451579ms","start":"2026-06-02T18:16:32.154077Z","end":"2026-06-02T18:16:32.617529Z","steps":["trace[86268076] 'process raft request' (duration: 326.95736ms)","trace[86268076] 'compare' (duration: 135.530046ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:32.617615Z","caller":"traceutil/trace.go:171","msg":"trace[531330275] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"145.112765ms","start":"2026-06-02T18:16:32.472443Z","end":"2026-06-02T18:16:32.617556Z","steps":["trace[531330275] 'process raft request' (duration: 144.627673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:32.617684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.175273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-06-02T18:16:32.617647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:32.228054Z","time spent":"389.464647ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:32.617822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:32.154044Z","time spent":"463.634024ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:32.617838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:32.233584Z","time spent":"384.094479ms","remote":"127.0.0.1:41070","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-06-02T18:16:32.618046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.551686ms","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-06-02T18:16:32.618127Z","caller":"traceutil/trace.go:171","msg":"trace[624342093] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25062; }","duration":"244.676319ms","start":"2026-06-02T18:16:32.373431Z","end":"2026-06-02T18:16:32.618107Z","steps":["trace[624342093] 'agreement among raft nodes before linearized reading' (duration: 244.426873ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:32.618102Z","caller":"traceutil/trace.go:171","msg":"trace[683919230] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25062; }","duration":"420.264665ms","start":"2026-06-02T18:16:32.197472Z","end":"2026-06-02T18:16:32.617737Z","steps":["trace[683919230] 'agreement among raft nodes before linearized reading' (duration: 420.018199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:32.618566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:32.197417Z","time spent":"421.091404ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-02T18:16:32.915679Z","caller":"traceutil/trace.go:171","msg":"trace[1534205263] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"292.051665ms","start":"2026-06-02T18:16:32.623594Z","end":"2026-06-02T18:16:32.915646Z","steps":["trace[1534205263] 'process raft request' (duration: 257.080437ms)","trace[1534205263] 'compare' (duration: 34.827894ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:33.218787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.949015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:33.218946Z","caller":"traceutil/trace.go:171","msg":"trace[338385344] linearizableReadLoop","detail":"{readStateIndex:25882; appliedIndex:25880; }","duration":"594.534279ms","start":"2026-06-02T18:16:32.624394Z","end":"2026-06-02T18:16:33.218929Z","steps":["trace[338385344] 'read index received' (duration: 256.297899ms)","trace[338385344] 'applied index is now lower than readState.Index' (duration: 338.23445ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:33.218974Z","caller":"traceutil/trace.go:171","msg":"trace[368729651] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"595.263936ms","start":"2026-06-02T18:16:32.623671Z","end":"2026-06-02T18:16:33.218935Z","steps":["trace[368729651] 'process raft request' (duration: 406.071325ms)","trace[368729651] 'compare' (duration: 188.812022ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:33.219196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.801325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18366"} {"level":"warn","ts":"2026-06-02T18:16:33.219185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:32.623656Z","time spent":"595.417339ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:16:33.21922Z","caller":"traceutil/trace.go:171","msg":"trace[1166357093] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25065; }","duration":"594.842536ms","start":"2026-06-02T18:16:32.62437Z","end":"2026-06-02T18:16:33.219212Z","steps":["trace[1166357093] 'agreement among raft nodes before linearized reading' (duration: 594.635821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:33.21925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:32.624345Z","time spent":"594.892757ms","remote":"127.0.0.1:40982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18389,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-02T18:16:33.219362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.868281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-02T18:16:33.219404Z","caller":"traceutil/trace.go:171","msg":"trace[221265302] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"450.236092ms","start":"2026-06-02T18:16:32.768947Z","end":"2026-06-02T18:16:33.219183Z","steps":["trace[221265302] 'process raft request' (duration: 449.932515ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:33.219449Z","caller":"traceutil/trace.go:171","msg":"trace[1011115697] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:25065; }","duration":"580.986565ms","start":"2026-06-02T18:16:32.638445Z","end":"2026-06-02T18:16:33.219431Z","steps":["trace[1011115697] 'agreement among raft nodes before linearized reading' (duration: 580.609085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:33.219877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:32.638434Z","time spent":"581.416565ms","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"warn","ts":"2026-06-02T18:16:33.219385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.238816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T18:16:33.219737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:32.768913Z","time spent":"450.712874ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:16:33.22009Z","caller":"traceutil/trace.go:171","msg":"trace[1707581907] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25065; }","duration":"147.971765ms","start":"2026-06-02T18:16:33.07205Z","end":"2026-06-02T18:16:33.220021Z","steps":["trace[1707581907] 'agreement among raft nodes before linearized reading' (duration: 147.094863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:33.469736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.248176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:33.469893Z","caller":"traceutil/trace.go:171","msg":"trace[463212393] linearizableReadLoop","detail":"{readStateIndex:25885; appliedIndex:25884; }","duration":"150.052724ms","start":"2026-06-02T18:16:33.31982Z","end":"2026-06-02T18:16:33.469873Z","steps":["trace[463212393] 'read index received' (duration: 35.46332ms)","trace[463212393] 'applied index is now lower than readState.Index' (duration: 114.588034ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:33.470023Z","caller":"traceutil/trace.go:171","msg":"trace[579002854] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"152.940293ms","start":"2026-06-02T18:16:33.31707Z","end":"2026-06-02T18:16:33.470011Z","steps":["trace[579002854] 'process raft request' (duration: 38.283607ms)","trace[579002854] 'compare' (duration: 114.049242ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:33.470345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.504416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T18:16:33.470374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.518195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:33.470461Z","caller":"traceutil/trace.go:171","msg":"trace[1440602170] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25066; }","duration":"130.69379ms","start":"2026-06-02T18:16:33.339727Z","end":"2026-06-02T18:16:33.470421Z","steps":["trace[1440602170] 'agreement among raft nodes before linearized reading' (duration: 130.402333ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:33.470504Z","caller":"traceutil/trace.go:171","msg":"trace[1564133255] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25066; }","duration":"150.667118ms","start":"2026-06-02T18:16:33.319806Z","end":"2026-06-02T18:16:33.470473Z","steps":["trace[1564133255] 'agreement among raft nodes before linearized reading' (duration: 150.34128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:33.823713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.464814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:33.824127Z","caller":"traceutil/trace.go:171","msg":"trace[2101454642] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"270.095338ms","start":"2026-06-02T18:16:33.553819Z","end":"2026-06-02T18:16:33.823914Z","steps":["trace[2101454642] 'process raft request' (duration: 111.276285ms)","trace[2101454642] 'compare' (duration: 158.295831ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:34.134644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.780294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:34.134773Z","caller":"traceutil/trace.go:171","msg":"trace[1589107951] linearizableReadLoop","detail":"{readStateIndex:25887; appliedIndex:25886; }","duration":"228.478602ms","start":"2026-06-02T18:16:33.906276Z","end":"2026-06-02T18:16:34.134755Z","steps":["trace[1589107951] 'read index received' (duration: 56.467932ms)","trace[1589107951] 'applied index is now lower than readState.Index' (duration: 172.00919ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:34.134823Z","caller":"traceutil/trace.go:171","msg":"trace[255204665] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"304.853681ms","start":"2026-06-02T18:16:33.82992Z","end":"2026-06-02T18:16:34.134773Z","steps":["trace[255204665] 'process raft request' (duration: 132.851652ms)","trace[255204665] 'compare' (duration: 171.595719ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:34.1349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.633675ms","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-06-02T18:16:34.134926Z","caller":"traceutil/trace.go:171","msg":"trace[517049455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25068; }","duration":"228.703456ms","start":"2026-06-02T18:16:33.906215Z","end":"2026-06-02T18:16:34.134918Z","steps":["trace[517049455] 'agreement among raft nodes before linearized reading' (duration: 228.586444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:34.135032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:33.829889Z","time spent":"305.026345ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:16:34.212003Z","caller":"traceutil/trace.go:171","msg":"trace[682273844] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"301.01855ms","start":"2026-06-02T18:16:33.910947Z","end":"2026-06-02T18:16:34.211966Z","steps":["trace[682273844] 'process raft request' (duration: 300.797645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:34.212253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:33.910903Z","time spent":"301.205514ms","remote":"127.0.0.1:41070","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-06-02T18:16:34.283437Z","caller":"traceutil/trace.go:171","msg":"trace[195415723] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"143.273171ms","start":"2026-06-02T18:16:34.14012Z","end":"2026-06-02T18:16:34.283393Z","steps":["trace[195415723] 'process raft request' (duration: 142.624536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:34.788412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.485833ms","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-06-02T18:16:34.788487Z","caller":"traceutil/trace.go:171","msg":"trace[2017859375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25073; }","duration":"165.607236ms","start":"2026-06-02T18:16:34.622863Z","end":"2026-06-02T18:16:34.78847Z","steps":["trace[2017859375] 'range keys from in-memory index tree' (duration: 165.328429ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:34.802796Z","caller":"traceutil/trace.go:171","msg":"trace[1109066774] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"174.94694ms","start":"2026-06-02T18:16:34.627818Z","end":"2026-06-02T18:16:34.802765Z","steps":["trace[1109066774] 'process raft request' (duration: 174.771676ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:34.82487Z","caller":"traceutil/trace.go:171","msg":"trace[1468780731] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"196.994168ms","start":"2026-06-02T18:16:34.627842Z","end":"2026-06-02T18:16:34.824836Z","steps":["trace[1468780731] 'process raft request' (duration: 196.021954ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:34.82533Z","caller":"traceutil/trace.go:171","msg":"trace[202248016] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"197.317416ms","start":"2026-06-02T18:16:34.627973Z","end":"2026-06-02T18:16:34.82529Z","steps":["trace[202248016] 'process raft request' (duration: 196.076276ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:34.825356Z","caller":"traceutil/trace.go:171","msg":"trace[2051046796] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"197.266145ms","start":"2026-06-02T18:16:34.628052Z","end":"2026-06-02T18:16:34.825318Z","steps":["trace[2051046796] 'process raft request' (duration: 196.043525ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:35.445728Z","caller":"traceutil/trace.go:171","msg":"trace[1443115235] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"219.24747ms","start":"2026-06-02T18:16:35.226452Z","end":"2026-06-02T18:16:35.445699Z","steps":["trace[1443115235] 'process raft request' (duration: 219.010575ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:35.48278Z","caller":"traceutil/trace.go:171","msg":"trace[86956133] linearizableReadLoop","detail":"{readStateIndex:25900; appliedIndex:25898; }","duration":"147.894451ms","start":"2026-06-02T18:16:35.334834Z","end":"2026-06-02T18:16:35.482728Z","steps":["trace[86956133] 'read index received' (duration: 110.720821ms)","trace[86956133] 'applied index is now lower than readState.Index' (duration: 37.17259ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:35.48298Z","caller":"traceutil/trace.go:171","msg":"trace[340411181] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"252.57307ms","start":"2026-06-02T18:16:35.230382Z","end":"2026-06-02T18:16:35.482955Z","steps":["trace[340411181] 'process raft request' (duration: 252.132218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:35.483249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.372023ms","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-06-02T18:16:35.483465Z","caller":"traceutil/trace.go:171","msg":"trace[840535046] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25081; }","duration":"148.526787ms","start":"2026-06-02T18:16:35.334783Z","end":"2026-06-02T18:16:35.48331Z","steps":["trace[840535046] 'agreement among raft nodes before linearized reading' (duration: 148.190379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:35.644124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.504395ms","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-06-02T18:16:35.644296Z","caller":"traceutil/trace.go:171","msg":"trace[1821415495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25081; }","duration":"110.70522ms","start":"2026-06-02T18:16:35.53356Z","end":"2026-06-02T18:16:35.644266Z","steps":["trace[1821415495] 'range keys from in-memory index tree' (duration: 110.474265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:35.64444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.808473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:35.644714Z","caller":"traceutil/trace.go:171","msg":"trace[1702309365] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"154.754716ms","start":"2026-06-02T18:16:35.489902Z","end":"2026-06-02T18:16:35.644656Z","steps":["trace[1702309365] 'process raft request' (duration: 23.452602ms)","trace[1702309365] 'compare' (duration: 130.353822ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:36.463373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.879618ms","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-06-02T18:16:36.463464Z","caller":"traceutil/trace.go:171","msg":"trace[181234697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25084; }","duration":"175.030612ms","start":"2026-06-02T18:16:36.288413Z","end":"2026-06-02T18:16:36.463443Z","steps":["trace[181234697] 'range keys from in-memory index tree' (duration: 174.576731ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:36.724041Z","caller":"traceutil/trace.go:171","msg":"trace[271743403] linearizableReadLoop","detail":"{readStateIndex:25904; appliedIndex:25903; }","duration":"423.372521ms","start":"2026-06-02T18:16:36.300641Z","end":"2026-06-02T18:16:36.724014Z","steps":["trace[271743403] 'read index received' (duration: 423.134245ms)","trace[271743403] 'applied index is now lower than readState.Index' (duration: 236.416µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:36.724084Z","caller":"traceutil/trace.go:171","msg":"trace[748106263] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"433.018632ms","start":"2026-06-02T18:16:36.29103Z","end":"2026-06-02T18:16:36.724048Z","steps":["trace[748106263] 'process raft request' (duration: 432.787866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:36.724297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:36.291015Z","time spent":"433.209266ms","remote":"127.0.0.1:41070","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-06-02T18:16:36.724327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.679828ms","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-06-02T18:16:36.724387Z","caller":"traceutil/trace.go:171","msg":"trace[1581347821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25085; }","duration":"423.74828ms","start":"2026-06-02T18:16:36.30062Z","end":"2026-06-02T18:16:36.724368Z","steps":["trace[1581347821] 'agreement among raft nodes before linearized reading' (duration: 423.496224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:36.724425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:36.300612Z","time spent":"423.79395ms","remote":"127.0.0.1:41070","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-06-02T18:16:36.739088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.206ms","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-06-02T18:16:36.739195Z","caller":"traceutil/trace.go:171","msg":"trace[826218956] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25086; }","duration":"201.379804ms","start":"2026-06-02T18:16:36.537793Z","end":"2026-06-02T18:16:36.739173Z","steps":["trace[826218956] 'agreement among raft nodes before linearized reading' (duration: 201.182289ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:36.739646Z","caller":"traceutil/trace.go:171","msg":"trace[1721485663] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"271.339569ms","start":"2026-06-02T18:16:36.468289Z","end":"2026-06-02T18:16:36.739629Z","steps":["trace[1721485663] 'process raft request' (duration: 270.52765ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:37.062321Z","caller":"traceutil/trace.go:171","msg":"trace[225057454] linearizableReadLoop","detail":"{readStateIndex:25909; appliedIndex:25907; }","duration":"229.729222ms","start":"2026-06-02T18:16:36.832563Z","end":"2026-06-02T18:16:37.062292Z","steps":["trace[225057454] 'read index received' (duration: 53.950133ms)","trace[225057454] 'applied index is now lower than readState.Index' (duration: 175.777899ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:37.062683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.121591ms","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-06-02T18:16:37.062767Z","caller":"traceutil/trace.go:171","msg":"trace[1440847361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25092; }","duration":"230.240664ms","start":"2026-06-02T18:16:36.83251Z","end":"2026-06-02T18:16:37.062751Z","steps":["trace[1440847361] 'agreement among raft nodes before linearized reading' (duration: 230.012578ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:37.063106Z","caller":"traceutil/trace.go:171","msg":"trace[220419690] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"231.393713ms","start":"2026-06-02T18:16:36.831699Z","end":"2026-06-02T18:16:37.063093Z","steps":["trace[220419690] 'process raft request' (duration: 219.922747ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:37.063307Z","caller":"traceutil/trace.go:171","msg":"trace[20613553] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"228.921882ms","start":"2026-06-02T18:16:36.834374Z","end":"2026-06-02T18:16:37.063296Z","steps":["trace[20613553] 'process raft request' (duration: 227.802495ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:37.063501Z","caller":"traceutil/trace.go:171","msg":"trace[725131819] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"228.629286ms","start":"2026-06-02T18:16:36.834858Z","end":"2026-06-02T18:16:37.063488Z","steps":["trace[725131819] 'process raft request' (duration: 227.388076ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:37.242308Z","caller":"traceutil/trace.go:171","msg":"trace[1439497234] linearizableReadLoop","detail":"{readStateIndex:25912; appliedIndex:25911; }","duration":"169.372297ms","start":"2026-06-02T18:16:37.07291Z","end":"2026-06-02T18:16:37.242282Z","steps":["trace[1439497234] 'read index received' (duration: 169.087909ms)","trace[1439497234] 'applied index is now lower than readState.Index' (duration: 283.578µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:37.242697Z","caller":"traceutil/trace.go:171","msg":"trace[1282687467] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"173.764281ms","start":"2026-06-02T18:16:37.068894Z","end":"2026-06-02T18:16:37.242659Z","steps":["trace[1282687467] 'process raft request' (duration: 173.198156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:37.242841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.913499ms","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":"warn","ts":"2026-06-02T18:16:37.242848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.779731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-06-02T18:16:37.242889Z","caller":"traceutil/trace.go:171","msg":"trace[181162195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25093; }","duration":"170.018432ms","start":"2026-06-02T18:16:37.072853Z","end":"2026-06-02T18:16:37.242871Z","steps":["trace[181162195] 'agreement among raft nodes before linearized reading' (duration: 169.826677ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:37.242928Z","caller":"traceutil/trace.go:171","msg":"trace[855443496] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25093; }","duration":"107.902924ms","start":"2026-06-02T18:16:37.135003Z","end":"2026-06-02T18:16:37.242906Z","steps":["trace[855443496] 'agreement among raft nodes before linearized reading' (duration: 107.643008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:47.949013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.509295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:47.9496Z","caller":"traceutil/trace.go:171","msg":"trace[348250566] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"457.930918ms","start":"2026-06-02T18:16:47.491602Z","end":"2026-06-02T18:16:47.949533Z","steps":["trace[348250566] 'process raft request' (duration: 235.749726ms)","trace[348250566] 'compare' (duration: 220.930871ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:47.94964Z","caller":"traceutil/trace.go:171","msg":"trace[1062688522] linearizableReadLoop","detail":"{readStateIndex:25989; appliedIndex:25988; }","duration":"398.969155ms","start":"2026-06-02T18:16:47.550637Z","end":"2026-06-02T18:16:47.949606Z","steps":["trace[1062688522] 'read index received' (duration: 176.788454ms)","trace[1062688522] 'applied index is now lower than readState.Index' (duration: 222.177681ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:47.949819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.180551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-02T18:16:47.949866Z","caller":"traceutil/trace.go:171","msg":"trace[816249174] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25168; }","duration":"399.274233ms","start":"2026-06-02T18:16:47.550575Z","end":"2026-06-02T18:16:47.949849Z","steps":["trace[816249174] 'agreement among raft nodes before linearized reading' (duration: 399.103729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:47.949873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:47.491572Z","time spent":"458.139793ms","remote":"127.0.0.1:41070","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-06-02T18:16:47.949907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:47.550549Z","time spent":"399.349834ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-02T18:16:47.95048Z","caller":"traceutil/trace.go:171","msg":"trace[514201356] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"308.538809ms","start":"2026-06-02T18:16:47.641926Z","end":"2026-06-02T18:16:47.950465Z","steps":["trace[514201356] 'process raft request' (duration: 307.645418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:47.950564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:47.641885Z","time spent":"308.638101ms","remote":"127.0.0.1:41070","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-06-02T18:16:48.248162Z","caller":"traceutil/trace.go:171","msg":"trace[81852408] linearizableReadLoop","detail":"{readStateIndex:25992; appliedIndex:25991; }","duration":"153.718182ms","start":"2026-06-02T18:16:48.094399Z","end":"2026-06-02T18:16:48.248117Z","steps":["trace[81852408] 'read index received' (duration: 140.207018ms)","trace[81852408] 'applied index is now lower than readState.Index' (duration: 13.510194ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:48.248441Z","caller":"traceutil/trace.go:171","msg":"trace[1896151682] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"252.18277ms","start":"2026-06-02T18:16:47.996221Z","end":"2026-06-02T18:16:48.248404Z","steps":["trace[1896151682] 'process raft request' (duration: 238.455351ms)","trace[1896151682] 'compare' (duration: 13.300978ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:48.248668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.217203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:48.248804Z","caller":"traceutil/trace.go:171","msg":"trace[38650865] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25171; }","duration":"154.427689ms","start":"2026-06-02T18:16:48.09433Z","end":"2026-06-02T18:16:48.248757Z","steps":["trace[38650865] 'agreement among raft nodes before linearized reading' (duration: 154.138211ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:48.828511Z","caller":"traceutil/trace.go:171","msg":"trace[51271453] linearizableReadLoop","detail":"{readStateIndex:25993; appliedIndex:25992; }","duration":"314.643265ms","start":"2026-06-02T18:16:48.513841Z","end":"2026-06-02T18:16:48.828485Z","steps":["trace[51271453] 'read index received' (duration: 314.396489ms)","trace[51271453] 'applied index is now lower than readState.Index' (duration: 245.806µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:48.828623Z","caller":"traceutil/trace.go:171","msg":"trace[660228641] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"544.089521ms","start":"2026-06-02T18:16:48.284506Z","end":"2026-06-02T18:16:48.828595Z","steps":["trace[660228641] 'process raft request' (duration: 543.750873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:48.828738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.877432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:16:48.828782Z","caller":"traceutil/trace.go:171","msg":"trace[814459760] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25172; }","duration":"314.993565ms","start":"2026-06-02T18:16:48.513777Z","end":"2026-06-02T18:16:48.82877Z","steps":["trace[814459760] 'agreement among raft nodes before linearized reading' (duration: 314.843901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:48.828792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:48.284476Z","time spent":"544.200713ms","remote":"127.0.0.1:41070","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-06-02T18:16:48.828818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:48.513748Z","time spent":"315.061196ms","remote":"127.0.0.1:41122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":20,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-02T18:16:48.831065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.112463ms","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-06-02T18:16:48.831141Z","caller":"traceutil/trace.go:171","msg":"trace[1253224695] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25173; }","duration":"104.229326ms","start":"2026-06-02T18:16:48.726889Z","end":"2026-06-02T18:16:48.831118Z","steps":["trace[1253224695] 'agreement among raft nodes before linearized reading' (duration: 103.99167ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:48.960476Z","caller":"traceutil/trace.go:171","msg":"trace[1548363744] linearizableReadLoop","detail":"{readStateIndex:25997; appliedIndex:25995; }","duration":"124.640575ms","start":"2026-06-02T18:16:48.835817Z","end":"2026-06-02T18:16:48.960457Z","steps":["trace[1548363744] 'read index received' (duration: 54.502326ms)","trace[1548363744] 'applied index is now lower than readState.Index' (duration: 70.137429ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:48.960554Z","caller":"traceutil/trace.go:171","msg":"trace[1148897141] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"125.365773ms","start":"2026-06-02T18:16:48.835147Z","end":"2026-06-02T18:16:48.960512Z","steps":["trace[1148897141] 'process raft request' (duration: 55.057008ms)","trace[1148897141] 'compare' (duration: 70.059758ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:48.960682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.84413ms","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-06-02T18:16:48.960693Z","caller":"traceutil/trace.go:171","msg":"trace[813398050] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"125.079506ms","start":"2026-06-02T18:16:48.835596Z","end":"2026-06-02T18:16:48.960675Z","steps":["trace[813398050] 'process raft request' (duration: 124.82836ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:48.960718Z","caller":"traceutil/trace.go:171","msg":"trace[1557104954] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25175; }","duration":"124.920962ms","start":"2026-06-02T18:16:48.835789Z","end":"2026-06-02T18:16:48.96071Z","steps":["trace[1557104954] 'agreement among raft nodes before linearized reading' (duration: 124.758529ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:49.172106Z","caller":"traceutil/trace.go:171","msg":"trace[904659417] linearizableReadLoop","detail":"{readStateIndex:25998; appliedIndex:25997; }","duration":"209.793154ms","start":"2026-06-02T18:16:48.962286Z","end":"2026-06-02T18:16:49.172079Z","steps":["trace[904659417] 'read index received' (duration: 203.76835ms)","trace[904659417] 'applied index is now lower than readState.Index' (duration: 6.023734ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:49.172229Z","caller":"traceutil/trace.go:171","msg":"trace[1633737829] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"216.979896ms","start":"2026-06-02T18:16:48.955237Z","end":"2026-06-02T18:16:49.172217Z","steps":["trace[1633737829] 'process raft request' (duration: 210.796799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:49.172505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.186983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18366"} {"level":"info","ts":"2026-06-02T18:16:49.172671Z","caller":"traceutil/trace.go:171","msg":"trace[518096142] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25176; }","duration":"210.316817ms","start":"2026-06-02T18:16:48.962263Z","end":"2026-06-02T18:16:49.172579Z","steps":["trace[518096142] 'agreement among raft nodes before linearized reading' (duration: 210.0475ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:49.499298Z","caller":"traceutil/trace.go:171","msg":"trace[1098850671] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"391.514477ms","start":"2026-06-02T18:16:49.107732Z","end":"2026-06-02T18:16:49.499246Z","steps":["trace[1098850671] 'process raft request' (duration: 361.57784ms)","trace[1098850671] 'compare' (duration: 29.536377ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:49.499355Z","caller":"traceutil/trace.go:171","msg":"trace[1323541009] linearizableReadLoop","detail":"{readStateIndex:26001; appliedIndex:25998; }","duration":"327.148856ms","start":"2026-06-02T18:16:49.172176Z","end":"2026-06-02T18:16:49.499325Z","steps":["trace[1323541009] 'read index received' (duration: 297.155427ms)","trace[1323541009] 'applied index is now lower than readState.Index' (duration: 29.992179ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:49.499443Z","caller":"traceutil/trace.go:171","msg":"trace[668202324] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"388.882953ms","start":"2026-06-02T18:16:49.110508Z","end":"2026-06-02T18:16:49.499391Z","steps":["trace[668202324] 'process raft request' (duration: 388.74433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:49.499538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.107693Z","time spent":"391.698521ms","remote":"127.0.0.1:41070","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-06-02T18:16:49.499669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.110479Z","time spent":"389.074588ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:49.499765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.022198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-02T18:16:49.499833Z","caller":"traceutil/trace.go:171","msg":"trace[2098194273] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"390.498581ms","start":"2026-06-02T18:16:49.109322Z","end":"2026-06-02T18:16:49.49982Z","steps":["trace[2098194273] 'process raft request' (duration: 389.820275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:49.499896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.109302Z","time spent":"390.561573ms","remote":"127.0.0.1:41070","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-06-02T18:16:49.499898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.756006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:49.49994Z","caller":"traceutil/trace.go:171","msg":"trace[1275124627] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25179; }","duration":"359.190322ms","start":"2026-06-02T18:16:49.140658Z","end":"2026-06-02T18:16:49.499848Z","steps":["trace[1275124627] 'agreement among raft nodes before linearized reading' (duration: 358.773502ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:49.49999Z","caller":"traceutil/trace.go:171","msg":"trace[827324633] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25179; }","duration":"253.90097ms","start":"2026-06-02T18:16:49.246069Z","end":"2026-06-02T18:16:49.49997Z","steps":["trace[827324633] 'agreement among raft nodes before linearized reading' (duration: 253.738706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:49.500007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.140631Z","time spent":"359.358756ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-02T18:16:49.499761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.840436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:49.500155Z","caller":"traceutil/trace.go:171","msg":"trace[856244676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25179; }","duration":"481.279516ms","start":"2026-06-02T18:16:49.018827Z","end":"2026-06-02T18:16:49.500107Z","steps":["trace[856244676] 'agreement among raft nodes before linearized reading' (duration: 480.675682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:49.5003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.018795Z","time spent":"481.45466ms","remote":"127.0.0.1:40786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T18:16:49.500381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.251548ms","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-06-02T18:16:49.500484Z","caller":"traceutil/trace.go:171","msg":"trace[1147172646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25179; }","duration":"193.418722ms","start":"2026-06-02T18:16:49.30704Z","end":"2026-06-02T18:16:49.500459Z","steps":["trace[1147172646] 'agreement among raft nodes before linearized reading' (duration: 193.083944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:49.50043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.012519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:49.501145Z","caller":"traceutil/trace.go:171","msg":"trace[1939900108] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25179; }","duration":"529.772488ms","start":"2026-06-02T18:16:48.971352Z","end":"2026-06-02T18:16:49.501124Z","steps":["trace[1939900108] 'agreement among raft nodes before linearized reading' (duration: 528.717332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:49.501415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:48.971331Z","time spent":"530.059935ms","remote":"127.0.0.1:57014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-06-02T18:16:49.82826Z","caller":"traceutil/trace.go:171","msg":"trace[1238227069] linearizableReadLoop","detail":"{readStateIndex:26004; appliedIndex:26001; }","duration":"319.928532ms","start":"2026-06-02T18:16:49.508305Z","end":"2026-06-02T18:16:49.828233Z","steps":["trace[1238227069] 'read index received' (duration: 14.552558ms)","trace[1238227069] 'applied index is now lower than readState.Index' (duration: 305.374564ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:49.828497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.173888ms","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-06-02T18:16:49.828527Z","caller":"traceutil/trace.go:171","msg":"trace[1246087053] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25182; }","duration":"320.235809ms","start":"2026-06-02T18:16:49.508282Z","end":"2026-06-02T18:16:49.828518Z","steps":["trace[1246087053] 'agreement among raft nodes before linearized reading' (duration: 320.071425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:49.828551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.508273Z","time spent":"320.2723ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-02T18:16:49.828734Z","caller":"traceutil/trace.go:171","msg":"trace[712742946] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"321.887899ms","start":"2026-06-02T18:16:49.506837Z","end":"2026-06-02T18:16:49.828724Z","steps":["trace[712742946] 'process raft request' (duration: 321.342025ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:49.828763Z","caller":"traceutil/trace.go:171","msg":"trace[1722227516] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"322.206067ms","start":"2026-06-02T18:16:49.506389Z","end":"2026-06-02T18:16:49.828595Z","steps":["trace[1722227516] 'process raft request' (duration: 260.768006ms)","trace[1722227516] 'compare' (duration: 60.847707ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:49.828986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.506371Z","time spent":"322.477464ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:49.828813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.506814Z","time spent":"321.943351ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:50.475259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311772221309104,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T18:16:50.509826Z","caller":"traceutil/trace.go:171","msg":"trace[1702185003] linearizableReadLoop","detail":"{readStateIndex:26005; appliedIndex:26004; }","duration":"534.798379ms","start":"2026-06-02T18:16:49.974994Z","end":"2026-06-02T18:16:50.509793Z","steps":["trace[1702185003] 'read index received' (duration: 534.611324ms)","trace[1702185003] 'applied index is now lower than readState.Index' (duration: 185.174µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:50.509958Z","caller":"traceutil/trace.go:171","msg":"trace[294908697] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"553.647459ms","start":"2026-06-02T18:16:49.956263Z","end":"2026-06-02T18:16:50.50991Z","steps":["trace[294908697] 'process raft request' (duration: 553.25259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:50.510213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.206078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-06-02T18:16:50.510242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.956227Z","time spent":"553.835344ms","remote":"127.0.0.1:41070","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-06-02T18:16:50.510228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.638707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:16:50.510294Z","caller":"traceutil/trace.go:171","msg":"trace[1093200546] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25183; }","duration":"535.348541ms","start":"2026-06-02T18:16:49.974929Z","end":"2026-06-02T18:16:50.510277Z","steps":["trace[1093200546] 'agreement among raft nodes before linearized reading' (duration: 535.084055ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:50.510321Z","caller":"traceutil/trace.go:171","msg":"trace[1877419338] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25183; }","duration":"228.779699ms","start":"2026-06-02T18:16:50.281516Z","end":"2026-06-02T18:16:50.510296Z","steps":["trace[1877419338] 'agreement among raft nodes before linearized reading' (duration: 228.606015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:50.510339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:49.974899Z","time spent":"535.430903ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-02T18:16:50.510689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.156989ms","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-06-02T18:16:50.510756Z","caller":"traceutil/trace.go:171","msg":"trace[2139043686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25183; }","duration":"257.317143ms","start":"2026-06-02T18:16:50.253421Z","end":"2026-06-02T18:16:50.510738Z","steps":["trace[2139043686] 'agreement among raft nodes before linearized reading' (duration: 256.612786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:57.191088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.818089ms","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-06-02T18:16:57.191716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.990851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T18:16:57.191815Z","caller":"traceutil/trace.go:171","msg":"trace[2015230310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25293; }","duration":"175.144225ms","start":"2026-06-02T18:16:57.016645Z","end":"2026-06-02T18:16:57.191789Z","steps":["trace[2015230310] 'range keys from in-memory index tree' (duration: 174.601662ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:57.191879Z","caller":"traceutil/trace.go:171","msg":"trace[1966735553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25293; }","duration":"173.606127ms","start":"2026-06-02T18:16:57.018159Z","end":"2026-06-02T18:16:57.191765Z","steps":["trace[1966735553] 'range keys from in-memory index tree' (duration: 172.539543ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:57.329735Z","caller":"traceutil/trace.go:171","msg":"trace[22783252] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"132.892682ms","start":"2026-06-02T18:16:57.196804Z","end":"2026-06-02T18:16:57.329697Z","steps":["trace[22783252] 'process raft request' (duration: 132.692378ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:57.468871Z","caller":"traceutil/trace.go:171","msg":"trace[1335240141] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"272.051055ms","start":"2026-06-02T18:16:57.196787Z","end":"2026-06-02T18:16:57.468838Z","steps":["trace[1335240141] 'process raft request' (duration: 248.246044ms)","trace[1335240141] 'compare' (duration: 23.556155ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:57.469376Z","caller":"traceutil/trace.go:171","msg":"trace[1455602640] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"266.536123ms","start":"2026-06-02T18:16:57.202697Z","end":"2026-06-02T18:16:57.469234Z","steps":["trace[1455602640] 'process raft request' (duration: 266.061552ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:57.733915Z","caller":"traceutil/trace.go:171","msg":"trace[1155325328] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"199.947979ms","start":"2026-06-02T18:16:57.533924Z","end":"2026-06-02T18:16:57.733872Z","steps":["trace[1155325328] 'process raft request' (duration: 196.393554ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:57.733935Z","caller":"traceutil/trace.go:171","msg":"trace[2065508262] linearizableReadLoop","detail":"{readStateIndex:26123; appliedIndex:26120; }","duration":"175.903092ms","start":"2026-06-02T18:16:57.558001Z","end":"2026-06-02T18:16:57.733905Z","steps":["trace[2065508262] 'read index received' (duration: 172.412088ms)","trace[2065508262] 'applied index is now lower than readState.Index' (duration: 3.489104ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:57.734223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.2393ms","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-06-02T18:16:57.734257Z","caller":"traceutil/trace.go:171","msg":"trace[2031343943] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"198.406842ms","start":"2026-06-02T18:16:57.535837Z","end":"2026-06-02T18:16:57.734244Z","steps":["trace[2031343943] 'process raft request' (duration: 197.996991ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:57.734318Z","caller":"traceutil/trace.go:171","msg":"trace[1856899411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25299; }","duration":"176.367764ms","start":"2026-06-02T18:16:57.557919Z","end":"2026-06-02T18:16:57.734287Z","steps":["trace[1856899411] 'agreement among raft nodes before linearized reading' (duration: 176.167578ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:57.734227Z","caller":"traceutil/trace.go:171","msg":"trace[2084715131] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"198.690628ms","start":"2026-06-02T18:16:57.5355Z","end":"2026-06-02T18:16:57.734191Z","steps":["trace[2084715131] 'process raft request' (duration: 198.242397ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:57.935265Z","caller":"traceutil/trace.go:171","msg":"trace[1465560305] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"196.236869ms","start":"2026-06-02T18:16:57.739002Z","end":"2026-06-02T18:16:57.935239Z","steps":["trace[1465560305] 'process raft request' (duration: 196.054885ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:58.196651Z","caller":"traceutil/trace.go:171","msg":"trace[1857822748] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"454.811173ms","start":"2026-06-02T18:16:57.741779Z","end":"2026-06-02T18:16:58.19659Z","steps":["trace[1857822748] 'process raft request' (duration: 454.429833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:58.196851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:57.741753Z","time spent":"454.983037ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3744,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:16:58.273564Z","caller":"traceutil/trace.go:171","msg":"trace[1918948451] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"307.973286ms","start":"2026-06-02T18:16:57.965567Z","end":"2026-06-02T18:16:58.273541Z","steps":["trace[1918948451] 'process raft request' (duration: 307.516375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:58.273659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.672461ms","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-06-02T18:16:58.273771Z","caller":"traceutil/trace.go:171","msg":"trace[1245901630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25301; }","duration":"409.839165ms","start":"2026-06-02T18:16:57.863905Z","end":"2026-06-02T18:16:58.273744Z","steps":["trace[1245901630] 'agreement among raft nodes before linearized reading' (duration: 409.532137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:58.273765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:57.965537Z","time spent":"308.101679ms","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:58.273818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:57.863882Z","time spent":"409.926887ms","remote":"127.0.0.1:41070","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-06-02T18:16:58.273319Z","caller":"traceutil/trace.go:171","msg":"trace[1206413083] linearizableReadLoop","detail":"{readStateIndex:26126; appliedIndex:26123; }","duration":"409.323423ms","start":"2026-06-02T18:16:57.863964Z","end":"2026-06-02T18:16:58.273288Z","steps":["trace[1206413083] 'read index received' (duration: 71.101382ms)","trace[1206413083] 'applied index is now lower than readState.Index' (duration: 338.220791ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:58.274713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.199225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c0c8ee2c-7379-415c-a37e-742e31f83322\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-06-02T18:16:58.274761Z","caller":"traceutil/trace.go:171","msg":"trace[1243199576] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c0c8ee2c-7379-415c-a37e-742e31f83322; range_end:; response_count:1; response_revision:25302; }","duration":"375.305498ms","start":"2026-06-02T18:16:57.899439Z","end":"2026-06-02T18:16:58.274745Z","steps":["trace[1243199576] 'agreement among raft nodes before linearized reading' (duration: 375.140314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:58.274799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:57.899412Z","time spent":"375.37654ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c0c8ee2c-7379-415c-a37e-742e31f83322\" "} {"level":"info","ts":"2026-06-02T18:17:09.026778Z","caller":"traceutil/trace.go:171","msg":"trace[1431709872] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"125.575198ms","start":"2026-06-02T18:17:08.901169Z","end":"2026-06-02T18:17:09.026745Z","steps":["trace[1431709872] 'process raft request' (duration: 125.362962ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:09.348293Z","caller":"traceutil/trace.go:171","msg":"trace[1333792839] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"442.030716ms","start":"2026-06-02T18:17:08.906224Z","end":"2026-06-02T18:17:09.348254Z","steps":["trace[1333792839] 'process raft request' (duration: 420.598703ms)","trace[1333792839] 'compare' (duration: 21.239148ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:09.348511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:17:08.906189Z","time spent":"442.202099ms","remote":"127.0.0.1:41070","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-06-02T18:17:11.132145Z","caller":"traceutil/trace.go:171","msg":"trace[1337454542] linearizableReadLoop","detail":"{readStateIndex:26249; appliedIndex:26248; }","duration":"147.033861ms","start":"2026-06-02T18:17:10.985085Z","end":"2026-06-02T18:17:11.132119Z","steps":["trace[1337454542] 'read index received' (duration: 127.205086ms)","trace[1337454542] 'applied index is now lower than readState.Index' (duration: 19.827995ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:11.132405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.309307ms","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-06-02T18:17:11.132449Z","caller":"traceutil/trace.go:171","msg":"trace[874671987] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25422; }","duration":"147.402299ms","start":"2026-06-02T18:17:10.985034Z","end":"2026-06-02T18:17:11.132436Z","steps":["trace[874671987] 'agreement among raft nodes before linearized reading' (duration: 147.196604ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:11.132592Z","caller":"traceutil/trace.go:171","msg":"trace[1097830179] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"151.325004ms","start":"2026-06-02T18:17:10.981249Z","end":"2026-06-02T18:17:11.132574Z","steps":["trace[1097830179] 'process raft request' (duration: 130.988647ms)","trace[1097830179] 'compare' (duration: 19.685181ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:11.181243Z","caller":"traceutil/trace.go:171","msg":"trace[1622455564] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"148.423345ms","start":"2026-06-02T18:17:11.032771Z","end":"2026-06-02T18:17:11.181194Z","steps":["trace[1622455564] 'process raft request' (duration: 148.059236ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:11.309214Z","caller":"traceutil/trace.go:171","msg":"trace[1825318078] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"120.106756ms","start":"2026-06-02T18:17:11.189039Z","end":"2026-06-02T18:17:11.309146Z","steps":["trace[1825318078] 'process raft request' (duration: 119.816169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:20.828803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.215062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-06-02T18:17:20.828897Z","caller":"traceutil/trace.go:171","msg":"trace[432215479] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25497; }","duration":"108.385096ms","start":"2026-06-02T18:17:20.720486Z","end":"2026-06-02T18:17:20.828872Z","steps":["trace[432215479] 'range keys from in-memory index tree' (duration: 107.953315ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:28.061708Z","caller":"traceutil/trace.go:171","msg":"trace[15279061] linearizableReadLoop","detail":"{readStateIndex:26392; appliedIndex:26389; }","duration":"102.619347ms","start":"2026-06-02T18:17:27.959067Z","end":"2026-06-02T18:17:28.061686Z","steps":["trace[15279061] 'read index received' (duration: 23.245377ms)","trace[15279061] 'applied index is now lower than readState.Index' (duration: 79.37286ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:28.061986Z","caller":"traceutil/trace.go:171","msg":"trace[1375126159] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"110.770114ms","start":"2026-06-02T18:17:27.951204Z","end":"2026-06-02T18:17:28.061974Z","steps":["trace[1375126159] 'process raft request' (duration: 96.972503ms)","trace[1375126159] 'compare' (duration: 13.030612ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:28.062182Z","caller":"traceutil/trace.go:171","msg":"trace[59062583] transaction","detail":"{read_only:false; response_revision:25561; number_of_response:1; }","duration":"110.675562ms","start":"2026-06-02T18:17:27.951499Z","end":"2026-06-02T18:17:28.062175Z","steps":["trace[59062583] 'process raft request' (duration: 110.130398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:28.062413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.324315ms","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-06-02T18:17:28.062454Z","caller":"traceutil/trace.go:171","msg":"trace[1140730753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25561; }","duration":"103.423797ms","start":"2026-06-02T18:17:27.959021Z","end":"2026-06-02T18:17:28.062445Z","steps":["trace[1140730753] 'agreement among raft nodes before linearized reading' (duration: 103.273354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:28.062629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.517937ms","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-06-02T18:17:28.062652Z","caller":"traceutil/trace.go:171","msg":"trace[346091748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25561; }","duration":"100.553709ms","start":"2026-06-02T18:17:27.962091Z","end":"2026-06-02T18:17:28.062645Z","steps":["trace[346091748] 'agreement among raft nodes before linearized reading' (duration: 100.488347ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:28.335009Z","caller":"traceutil/trace.go:171","msg":"trace[103913769] linearizableReadLoop","detail":"{readStateIndex:26394; appliedIndex:26393; }","duration":"185.043942ms","start":"2026-06-02T18:17:28.149941Z","end":"2026-06-02T18:17:28.334985Z","steps":["trace[103913769] 'read index received' (duration: 184.841157ms)","trace[103913769] 'applied index is now lower than readState.Index' (duration: 201.135µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:28.335107Z","caller":"traceutil/trace.go:171","msg":"trace[1614020258] transaction","detail":"{read_only:false; response_revision:25563; number_of_response:1; }","duration":"265.468918ms","start":"2026-06-02T18:17:28.0696Z","end":"2026-06-02T18:17:28.335069Z","steps":["trace[1614020258] 'process raft request' (duration: 265.15094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:28.335278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.307628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"info","ts":"2026-06-02T18:17:28.335382Z","caller":"traceutil/trace.go:171","msg":"trace[932445693] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:25563; }","duration":"185.477052ms","start":"2026-06-02T18:17:28.14988Z","end":"2026-06-02T18:17:28.335357Z","steps":["trace[932445693] 'agreement among raft nodes before linearized reading' (duration: 185.193515ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:28.597845Z","caller":"traceutil/trace.go:171","msg":"trace[1378902084] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"126.987162ms","start":"2026-06-02T18:17:28.470822Z","end":"2026-06-02T18:17:28.59781Z","steps":["trace[1378902084] 'process raft request' (duration: 49.232299ms)","trace[1378902084] 'compare' (duration: 77.415375ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:45.088188Z","caller":"traceutil/trace.go:171","msg":"trace[1725205748] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"146.405846ms","start":"2026-06-02T18:17:44.941747Z","end":"2026-06-02T18:17:45.088153Z","steps":["trace[1725205748] 'process raft request' (duration: 65.861868ms)","trace[1725205748] 'compare' (duration: 80.387285ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:45.90319Z","caller":"traceutil/trace.go:171","msg":"trace[166329581] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"179.459248ms","start":"2026-06-02T18:17:45.723696Z","end":"2026-06-02T18:17:45.903155Z","steps":["trace[166329581] 'process raft request' (duration: 179.259063ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:46.019165Z","caller":"traceutil/trace.go:171","msg":"trace[1210472975] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"222.615172ms","start":"2026-06-02T18:17:45.796518Z","end":"2026-06-02T18:17:46.019133Z","steps":["trace[1210472975] 'process raft request' (duration: 196.694581ms)","trace[1210472975] 'compare' (duration: 25.764127ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:46.242902Z","caller":"traceutil/trace.go:171","msg":"trace[165105040] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"117.938616ms","start":"2026-06-02T18:17:46.124895Z","end":"2026-06-02T18:17:46.242834Z","steps":["trace[165105040] 'process raft request' (duration: 68.738867ms)","trace[165105040] 'compare' (duration: 48.780718ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:46.243118Z","caller":"traceutil/trace.go:171","msg":"trace[911883095] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"100.893647ms","start":"2026-06-02T18:17:46.142189Z","end":"2026-06-02T18:17:46.243083Z","steps":["trace[911883095] 'process raft request' (duration: 100.469076ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:50.174162Z","caller":"traceutil/trace.go:171","msg":"trace[1455174700] transaction","detail":"{read_only:false; response_revision:25731; number_of_response:1; }","duration":"140.893574ms","start":"2026-06-02T18:17:50.033231Z","end":"2026-06-02T18:17:50.174124Z","steps":["trace[1455174700] 'process raft request' (duration: 84.356721ms)","trace[1455174700] 'compare' (duration: 55.99369ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:55.907592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.614442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-06-02T18:17:55.907724Z","caller":"traceutil/trace.go:171","msg":"trace[1176434833] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25774; }","duration":"126.781377ms","start":"2026-06-02T18:17:55.780893Z","end":"2026-06-02T18:17:55.907674Z","steps":["trace[1176434833] 'range keys from in-memory index tree' (duration: 124.462631ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:56.363909Z","caller":"traceutil/trace.go:171","msg":"trace[250065316] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"160.821391ms","start":"2026-06-02T18:17:56.203055Z","end":"2026-06-02T18:17:56.363876Z","steps":["trace[250065316] 'process raft request' (duration: 160.33664ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:56.589253Z","caller":"traceutil/trace.go:171","msg":"trace[1977551280] linearizableReadLoop","detail":"{readStateIndex:26616; appliedIndex:26615; }","duration":"155.180657ms","start":"2026-06-02T18:17:56.434044Z","end":"2026-06-02T18:17:56.589224Z","steps":["trace[1977551280] 'read index received' (duration: 62.875516ms)","trace[1977551280] 'applied index is now lower than readState.Index' (duration: 92.303841ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:56.589366Z","caller":"traceutil/trace.go:171","msg":"trace[499884628] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"158.494996ms","start":"2026-06-02T18:17:56.430831Z","end":"2026-06-02T18:17:56.589326Z","steps":["trace[499884628] 'process raft request' (duration: 66.221486ms)","trace[499884628] 'compare' (duration: 91.957743ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:56.589539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.476574ms","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-06-02T18:17:56.589626Z","caller":"traceutil/trace.go:171","msg":"trace[664683427] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25780; }","duration":"155.587967ms","start":"2026-06-02T18:17:56.434021Z","end":"2026-06-02T18:17:56.589609Z","steps":["trace[664683427] 'agreement among raft nodes before linearized reading' (duration: 155.324111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:56.844239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.951003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:17:56.844379Z","caller":"traceutil/trace.go:171","msg":"trace[1839809506] linearizableReadLoop","detail":"{readStateIndex:26618; appliedIndex:26617; }","duration":"179.536719ms","start":"2026-06-02T18:17:56.664823Z","end":"2026-06-02T18:17:56.84436Z","steps":["trace[1839809506] 'read index received' (duration: 36.312769ms)","trace[1839809506] 'applied index is now lower than readState.Index' (duration: 143.22249ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:56.844583Z","caller":"traceutil/trace.go:171","msg":"trace[1291291624] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"249.67463ms","start":"2026-06-02T18:17:56.594864Z","end":"2026-06-02T18:17:56.844539Z","steps":["trace[1291291624] 'process raft request' (duration: 106.302746ms)","trace[1291291624] 'compare' (duration: 142.761839ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:56.844886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.038342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:17:56.845608Z","caller":"traceutil/trace.go:171","msg":"trace[763087618] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25782; }","duration":"180.81266ms","start":"2026-06-02T18:17:56.66477Z","end":"2026-06-02T18:17:56.845583Z","steps":["trace[763087618] 'agreement among raft nodes before linearized reading' (duration: 179.866558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:57.097088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.370679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:17:57.097216Z","caller":"traceutil/trace.go:171","msg":"trace[2130415564] linearizableReadLoop","detail":"{readStateIndex:26619; appliedIndex:26618; }","duration":"252.790014ms","start":"2026-06-02T18:17:56.844402Z","end":"2026-06-02T18:17:57.097192Z","steps":["trace[2130415564] 'read index received' (duration: 117.910594ms)","trace[2130415564] 'applied index is now lower than readState.Index' (duration: 134.87742ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:57.097312Z","caller":"traceutil/trace.go:171","msg":"trace[362647208] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"273.285285ms","start":"2026-06-02T18:17:56.823983Z","end":"2026-06-02T18:17:57.097268Z","steps":["trace[362647208] 'process raft request' (duration: 138.574579ms)","trace[362647208] 'compare' (duration: 134.064441ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:57.097687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.16768ms","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-06-02T18:17:57.097783Z","caller":"traceutil/trace.go:171","msg":"trace[699577704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25783; }","duration":"351.286122ms","start":"2026-06-02T18:17:56.746475Z","end":"2026-06-02T18:17:57.097761Z","steps":["trace[699577704] 'agreement among raft nodes before linearized reading' (duration: 350.960255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:57.097719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.955238ms","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-06-02T18:17:57.097809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.320427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:17:57.097885Z","caller":"traceutil/trace.go:171","msg":"trace[338379756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25783; }","duration":"101.158133ms","start":"2026-06-02T18:17:56.996701Z","end":"2026-06-02T18:17:57.097859Z","steps":["trace[338379756] 'agreement among raft nodes before linearized reading' (duration: 100.917747ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.097934Z","caller":"traceutil/trace.go:171","msg":"trace[1874137617] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25783; }","duration":"129.527372ms","start":"2026-06-02T18:17:56.96838Z","end":"2026-06-02T18:17:57.097907Z","steps":["trace[1874137617] 'agreement among raft nodes before linearized reading' (duration: 129.264086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:57.097827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:17:56.746409Z","time spent":"351.408336ms","remote":"127.0.0.1:41070","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":"warn","ts":"2026-06-02T18:17:57.098411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.964448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:17:57.098469Z","caller":"traceutil/trace.go:171","msg":"trace[550478722] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25783; }","duration":"248.574554ms","start":"2026-06-02T18:17:56.849879Z","end":"2026-06-02T18:17:57.098454Z","steps":["trace[550478722] 'agreement among raft nodes before linearized reading' (duration: 247.735693ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.323936Z","caller":"traceutil/trace.go:171","msg":"trace[40579284] linearizableReadLoop","detail":"{readStateIndex:26622; appliedIndex:26621; }","duration":"126.275704ms","start":"2026-06-02T18:17:57.197542Z","end":"2026-06-02T18:17:57.323818Z","steps":["trace[40579284] 'read index received' (duration: 117.048443ms)","trace[40579284] 'applied index is now lower than readState.Index' (duration: 9.225231ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:57.324245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.682504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-06-02T18:17:57.324289Z","caller":"traceutil/trace.go:171","msg":"trace[1679587839] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25786; }","duration":"126.795346ms","start":"2026-06-02T18:17:57.197481Z","end":"2026-06-02T18:17:57.324276Z","steps":["trace[1679587839] 'agreement among raft nodes before linearized reading' (duration: 126.543741ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.324273Z","caller":"traceutil/trace.go:171","msg":"trace[1699738169] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"133.614459ms","start":"2026-06-02T18:17:57.190616Z","end":"2026-06-02T18:17:57.32423Z","steps":["trace[1699738169] 'process raft request' (duration: 123.949398ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.799583Z","caller":"traceutil/trace.go:171","msg":"trace[1985807409] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"124.248585ms","start":"2026-06-02T18:17:57.675294Z","end":"2026-06-02T18:17:57.799543Z","steps":["trace[1985807409] 'process raft request' (duration: 123.972788ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:59.334406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22939} {"level":"info","ts":"2026-06-02T18:17:59.482502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22939,"took":"142.599365ms","hash":2984445088,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":35364864,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-02T18:17:59.482692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2984445088,"revision":22939,"compact-revision":20134} {"level":"info","ts":"2026-06-02T18:19:07.607464Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006aef.wal"} {"level":"info","ts":"2026-06-02T18:22:59.341709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25800} {"level":"info","ts":"2026-06-02T18:22:59.479791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25800,"took":"133.120537ms","hash":3013902165,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":37871616,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-06-02T18:22:59.479856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3013902165,"revision":25800,"compact-revision":22939} {"level":"info","ts":"2026-06-02T18:23:54.613824Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T18:23:54.620569Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-02T18:23:54.62069Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-02T18:27:59.351958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28670} {"level":"info","ts":"2026-06-02T18:27:59.493673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28670,"took":"137.240541ms","hash":652905629,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":30760960,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T18:27:59.49375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":652905629,"revision":28670,"compact-revision":25800} {"level":"info","ts":"2026-06-02T18:32:59.358697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30891} {"level":"info","ts":"2026-06-02T18:32:59.486553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30891,"took":"121.821453ms","hash":2821810042,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":31174656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T18:32:59.48663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2821810042,"revision":30891,"compact-revision":28670} {"level":"info","ts":"2026-06-02T18:37:59.366028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33154} {"level":"info","ts":"2026-06-02T18:37:59.522312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33154,"took":"150.410015ms","hash":3506314137,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T18:37:59.522379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3506314137,"revision":33154,"compact-revision":30891} {"level":"info","ts":"2026-06-02T18:42:59.377182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35448} {"level":"info","ts":"2026-06-02T18:42:59.529791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35448,"took":"148.741945ms","hash":1301100236,"current-db-size-bytes":60964864,"current-db-size":"61 MB","current-db-size-in-use-bytes":31477760,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T18:42:59.529884Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1301100236,"revision":35448,"compact-revision":33154} {"level":"info","ts":"2026-06-02T18:45:21.717492Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T18:45:21.722406Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-02T18:45:21.722579Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-02T18:47:59.384987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37674} {"level":"info","ts":"2026-06-02T18:47:59.507578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37674,"took":"118.296507ms","hash":2771460920,"current-db-size-bytes":60964864,"current-db-size":"61 MB","current-db-size-in-use-bytes":31469568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T18:47:59.507664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2771460920,"revision":37674,"compact-revision":35448}