{"level":"warn","ts":"2026-02-24T19:58:33.169775Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T19:58:33.169851Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-02-24T19:58:33.169917Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T19:58:33.169926Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-24T19:58:33.169943Z","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-02-24T19:58:33.170552Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-02-24T19:58:33.170628Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-02-24T19:58:33.176046Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.208529ms"} {"level":"info","ts":"2026-02-24T19:58:33.184205Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-02-24T19:58:33.184315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T19:58:33.184349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-02-24T19:58:33.184375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T19:58:33.184404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-02-24T19:58:33.184459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-02-24T19:58:33.195884Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T19:58:33.197342Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T19:58:33.198493Z","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-02-24T19:58:33.200793Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T19:58:33.202068Z","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-02-24T19:58:33.202743Z","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-02-24T19:58:33.202796Z","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-02-24T19:58:33.202616Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T19:58:33.203433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-02-24T19:58:33.206037Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-24T19:58:33.208517Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-24T19:58:33.20855Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-24T19:58:33.208354Z","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-02-24T19:58:33.209729Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T19:58:33.209783Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T19:58:33.985663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T19:58:33.985772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T19:58:33.985801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-02-24T19:58:33.985824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-02-24T19:58:33.985836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-24T19:58:33.985854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-02-24T19:58:33.985869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-24T19:58:33.987514Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T19:58:33.987567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T19:58:33.987658Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:58:33.987729Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T19:58:33.987935Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T19:58:33.98808Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T19:58:33.98892Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:58:33.989075Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:58:33.98916Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:58:33.991293Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T19:58:33.991876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-02-24T19:58:47.85483Z","caller":"traceutil/trace.go:171","msg":"trace[1773290595] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"119.39481ms","start":"2026-02-24T19:58:47.735408Z","end":"2026-02-24T19:58:47.854803Z","steps":["trace[1773290595] 'process raft request' (duration: 118.958939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:02:02.274768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.418517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T20:02:02.274912Z","caller":"traceutil/trace.go:171","msg":"trace[781311585] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2035; }","duration":"109.623782ms","start":"2026-02-24T20:02:02.165267Z","end":"2026-02-24T20:02:02.274891Z","steps":["trace[781311585] 'count revisions from in-memory index tree' (duration: 109.303722ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:02:38.36789Z","caller":"traceutil/trace.go:171","msg":"trace[2131573835] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"162.266494ms","start":"2026-02-24T20:02:38.205572Z","end":"2026-02-24T20:02:38.367839Z","steps":["trace[2131573835] 'process raft request' (duration: 161.920704ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:03:39.856351Z","caller":"traceutil/trace.go:171","msg":"trace[1301144096] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"140.663271ms","start":"2026-02-24T20:03:39.715652Z","end":"2026-02-24T20:03:39.856315Z","steps":["trace[1301144096] 'process raft request' (duration: 140.447745ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:04:13.013057Z","caller":"traceutil/trace.go:171","msg":"trace[1025773860] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"106.794639ms","start":"2026-02-24T20:04:12.906231Z","end":"2026-02-24T20:04:13.013026Z","steps":["trace[1025773860] 'process raft request' (duration: 106.597604ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:03.119606Z","caller":"traceutil/trace.go:171","msg":"trace[671945630] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"174.363804ms","start":"2026-02-24T20:08:02.945202Z","end":"2026-02-24T20:08:03.119566Z","steps":["trace[671945630] 'process raft request' (duration: 123.369936ms)","trace[671945630] 'compare' (duration: 50.786003ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:08:03.119889Z","caller":"traceutil/trace.go:171","msg":"trace[1080800297] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4984; }","duration":"152.995345ms","start":"2026-02-24T20:08:02.966858Z","end":"2026-02-24T20:08:03.119853Z","steps":["trace[1080800297] 'read index received' (duration: 33.332467ms)","trace[1080800297] 'applied index is now lower than readState.Index' (duration: 119.660857ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:08:03.120074Z","caller":"traceutil/trace.go:171","msg":"trace[528284604] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"173.973384ms","start":"2026-02-24T20:08:02.946082Z","end":"2026-02-24T20:08:03.120055Z","steps":["trace[528284604] 'process raft request' (duration: 173.413069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:08:03.120173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.289952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-02-24T20:08:03.120285Z","caller":"traceutil/trace.go:171","msg":"trace[1249248261] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4789; }","duration":"153.465946ms","start":"2026-02-24T20:08:02.9668Z","end":"2026-02-24T20:08:03.120266Z","steps":["trace[1249248261] 'agreement among raft nodes before linearized reading' (duration: 153.175229ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:03.385181Z","caller":"traceutil/trace.go:171","msg":"trace[1176707222] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"118.813775ms","start":"2026-02-24T20:08:03.266331Z","end":"2026-02-24T20:08:03.385145Z","steps":["trace[1176707222] 'process raft request' (duration: 85.391455ms)","trace[1176707222] 'compare' (duration: 33.233155ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:08:03.59178Z","caller":"traceutil/trace.go:171","msg":"trace[345394456] linearizableReadLoop","detail":"{readStateIndex:4991; appliedIndex:4990; }","duration":"198.575239ms","start":"2026-02-24T20:08:03.393177Z","end":"2026-02-24T20:08:03.591752Z","steps":["trace[345394456] 'read index received' (duration: 198.472766ms)","trace[345394456] 'applied index is now lower than readState.Index' (duration: 101.173µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:08:03.591884Z","caller":"traceutil/trace.go:171","msg":"trace[756703255] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"198.890527ms","start":"2026-02-24T20:08:03.392942Z","end":"2026-02-24T20:08:03.591833Z","steps":["trace[756703255] 'process raft request' (duration: 198.652801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:08:03.591949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.755794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T20:08:03.59203Z","caller":"traceutil/trace.go:171","msg":"trace[1903645205] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:0; response_revision:4793; }","duration":"198.911038ms","start":"2026-02-24T20:08:03.393104Z","end":"2026-02-24T20:08:03.592015Z","steps":["trace[1903645205] 'agreement among raft nodes before linearized reading' (duration: 198.774064ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:03.596658Z","caller":"traceutil/trace.go:171","msg":"trace[1415814939] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"202.958425ms","start":"2026-02-24T20:08:03.393674Z","end":"2026-02-24T20:08:03.596633Z","steps":["trace[1415814939] 'process raft request' (duration: 202.328559ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:03.597085Z","caller":"traceutil/trace.go:171","msg":"trace[615166746] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"202.326719ms","start":"2026-02-24T20:08:03.394719Z","end":"2026-02-24T20:08:03.597046Z","steps":["trace[615166746] 'process raft request' (duration: 201.432206ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:06.142654Z","caller":"traceutil/trace.go:171","msg":"trace[290044640] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"208.275907ms","start":"2026-02-24T20:08:05.934334Z","end":"2026-02-24T20:08:06.142609Z","steps":["trace[290044640] 'process raft request' (duration: 208.074792ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:06.298815Z","caller":"traceutil/trace.go:171","msg":"trace[1521671352] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5120; }","duration":"192.277501ms","start":"2026-02-24T20:08:06.10651Z","end":"2026-02-24T20:08:06.298787Z","steps":["trace[1521671352] 'read index received' (duration: 35.960838ms)","trace[1521671352] 'applied index is now lower than readState.Index' (duration: 156.315933ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:08:06.299001Z","caller":"traceutil/trace.go:171","msg":"trace[922573426] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"341.560337ms","start":"2026-02-24T20:08:05.957387Z","end":"2026-02-24T20:08:06.298947Z","steps":["trace[922573426] 'process raft request' (duration: 341.257659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:08:06.2991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.57168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ce24285e-46d3-4a89-8625-1ea3a3a49e6f\" ","response":"range_response_count:1 size:3557"} {"level":"info","ts":"2026-02-24T20:08:06.299144Z","caller":"traceutil/trace.go:171","msg":"trace[363246904] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ce24285e-46d3-4a89-8625-1ea3a3a49e6f; range_end:; response_count:1; response_revision:4921; }","duration":"192.675792ms","start":"2026-02-24T20:08:06.106458Z","end":"2026-02-24T20:08:06.299134Z","steps":["trace[363246904] 'agreement among raft nodes before linearized reading' (duration: 192.464177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:08:06.299611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:08:05.957363Z","time spent":"341.728962ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4866,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T20:08:34.014123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2616} {"level":"info","ts":"2026-02-24T20:08:34.064689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2616,"took":"49.480587ms","hash":1245631935,"current-db-size-bytes":38395904,"current-db-size":"38 MB","current-db-size-in-use-bytes":28368896,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-24T20:08:34.064753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245631935,"revision":2616,"compact-revision":-1} {"level":"info","ts":"2026-02-24T20:08:37.409153Z","caller":"traceutil/trace.go:171","msg":"trace[506407185] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"108.132079ms","start":"2026-02-24T20:08:37.300988Z","end":"2026-02-24T20:08:37.40912Z","steps":["trace[506407185] 'process raft request' (duration: 89.274648ms)","trace[506407185] 'compare' (duration: 18.656096ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:08:37.409427Z","caller":"traceutil/trace.go:171","msg":"trace[830461387] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"108.198752ms","start":"2026-02-24T20:08:37.301199Z","end":"2026-02-24T20:08:37.409397Z","steps":["trace[830461387] 'process raft request' (duration: 107.857853ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:37.409576Z","caller":"traceutil/trace.go:171","msg":"trace[2079609026] linearizableReadLoop","detail":"{readStateIndex:5676; appliedIndex:5674; }","duration":"108.392336ms","start":"2026-02-24T20:08:37.301143Z","end":"2026-02-24T20:08:37.409535Z","steps":["trace[2079609026] 'read index received' (duration: 48.424619ms)","trace[2079609026] 'applied index is now lower than readState.Index' (duration: 59.966477ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:08:37.409591Z","caller":"traceutil/trace.go:171","msg":"trace[262528861] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"106.320472ms","start":"2026-02-24T20:08:37.303239Z","end":"2026-02-24T20:08:37.40956Z","steps":["trace[262528861] 'process raft request' (duration: 106.23096ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:37.409578Z","caller":"traceutil/trace.go:171","msg":"trace[963743305] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"108.363395ms","start":"2026-02-24T20:08:37.30118Z","end":"2026-02-24T20:08:37.409543Z","steps":["trace[963743305] 'process raft request' (duration: 108.14115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:08:37.409799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.336488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-24T20:08:37.409855Z","caller":"traceutil/trace.go:171","msg":"trace[120769651] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5461; }","duration":"107.434212ms","start":"2026-02-24T20:08:37.302404Z","end":"2026-02-24T20:08:37.409838Z","steps":["trace[120769651] 'agreement among raft nodes before linearized reading' (duration: 107.319668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:08:37.410251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.068075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-24T20:08:37.410349Z","caller":"traceutil/trace.go:171","msg":"trace[1259372982] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5461; }","duration":"109.206219ms","start":"2026-02-24T20:08:37.30112Z","end":"2026-02-24T20:08:37.410326Z","steps":["trace[1259372982] 'agreement among raft nodes before linearized reading' (duration: 108.549491ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:13:34.025109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5431} {"level":"info","ts":"2026-02-24T20:13:34.11874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5431,"took":"91.009154ms","hash":3858781874,"current-db-size-bytes":40742912,"current-db-size":"41 MB","current-db-size-in-use-bytes":22249472,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-02-24T20:13:34.118813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3858781874,"revision":5431,"compact-revision":2616} {"level":"info","ts":"2026-02-24T20:14:21.905484Z","caller":"traceutil/trace.go:171","msg":"trace[172161956] transaction","detail":"{read_only:false; response_revision:7913; number_of_response:1; }","duration":"322.269606ms","start":"2026-02-24T20:14:21.583189Z","end":"2026-02-24T20:14:21.905458Z","steps":["trace[172161956] 'process raft request' (duration: 321.897896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:14:21.905784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:14:21.583165Z","time spent":"322.381519ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:14:31.463939Z","caller":"traceutil/trace.go:171","msg":"trace[513858943] transaction","detail":"{read_only:false; response_revision:7960; number_of_response:1; }","duration":"168.369203ms","start":"2026-02-24T20:14:31.295542Z","end":"2026-02-24T20:14:31.463911Z","steps":["trace[513858943] 'process raft request' (duration: 168.150757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:14:32.451945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.892382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T20:14:32.452124Z","caller":"traceutil/trace.go:171","msg":"trace[809317020] linearizableReadLoop","detail":"{readStateIndex:8267; appliedIndex:8266; }","duration":"174.788527ms","start":"2026-02-24T20:14:32.277313Z","end":"2026-02-24T20:14:32.452101Z","steps":["trace[809317020] 'read index received' (duration: 57.122µs)","trace[809317020] 'applied index is now lower than readState.Index' (duration: 174.729405ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:14:32.452177Z","caller":"traceutil/trace.go:171","msg":"trace[252677176] transaction","detail":"{read_only:false; response_revision:7963; number_of_response:1; }","duration":"280.382737ms","start":"2026-02-24T20:14:32.171748Z","end":"2026-02-24T20:14:32.452131Z","steps":["trace[252677176] 'process raft request' (duration: 35.037212ms)","trace[252677176] 'compare' (duration: 244.716038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:14:32.452352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.038583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T20:14:32.452393Z","caller":"traceutil/trace.go:171","msg":"trace[1022910099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7963; }","duration":"175.146436ms","start":"2026-02-24T20:14:32.277236Z","end":"2026-02-24T20:14:32.452382Z","steps":["trace[1022910099] 'agreement among raft nodes before linearized reading' (duration: 174.963901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:14:32.503974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.950626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12011"} {"level":"info","ts":"2026-02-24T20:14:32.504061Z","caller":"traceutil/trace.go:171","msg":"trace[1802654593] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7964; }","duration":"147.098219ms","start":"2026-02-24T20:14:32.356944Z","end":"2026-02-24T20:14:32.504042Z","steps":["trace[1802654593] 'agreement among raft nodes before linearized reading' (duration: 146.769481ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:15:02.296708Z","caller":"traceutil/trace.go:171","msg":"trace[1895780748] transaction","detail":"{read_only:false; response_revision:8162; number_of_response:1; }","duration":"101.698648ms","start":"2026-02-24T20:15:02.194987Z","end":"2026-02-24T20:15:02.296686Z","steps":["trace[1895780748] 'process raft request' (duration: 42.779801ms)","trace[1895780748] 'compare' (duration: 58.825934ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:15:20.694061Z","caller":"traceutil/trace.go:171","msg":"trace[277260043] linearizableReadLoop","detail":"{readStateIndex:8610; appliedIndex:8609; }","duration":"185.311689ms","start":"2026-02-24T20:15:20.508708Z","end":"2026-02-24T20:15:20.694019Z","steps":["trace[277260043] 'read index received' (duration: 159.414862ms)","trace[277260043] 'applied index is now lower than readState.Index' (duration: 25.894867ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:15:20.69445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.723261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-9zhpk\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-02-24T20:15:20.694539Z","caller":"traceutil/trace.go:171","msg":"trace[1099701640] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-9zhpk; range_end:; response_count:1; response_revision:8291; }","duration":"185.850224ms","start":"2026-02-24T20:15:20.50867Z","end":"2026-02-24T20:15:20.69452Z","steps":["trace[1099701640] 'agreement among raft nodes before linearized reading' (duration: 185.473233ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:15:20.694104Z","caller":"traceutil/trace.go:171","msg":"trace[867238504] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"185.733549ms","start":"2026-02-24T20:15:20.508298Z","end":"2026-02-24T20:15:20.694032Z","steps":["trace[867238504] 'process raft request' (duration: 159.807042ms)","trace[867238504] 'compare' (duration: 25.570768ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:15:20.855493Z","caller":"traceutil/trace.go:171","msg":"trace[1893960295] linearizableReadLoop","detail":"{readStateIndex:8612; appliedIndex:8611; }","duration":"153.166013ms","start":"2026-02-24T20:15:20.702284Z","end":"2026-02-24T20:15:20.85545Z","steps":["trace[1893960295] 'read index received' (duration: 106.337912ms)","trace[1893960295] 'applied index is now lower than readState.Index' (duration: 46.826701ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:15:20.855556Z","caller":"traceutil/trace.go:171","msg":"trace[1279678983] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8293; }","duration":"158.2448ms","start":"2026-02-24T20:15:20.697261Z","end":"2026-02-24T20:15:20.855506Z","steps":["trace[1279678983] 'process raft request' (duration: 111.347327ms)","trace[1279678983] 'compare' (duration: 46.641286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:15:20.855786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.646969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T20:15:20.855845Z","caller":"traceutil/trace.go:171","msg":"trace[1248196649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8293; }","duration":"152.759281ms","start":"2026-02-24T20:15:20.703067Z","end":"2026-02-24T20:15:20.855827Z","steps":["trace[1248196649] 'agreement among raft nodes before linearized reading' (duration: 152.622708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:15:20.855857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.149241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9zhpk\" ","response":"range_response_count:1 size:4521"} {"level":"info","ts":"2026-02-24T20:15:20.855978Z","caller":"traceutil/trace.go:171","msg":"trace[31860095] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-9zhpk; range_end:; response_count:1; response_revision:8293; }","duration":"157.333766ms","start":"2026-02-24T20:15:20.698617Z","end":"2026-02-24T20:15:20.855951Z","steps":["trace[31860095] 'agreement among raft nodes before linearized reading' (duration: 156.991887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:15:20.855909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.27459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T20:15:20.856121Z","caller":"traceutil/trace.go:171","msg":"trace[1511436908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8293; }","duration":"100.556228ms","start":"2026-02-24T20:15:20.755547Z","end":"2026-02-24T20:15:20.856103Z","steps":["trace[1511436908] 'agreement among raft nodes before linearized reading' (duration: 100.245989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:15:20.856481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.837769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T20:15:20.856597Z","caller":"traceutil/trace.go:171","msg":"trace[32613661] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8293; }","duration":"132.109486ms","start":"2026-02-24T20:15:20.724469Z","end":"2026-02-24T20:15:20.856579Z","steps":["trace[32613661] 'agreement among raft nodes before linearized reading' (duration: 131.5037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:15:21.648656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.547961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T20:15:21.648793Z","caller":"traceutil/trace.go:171","msg":"trace[941042418] transaction","detail":"{read_only:false; response_revision:8296; number_of_response:1; }","duration":"306.037469ms","start":"2026-02-24T20:15:21.342728Z","end":"2026-02-24T20:15:21.648765Z","steps":["trace[941042418] 'process raft request' (duration: 135.262181ms)","trace[941042418] 'compare' (duration: 170.377887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:15:21.648884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:15:21.342713Z","time spent":"306.138421ms","remote":"127.0.0.1:56680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T20:15:21.667359Z","caller":"traceutil/trace.go:171","msg":"trace[245972870] linearizableReadLoop","detail":"{readStateIndex:8616; appliedIndex:8614; }","duration":"283.850321ms","start":"2026-02-24T20:15:21.383486Z","end":"2026-02-24T20:15:21.667337Z","steps":["trace[245972870] 'read index received' (duration: 94.522554ms)","trace[245972870] 'applied index is now lower than readState.Index' (duration: 189.322917ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:15:21.667496Z","caller":"traceutil/trace.go:171","msg":"trace[1979043682] transaction","detail":"{read_only:false; response_revision:8297; number_of_response:1; }","duration":"307.380095ms","start":"2026-02-24T20:15:21.360062Z","end":"2026-02-24T20:15:21.667442Z","steps":["trace[1979043682] 'process raft request' (duration: 307.132778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:15:21.667593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.089457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-24T20:15:21.667633Z","caller":"traceutil/trace.go:171","msg":"trace[2026256149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8297; }","duration":"284.143549ms","start":"2026-02-24T20:15:21.383475Z","end":"2026-02-24T20:15:21.667619Z","steps":["trace[2026256149] 'agreement among raft nodes before linearized reading' (duration: 283.996885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:15:21.667707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:15:21.360037Z","time spent":"307.55003ms","remote":"127.0.0.1:56820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2993,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:15:21.667702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.948253ms","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-02-24T20:15:21.66783Z","caller":"traceutil/trace.go:171","msg":"trace[44390902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8297; }","duration":"284.071037ms","start":"2026-02-24T20:15:21.383722Z","end":"2026-02-24T20:15:21.667793Z","steps":["trace[44390902] 'agreement among raft nodes before linearized reading' (duration: 283.79588ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:15:22.331022Z","caller":"traceutil/trace.go:171","msg":"trace[1772058446] transaction","detail":"{read_only:false; response_revision:8303; number_of_response:1; }","duration":"160.231503ms","start":"2026-02-24T20:15:22.170759Z","end":"2026-02-24T20:15:22.330991Z","steps":["trace[1772058446] 'process raft request' (duration: 160.053988ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:15:22.39508Z","caller":"traceutil/trace.go:171","msg":"trace[1939251593] linearizableReadLoop","detail":"{readStateIndex:8623; appliedIndex:8621; }","duration":"122.666173ms","start":"2026-02-24T20:15:22.272395Z","end":"2026-02-24T20:15:22.395061Z","steps":["trace[1939251593] 'read index received' (duration: 58.477485ms)","trace[1939251593] 'applied index is now lower than readState.Index' (duration: 64.187938ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:15:22.395159Z","caller":"traceutil/trace.go:171","msg":"trace[1492954997] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"171.554949ms","start":"2026-02-24T20:15:22.223571Z","end":"2026-02-24T20:15:22.395126Z","steps":["trace[1492954997] 'process raft request' (duration: 171.320152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:15:22.395268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.854748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T20:15:22.39531Z","caller":"traceutil/trace.go:171","msg":"trace[1796182657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8304; }","duration":"122.9549ms","start":"2026-02-24T20:15:22.272346Z","end":"2026-02-24T20:15:22.395301Z","steps":["trace[1796182657] 'agreement among raft nodes before linearized reading' (duration: 122.795766ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:15:22.523762Z","caller":"traceutil/trace.go:171","msg":"trace[171238108] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"124.300777ms","start":"2026-02-24T20:15:22.399396Z","end":"2026-02-24T20:15:22.523697Z","steps":["trace[171238108] 'process raft request' (duration: 123.734161ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:18:06.259915Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6f1fcbe50562a63a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-24T20:18:06.266584Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-24T20:18:06.266689Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-24T20:18:34.030768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7480} {"level":"info","ts":"2026-02-24T20:18:34.095431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7480,"took":"62.697996ms","hash":18066775,"current-db-size-bytes":40742912,"current-db-size":"41 MB","current-db-size-in-use-bytes":25530368,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-02-24T20:18:34.095509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":18066775,"revision":7480,"compact-revision":5431} {"level":"info","ts":"2026-02-24T20:20:05.429781Z","caller":"traceutil/trace.go:171","msg":"trace[1845375306] transaction","detail":"{read_only:false; response_revision:10672; number_of_response:1; }","duration":"112.823533ms","start":"2026-02-24T20:20:05.316934Z","end":"2026-02-24T20:20:05.429758Z","steps":["trace[1845375306] 'process raft request' (duration: 112.674599ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:20:05.578561Z","caller":"traceutil/trace.go:171","msg":"trace[790070701] transaction","detail":"{read_only:false; response_revision:10674; number_of_response:1; }","duration":"117.463032ms","start":"2026-02-24T20:20:05.461072Z","end":"2026-02-24T20:20:05.578535Z","steps":["trace[790070701] 'process raft request' (duration: 117.275216ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:23:34.038114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9802} {"level":"info","ts":"2026-02-24T20:23:34.120644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9802,"took":"80.156456ms","hash":2691361296,"current-db-size-bytes":40742912,"current-db-size":"41 MB","current-db-size-in-use-bytes":23429120,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-24T20:23:34.120719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2691361296,"revision":9802,"compact-revision":7480} {"level":"info","ts":"2026-02-24T20:25:49.107948Z","caller":"traceutil/trace.go:171","msg":"trace[1657696082] transaction","detail":"{read_only:false; response_revision:12859; number_of_response:1; }","duration":"123.57383ms","start":"2026-02-24T20:25:48.984333Z","end":"2026-02-24T20:25:49.107907Z","steps":["trace[1657696082] 'process raft request' (duration: 85.503151ms)","trace[1657696082] 'compare' (duration: 37.72502ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:26:08.141902Z","caller":"traceutil/trace.go:171","msg":"trace[2100262830] transaction","detail":"{read_only:false; response_revision:12953; number_of_response:1; }","duration":"129.321766ms","start":"2026-02-24T20:26:08.012551Z","end":"2026-02-24T20:26:08.141873Z","steps":["trace[2100262830] 'process raft request' (duration: 129.153741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:08.333074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.085738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T20:26:08.333189Z","caller":"traceutil/trace.go:171","msg":"trace[2066302247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12953; }","duration":"133.238943ms","start":"2026-02-24T20:26:08.199931Z","end":"2026-02-24T20:26:08.33317Z","steps":["trace[2066302247] 'range keys from in-memory index tree' (duration: 132.934964ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:26:09.781278Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000347e.wal"} {"level":"info","ts":"2026-02-24T20:26:15.66401Z","caller":"traceutil/trace.go:171","msg":"trace[1833899859] linearizableReadLoop","detail":"{readStateIndex:13466; appliedIndex:13465; }","duration":"115.171465ms","start":"2026-02-24T20:26:15.548814Z","end":"2026-02-24T20:26:15.663986Z","steps":["trace[1833899859] 'read index received' (duration: 114.966848ms)","trace[1833899859] 'applied index is now lower than readState.Index' (duration: 203.657µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:26:15.664114Z","caller":"traceutil/trace.go:171","msg":"trace[486991748] transaction","detail":"{read_only:false; response_revision:12989; number_of_response:1; }","duration":"121.051083ms","start":"2026-02-24T20:26:15.54303Z","end":"2026-02-24T20:26:15.664081Z","steps":["trace[486991748] 'process raft request' (duration: 120.787965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:15.664145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.314298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T20:26:15.664275Z","caller":"traceutil/trace.go:171","msg":"trace[751878081] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:12989; }","duration":"115.432972ms","start":"2026-02-24T20:26:15.548808Z","end":"2026-02-24T20:26:15.664241Z","steps":["trace[751878081] 'agreement among raft nodes before linearized reading' (duration: 115.289928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:15.665056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.633261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T20:26:15.665097Z","caller":"traceutil/trace.go:171","msg":"trace[1285902427] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:12990; }","duration":"100.706273ms","start":"2026-02-24T20:26:15.564381Z","end":"2026-02-24T20:26:15.665087Z","steps":["trace[1285902427] 'agreement among raft nodes before linearized reading' (duration: 100.625211ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:26:23.521413Z","caller":"traceutil/trace.go:171","msg":"trace[1067028490] transaction","detail":"{read_only:false; response_revision:13040; number_of_response:1; }","duration":"290.856082ms","start":"2026-02-24T20:26:23.230488Z","end":"2026-02-24T20:26:23.521345Z","steps":["trace[1067028490] 'process raft request' (duration: 215.368401ms)","trace[1067028490] 'compare' (duration: 75.277655ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:26:23.521684Z","caller":"traceutil/trace.go:171","msg":"trace[1715803595] transaction","detail":"{read_only:false; response_revision:13041; number_of_response:1; }","duration":"191.072695ms","start":"2026-02-24T20:26:23.330556Z","end":"2026-02-24T20:26:23.521629Z","steps":["trace[1715803595] 'process raft request' (duration: 190.749866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:42.964005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.064706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T20:27:42.964162Z","caller":"traceutil/trace.go:171","msg":"trace[279672301] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13969; }","duration":"110.286862ms","start":"2026-02-24T20:27:42.853853Z","end":"2026-02-24T20:27:42.96414Z","steps":["trace[279672301] 'range keys from in-memory index tree' (duration: 109.938472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:42.964291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.155444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5c5d95dbfd-2wkln.189747eaef044c35\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-02-24T20:27:42.964383Z","caller":"traceutil/trace.go:171","msg":"trace[1514377254] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5c5d95dbfd-2wkln.189747eaef044c35; range_end:; response_count:1; response_revision:13969; }","duration":"209.307959ms","start":"2026-02-24T20:27:42.755054Z","end":"2026-02-24T20:27:42.964362Z","steps":["trace[1514377254] 'range keys from in-memory index tree' (duration: 208.96796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:42.964417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.525238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-02-24T20:27:42.964525Z","caller":"traceutil/trace.go:171","msg":"trace[2099478379] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13969; }","duration":"110.692403ms","start":"2026-02-24T20:27:42.853808Z","end":"2026-02-24T20:27:42.9645Z","steps":["trace[2099478379] 'range keys from in-memory index tree' (duration: 110.262501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:42.964812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.719128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-24T20:27:42.964774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.849207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"info","ts":"2026-02-24T20:27:42.964859Z","caller":"traceutil/trace.go:171","msg":"trace[1685657176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13969; }","duration":"105.813031ms","start":"2026-02-24T20:27:42.859034Z","end":"2026-02-24T20:27:42.964847Z","steps":["trace[1685657176] 'range keys from in-memory index tree' (duration: 105.591915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:42.96483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.737436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T20:27:42.96491Z","caller":"traceutil/trace.go:171","msg":"trace[1298494343] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:13969; }","duration":"111.026571ms","start":"2026-02-24T20:27:42.853842Z","end":"2026-02-24T20:27:42.964868Z","steps":["trace[1298494343] 'range keys from in-memory index tree' (duration: 110.59151ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:27:42.964954Z","caller":"traceutil/trace.go:171","msg":"trace[435193600] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:13969; }","duration":"109.904871ms","start":"2026-02-24T20:27:42.855018Z","end":"2026-02-24T20:27:42.964923Z","steps":["trace[435193600] 'range keys from in-memory index tree' (duration: 109.644984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:42.965807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.545565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5478"} {"level":"info","ts":"2026-02-24T20:27:42.965869Z","caller":"traceutil/trace.go:171","msg":"trace[64675043] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13969; }","duration":"111.608127ms","start":"2026-02-24T20:27:42.854244Z","end":"2026-02-24T20:27:42.965852Z","steps":["trace[64675043] 'range keys from in-memory index tree' (duration: 111.232106ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:27:43.075471Z","caller":"traceutil/trace.go:171","msg":"trace[1328310178] transaction","detail":"{read_only:false; response_revision:13970; number_of_response:1; }","duration":"107.765163ms","start":"2026-02-24T20:27:42.967674Z","end":"2026-02-24T20:27:43.075439Z","steps":["trace[1328310178] 'process raft request' (duration: 107.596009ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:27:43.15682Z","caller":"traceutil/trace.go:171","msg":"trace[1897332578] linearizableReadLoop","detail":"{readStateIndex:14481; appliedIndex:14479; }","duration":"174.1868ms","start":"2026-02-24T20:27:42.982609Z","end":"2026-02-24T20:27:43.156796Z","steps":["trace[1897332578] 'read index received' (duration: 92.769377ms)","trace[1897332578] 'applied index is now lower than readState.Index' (duration: 81.416343ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:27:43.156949Z","caller":"traceutil/trace.go:171","msg":"trace[1247247624] transaction","detail":"{read_only:false; response_revision:13971; number_of_response:1; }","duration":"185.239289ms","start":"2026-02-24T20:27:42.971675Z","end":"2026-02-24T20:27:43.156914Z","steps":["trace[1247247624] 'process raft request' (duration: 184.987901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:43.15705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.414126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-6gzg5\" ","response":"range_response_count:1 size:21734"} {"level":"info","ts":"2026-02-24T20:27:43.15708Z","caller":"traceutil/trace.go:171","msg":"trace[1076181778] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-6gzg5; range_end:; response_count:1; response_revision:13971; }","duration":"174.488408ms","start":"2026-02-24T20:27:42.982582Z","end":"2026-02-24T20:27:43.15707Z","steps":["trace[1076181778] 'agreement among raft nodes before linearized reading' (duration: 174.338894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:43.15714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.263142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-6gzg5\" ","response":"range_response_count:1 size:21734"} {"level":"info","ts":"2026-02-24T20:27:43.157202Z","caller":"traceutil/trace.go:171","msg":"trace[1802046888] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-6gzg5; range_end:; response_count:1; response_revision:13971; }","duration":"147.365984ms","start":"2026-02-24T20:27:43.009822Z","end":"2026-02-24T20:27:43.157188Z","steps":["trace[1802046888] 'agreement among raft nodes before linearized reading' (duration: 147.151378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:43.157139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.867486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-02-24T20:27:43.157328Z","caller":"traceutil/trace.go:171","msg":"trace[248705174] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:13971; }","duration":"105.115992ms","start":"2026-02-24T20:27:43.052191Z","end":"2026-02-24T20:27:43.157307Z","steps":["trace[248705174] 'agreement among raft nodes before linearized reading' (duration: 104.823994ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:28:34.045481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11910} {"level":"info","ts":"2026-02-24T20:28:34.125279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11910,"took":"76.873229ms","hash":687837158,"current-db-size-bytes":41160704,"current-db-size":"41 MB","current-db-size-in-use-bytes":26193920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-02-24T20:28:34.125381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":687837158,"revision":11910,"compact-revision":9802} {"level":"info","ts":"2026-02-24T20:33:34.051584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14213} {"level":"info","ts":"2026-02-24T20:33:34.144907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14213,"took":"90.496364ms","hash":1435152410,"current-db-size-bytes":42442752,"current-db-size":"42 MB","current-db-size-in-use-bytes":28524544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-24T20:33:34.14498Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1435152410,"revision":14213,"compact-revision":11910} {"level":"info","ts":"2026-02-24T20:33:39.17775Z","caller":"traceutil/trace.go:171","msg":"trace[682107270] transaction","detail":"{read_only:false; response_revision:16418; number_of_response:1; }","duration":"274.821804ms","start":"2026-02-24T20:33:38.902899Z","end":"2026-02-24T20:33:39.177721Z","steps":["trace[682107270] 'process raft request' (duration: 274.637009ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:33:39.21431Z","caller":"traceutil/trace.go:171","msg":"trace[1163197503] linearizableReadLoop","detail":"{readStateIndex:17018; appliedIndex:17016; }","duration":"288.688336ms","start":"2026-02-24T20:33:38.925596Z","end":"2026-02-24T20:33:39.214284Z","steps":["trace[1163197503] 'read index received' (duration: 252.043374ms)","trace[1163197503] 'applied index is now lower than readState.Index' (duration: 36.643492ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:33:39.214335Z","caller":"traceutil/trace.go:171","msg":"trace[1518249089] transaction","detail":"{read_only:false; response_revision:16419; number_of_response:1; }","duration":"299.152046ms","start":"2026-02-24T20:33:38.915161Z","end":"2026-02-24T20:33:39.214313Z","steps":["trace[1518249089] 'process raft request' (duration: 298.91688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:39.214573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.960842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2kbn7\" ","response":"range_response_count:1 size:20560"} {"level":"warn","ts":"2026-02-24T20:33:39.214622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.982456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12012"} {"level":"info","ts":"2026-02-24T20:33:39.214709Z","caller":"traceutil/trace.go:171","msg":"trace[1349845083] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16420; }","duration":"204.13719ms","start":"2026-02-24T20:33:39.010559Z","end":"2026-02-24T20:33:39.214696Z","steps":["trace[1349845083] 'agreement among raft nodes before linearized reading' (duration: 203.916524ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:33:39.214665Z","caller":"traceutil/trace.go:171","msg":"trace[922365770] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2kbn7; range_end:; response_count:1; response_revision:16420; }","duration":"289.083357ms","start":"2026-02-24T20:33:38.925537Z","end":"2026-02-24T20:33:39.21462Z","steps":["trace[922365770] 'agreement among raft nodes before linearized reading' (duration: 288.889591ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:33:39.684214Z","caller":"traceutil/trace.go:171","msg":"trace[210390439] linearizableReadLoop","detail":"{readStateIndex:17020; appliedIndex:17019; }","duration":"434.056051ms","start":"2026-02-24T20:33:39.250137Z","end":"2026-02-24T20:33:39.684193Z","steps":["trace[210390439] 'read index received' (duration: 433.852446ms)","trace[210390439] 'applied index is now lower than readState.Index' (duration: 202.585µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:33:39.684337Z","caller":"traceutil/trace.go:171","msg":"trace[461792400] transaction","detail":"{read_only:false; response_revision:16421; number_of_response:1; }","duration":"452.490905ms","start":"2026-02-24T20:33:39.231832Z","end":"2026-02-24T20:33:39.684323Z","steps":["trace[461792400] 'process raft request' (duration: 452.173167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:39.684428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:33:39.231801Z","time spent":"452.555927ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20676,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:33:39.6846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.463681ms","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-02-24T20:33:39.68463Z","caller":"traceutil/trace.go:171","msg":"trace[265276356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16421; }","duration":"434.492322ms","start":"2026-02-24T20:33:39.250129Z","end":"2026-02-24T20:33:39.684622Z","steps":["trace[265276356] 'agreement among raft nodes before linearized reading' (duration: 434.385289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:39.684651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:33:39.250079Z","time spent":"434.567335ms","remote":"127.0.0.1:56862","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":"warn","ts":"2026-02-24T20:33:39.684737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.243116ms","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-02-24T20:33:39.684756Z","caller":"traceutil/trace.go:171","msg":"trace[1603209393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16421; }","duration":"434.276467ms","start":"2026-02-24T20:33:39.250473Z","end":"2026-02-24T20:33:39.68475Z","steps":["trace[1603209393] 'agreement among raft nodes before linearized reading' (duration: 434.212795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:39.684774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:33:39.250468Z","time spent":"434.302467ms","remote":"127.0.0.1:56862","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-02-24T20:33:39.685013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.132646ms","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-02-24T20:33:39.685033Z","caller":"traceutil/trace.go:171","msg":"trace[681293905] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16421; }","duration":"408.196599ms","start":"2026-02-24T20:33:39.27683Z","end":"2026-02-24T20:33:39.685027Z","steps":["trace[681293905] 'agreement among raft nodes before linearized reading' (duration: 408.158088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:39.685051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:33:39.276807Z","time spent":"408.239549ms","remote":"127.0.0.1:57050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-02-24T20:33:39.685135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.040429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-6gzg5\" ","response":"range_response_count:1 size:21735"} {"level":"info","ts":"2026-02-24T20:33:39.68519Z","caller":"traceutil/trace.go:171","msg":"trace[1287091715] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-6gzg5; range_end:; response_count:1; response_revision:16421; }","duration":"422.132672ms","start":"2026-02-24T20:33:39.263043Z","end":"2026-02-24T20:33:39.685175Z","steps":["trace[1287091715] 'agreement among raft nodes before linearized reading' (duration: 422.026409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:39.685218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:33:39.263028Z","time spent":"422.184063ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":21758,"request content":"key:\"/registry/pods/openstack/nova-compute-default-6gzg5\" "} {"level":"info","ts":"2026-02-24T20:38:34.058719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16394} {"level":"info","ts":"2026-02-24T20:38:34.153257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16394,"took":"91.591664ms","hash":943218272,"current-db-size-bytes":43708416,"current-db-size":"44 MB","current-db-size-in-use-bytes":30232576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-24T20:38:34.153327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":943218272,"revision":16394,"compact-revision":14213} {"level":"info","ts":"2026-02-24T20:38:49.36526Z","caller":"traceutil/trace.go:171","msg":"trace[1951211502] transaction","detail":"{read_only:false; response_revision:18762; number_of_response:1; }","duration":"134.922477ms","start":"2026-02-24T20:38:49.230312Z","end":"2026-02-24T20:38:49.365234Z","steps":["trace[1951211502] 'process raft request' (duration: 119.117893ms)","trace[1951211502] 'compare' (duration: 15.657269ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:39:05.685029Z","caller":"traceutil/trace.go:171","msg":"trace[718661547] transaction","detail":"{read_only:false; response_revision:18833; number_of_response:1; }","duration":"245.411475ms","start":"2026-02-24T20:39:05.43959Z","end":"2026-02-24T20:39:05.685002Z","steps":["trace[718661547] 'process raft request' (duration: 245.264711ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:39:05.685156Z","caller":"traceutil/trace.go:171","msg":"trace[82209584] linearizableReadLoop","detail":"{readStateIndex:19516; appliedIndex:19516; }","duration":"195.332185ms","start":"2026-02-24T20:39:05.489767Z","end":"2026-02-24T20:39:05.685099Z","steps":["trace[82209584] 'read index received' (duration: 195.320715ms)","trace[82209584] 'applied index is now lower than readState.Index' (duration: 9.21µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:39:05.685406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.568471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:39:05.686424Z","caller":"traceutil/trace.go:171","msg":"trace[1011968546] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:18833; }","duration":"196.673991ms","start":"2026-02-24T20:39:05.489712Z","end":"2026-02-24T20:39:05.686386Z","steps":["trace[1011968546] 'agreement among raft nodes before linearized reading' (duration: 195.507369ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:39:05.815465Z","caller":"traceutil/trace.go:171","msg":"trace[1132564710] linearizableReadLoop","detail":"{readStateIndex:19517; appliedIndex:19516; }","duration":"120.948941ms","start":"2026-02-24T20:39:05.694493Z","end":"2026-02-24T20:39:05.815442Z","steps":["trace[1132564710] 'read index received' (duration: 112.410782ms)","trace[1132564710] 'applied index is now lower than readState.Index' (duration: 8.536969ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:39:05.815541Z","caller":"traceutil/trace.go:171","msg":"trace[1929635026] transaction","detail":"{read_only:false; response_revision:18834; number_of_response:1; }","duration":"224.571308ms","start":"2026-02-24T20:39:05.590932Z","end":"2026-02-24T20:39:05.815504Z","steps":["trace[1929635026] 'process raft request' (duration: 215.957647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:39:05.815686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.182047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:39:05.815732Z","caller":"traceutil/trace.go:171","msg":"trace[1906332379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18834; }","duration":"121.240889ms","start":"2026-02-24T20:39:05.69448Z","end":"2026-02-24T20:39:05.815721Z","steps":["trace[1906332379] 'agreement among raft nodes before linearized reading' (duration: 121.075794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:39:05.815744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.905666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T20:39:05.815815Z","caller":"traceutil/trace.go:171","msg":"trace[1221517405] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:18834; }","duration":"118.998999ms","start":"2026-02-24T20:39:05.696802Z","end":"2026-02-24T20:39:05.815801Z","steps":["trace[1221517405] 'agreement among raft nodes before linearized reading' (duration: 118.805034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:39:05.815881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.666869ms","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-02-24T20:39:05.81595Z","caller":"traceutil/trace.go:171","msg":"trace[1386823203] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18834; }","duration":"115.774222ms","start":"2026-02-24T20:39:05.700157Z","end":"2026-02-24T20:39:05.815931Z","steps":["trace[1386823203] 'agreement among raft nodes before linearized reading' (duration: 115.590647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:39:05.816073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.476905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12012"} {"level":"info","ts":"2026-02-24T20:39:05.816124Z","caller":"traceutil/trace.go:171","msg":"trace[1929101941] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18834; }","duration":"118.609058ms","start":"2026-02-24T20:39:05.697505Z","end":"2026-02-24T20:39:05.816114Z","steps":["trace[1929101941] 'agreement among raft nodes before linearized reading' (duration: 118.430033ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:39:05.965471Z","caller":"traceutil/trace.go:171","msg":"trace[1688588897] transaction","detail":"{read_only:false; response_revision:18835; number_of_response:1; }","duration":"145.581161ms","start":"2026-02-24T20:39:05.819809Z","end":"2026-02-24T20:39:05.96539Z","steps":["trace[1688588897] 'process raft request' (duration: 122.481772ms)","trace[1688588897] 'compare' (duration: 22.936344ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:39:05.965656Z","caller":"traceutil/trace.go:171","msg":"trace[1600871786] transaction","detail":"{read_only:false; response_revision:18836; number_of_response:1; }","duration":"145.367875ms","start":"2026-02-24T20:39:05.820273Z","end":"2026-02-24T20:39:05.965641Z","steps":["trace[1600871786] 'process raft request' (duration: 145.188971ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:39:05.965664Z","caller":"traceutil/trace.go:171","msg":"trace[490320153] linearizableReadLoop","detail":"{readStateIndex:19519; appliedIndex:19517; }","duration":"144.517232ms","start":"2026-02-24T20:39:05.821124Z","end":"2026-02-24T20:39:05.965641Z","steps":["trace[490320153] 'read index received' (duration: 121.106785ms)","trace[490320153] 'applied index is now lower than readState.Index' (duration: 23.409277ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:39:05.965723Z","caller":"traceutil/trace.go:171","msg":"trace[1945660842] transaction","detail":"{read_only:false; response_revision:18837; number_of_response:1; }","duration":"106.581366ms","start":"2026-02-24T20:39:05.859126Z","end":"2026-02-24T20:39:05.965707Z","steps":["trace[1945660842] 'process raft request' (duration: 106.436242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:39:05.9659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.753439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16579"} {"level":"info","ts":"2026-02-24T20:39:05.965966Z","caller":"traceutil/trace.go:171","msg":"trace[206332441] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18837; }","duration":"144.853811ms","start":"2026-02-24T20:39:05.821096Z","end":"2026-02-24T20:39:05.96595Z","steps":["trace[206332441] 'agreement among raft nodes before linearized reading' (duration: 144.621384ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:39:48.775755Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6f1fcbe50562a63a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-24T20:39:48.781578Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-02-24T20:39:48.781858Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-02-24T20:42:48.755126Z","caller":"traceutil/trace.go:171","msg":"trace[1411380689] linearizableReadLoop","detail":"{readStateIndex:21829; appliedIndex:21828; }","duration":"147.613965ms","start":"2026-02-24T20:42:48.607468Z","end":"2026-02-24T20:42:48.755082Z","steps":["trace[1411380689] 'read index received' (duration: 147.385079ms)","trace[1411380689] 'applied index is now lower than readState.Index' (duration: 227.306µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:42:48.755245Z","caller":"traceutil/trace.go:171","msg":"trace[1430372246] transaction","detail":"{read_only:false; response_revision:21074; number_of_response:1; }","duration":"240.043904ms","start":"2026-02-24T20:42:48.515173Z","end":"2026-02-24T20:42:48.755216Z","steps":["trace[1430372246] 'process raft request' (duration: 239.660003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:42:48.755344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.841831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:42:48.755422Z","caller":"traceutil/trace.go:171","msg":"trace[1182920379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21074; }","duration":"147.992285ms","start":"2026-02-24T20:42:48.607417Z","end":"2026-02-24T20:42:48.755409Z","steps":["trace[1182920379] 'agreement among raft nodes before linearized reading' (duration: 147.833441ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:43:34.065151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18690} {"level":"info","ts":"2026-02-24T20:43:34.176102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18690,"took":"106.858748ms","hash":3740955931,"current-db-size-bytes":46366720,"current-db-size":"46 MB","current-db-size-in-use-bytes":37167104,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-02-24T20:43:34.176157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3740955931,"revision":18690,"compact-revision":16394} {"level":"info","ts":"2026-02-24T20:46:17.111101Z","caller":"traceutil/trace.go:171","msg":"trace[1283263138] transaction","detail":"{read_only:false; response_revision:23090; number_of_response:1; }","duration":"128.456528ms","start":"2026-02-24T20:46:16.982613Z","end":"2026-02-24T20:46:17.111069Z","steps":["trace[1283263138] 'process raft request' (duration: 82.951582ms)","trace[1283263138] 'compare' (duration: 45.182798ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:17.246917Z","caller":"traceutil/trace.go:171","msg":"trace[614409812] transaction","detail":"{read_only:false; response_revision:23091; number_of_response:1; }","duration":"130.922955ms","start":"2026-02-24T20:46:17.115849Z","end":"2026-02-24T20:46:17.246772Z","steps":["trace[614409812] 'process raft request' (duration: 128.058708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:17.655177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:17.311829Z","time spent":"343.343705ms","remote":"127.0.0.1:56628","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T20:46:17.665298Z","caller":"traceutil/trace.go:171","msg":"trace[1115504974] transaction","detail":"{read_only:false; response_revision:23092; number_of_response:1; }","duration":"206.448659ms","start":"2026-02-24T20:46:17.458824Z","end":"2026-02-24T20:46:17.665273Z","steps":["trace[1115504974] 'process raft request' (duration: 206.252962ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:17.665688Z","caller":"traceutil/trace.go:171","msg":"trace[1895570915] transaction","detail":"{read_only:false; response_revision:23093; number_of_response:1; }","duration":"154.340736ms","start":"2026-02-24T20:46:17.511306Z","end":"2026-02-24T20:46:17.665646Z","steps":["trace[1895570915] 'process raft request' (duration: 153.927855ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:21.80063Z","caller":"traceutil/trace.go:171","msg":"trace[1985561396] transaction","detail":"{read_only:false; response_revision:23125; number_of_response:1; }","duration":"108.567664ms","start":"2026-02-24T20:46:21.692033Z","end":"2026-02-24T20:46:21.8006Z","steps":["trace[1985561396] 'process raft request' (duration: 108.434931ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:21.801027Z","caller":"traceutil/trace.go:171","msg":"trace[361730192] transaction","detail":"{read_only:false; response_revision:23127; number_of_response:1; }","duration":"108.002628ms","start":"2026-02-24T20:46:21.692989Z","end":"2026-02-24T20:46:21.800992Z","steps":["trace[361730192] 'process raft request' (duration: 107.860865ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:21.801125Z","caller":"traceutil/trace.go:171","msg":"trace[931964091] transaction","detail":"{read_only:false; response_revision:23126; number_of_response:1; }","duration":"108.119521ms","start":"2026-02-24T20:46:21.692941Z","end":"2026-02-24T20:46:21.801061Z","steps":["trace[931964091] 'process raft request' (duration: 107.618508ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:27.302627Z","caller":"traceutil/trace.go:171","msg":"trace[454992341] transaction","detail":"{read_only:false; response_revision:23162; number_of_response:1; }","duration":"134.179483ms","start":"2026-02-24T20:46:27.168412Z","end":"2026-02-24T20:46:27.302592Z","steps":["trace[454992341] 'process raft request' (duration: 133.985628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:27.590596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.037504ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-24T20:46:27.590984Z","caller":"traceutil/trace.go:171","msg":"trace[497445604] transaction","detail":"{read_only:false; response_revision:23163; number_of_response:1; }","duration":"270.222096ms","start":"2026-02-24T20:46:27.320746Z","end":"2026-02-24T20:46:27.590968Z","steps":["trace[497445604] 'process raft request' (duration: 269.9878ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:27.927992Z","caller":"traceutil/trace.go:171","msg":"trace[1838597467] transaction","detail":"{read_only:false; response_revision:23165; number_of_response:1; }","duration":"105.755808ms","start":"2026-02-24T20:46:27.822205Z","end":"2026-02-24T20:46:27.927961Z","steps":["trace[1838597467] 'process raft request' (duration: 105.577614ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:28.103318Z","caller":"traceutil/trace.go:171","msg":"trace[557134447] transaction","detail":"{read_only:false; response_revision:23167; number_of_response:1; }","duration":"276.100425ms","start":"2026-02-24T20:46:27.827195Z","end":"2026-02-24T20:46:28.103295Z","steps":["trace[557134447] 'process raft request' (duration: 275.979912ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:28.103471Z","caller":"traceutil/trace.go:171","msg":"trace[592786656] transaction","detail":"{read_only:false; response_revision:23168; number_of_response:1; }","duration":"276.239538ms","start":"2026-02-24T20:46:27.827215Z","end":"2026-02-24T20:46:28.103454Z","steps":["trace[592786656] 'process raft request' (duration: 276.045713ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:28.103319Z","caller":"traceutil/trace.go:171","msg":"trace[1244187587] transaction","detail":"{read_only:false; response_revision:23166; number_of_response:1; }","duration":"277.939155ms","start":"2026-02-24T20:46:27.825344Z","end":"2026-02-24T20:46:28.103283Z","steps":["trace[1244187587] 'process raft request' (duration: 269.57963ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:28.103645Z","caller":"traceutil/trace.go:171","msg":"trace[1013878313] linearizableReadLoop","detail":"{readStateIndex:23982; appliedIndex:23978; }","duration":"196.579933ms","start":"2026-02-24T20:46:27.907055Z","end":"2026-02-24T20:46:28.103635Z","steps":["trace[1013878313] 'read index received' (duration: 21.662562ms)","trace[1013878313] 'applied index is now lower than readState.Index' (duration: 174.916621ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:28.103727Z","caller":"traceutil/trace.go:171","msg":"trace[1308543114] transaction","detail":"{read_only:false; response_revision:23169; number_of_response:1; }","duration":"197.816537ms","start":"2026-02-24T20:46:27.905894Z","end":"2026-02-24T20:46:28.103711Z","steps":["trace[1308543114] 'process raft request' (duration: 197.498149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.103792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.732067ms","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-02-24T20:46:28.103822Z","caller":"traceutil/trace.go:171","msg":"trace[1884454978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23169; }","duration":"196.776188ms","start":"2026-02-24T20:46:27.907039Z","end":"2026-02-24T20:46:28.103815Z","steps":["trace[1884454978] 'agreement among raft nodes before linearized reading' (duration: 196.671995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.336909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.202023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:28.337102Z","caller":"traceutil/trace.go:171","msg":"trace[836054093] transaction","detail":"{read_only:false; response_revision:23171; number_of_response:1; }","duration":"230.327342ms","start":"2026-02-24T20:46:28.106757Z","end":"2026-02-24T20:46:28.337084Z","steps":["trace[836054093] 'process raft request' (duration: 230.25405ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:28.337466Z","caller":"traceutil/trace.go:171","msg":"trace[55236624] transaction","detail":"{read_only:false; response_revision:23170; number_of_response:1; }","duration":"249.473418ms","start":"2026-02-24T20:46:28.08798Z","end":"2026-02-24T20:46:28.337453Z","steps":["trace[55236624] 'process raft request' (duration: 143.633117ms)","trace[55236624] 'compare' (duration: 104.738491ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:28.337602Z","caller":"traceutil/trace.go:171","msg":"trace[1038131569] linearizableReadLoop","detail":"{readStateIndex:23983; appliedIndex:23982; }","duration":"233.927118ms","start":"2026-02-24T20:46:28.103665Z","end":"2026-02-24T20:46:28.337592Z","steps":["trace[1038131569] 'read index received' (duration: 127.988756ms)","trace[1038131569] 'applied index is now lower than readState.Index' (duration: 105.937302ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:28.337869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.297992ms","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-02-24T20:46:28.337911Z","caller":"traceutil/trace.go:171","msg":"trace[28771255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23171; }","duration":"249.362954ms","start":"2026-02-24T20:46:28.088536Z","end":"2026-02-24T20:46:28.337899Z","steps":["trace[28771255] 'agreement among raft nodes before linearized reading' (duration: 249.236901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.338064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.127174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:28.338101Z","caller":"traceutil/trace.go:171","msg":"trace[1158755973] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:23171; }","duration":"260.233528ms","start":"2026-02-24T20:46:28.077857Z","end":"2026-02-24T20:46:28.338091Z","steps":["trace[1158755973] 'agreement among raft nodes before linearized reading' (duration: 260.174446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.338297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.729602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-24T20:46:28.338334Z","caller":"traceutil/trace.go:171","msg":"trace[1172293155] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23171; }","duration":"305.766153ms","start":"2026-02-24T20:46:28.03256Z","end":"2026-02-24T20:46:28.338326Z","steps":["trace[1172293155] 'agreement among raft nodes before linearized reading' (duration: 305.66861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.338362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:28.032506Z","time spent":"305.848486ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-02-24T20:46:28.338539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.231762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-24T20:46:28.338573Z","caller":"traceutil/trace.go:171","msg":"trace[478924621] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23171; }","duration":"135.297684ms","start":"2026-02-24T20:46:28.203267Z","end":"2026-02-24T20:46:28.338565Z","steps":["trace[478924621] 'agreement among raft nodes before linearized reading' (duration: 135.210782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.338873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.765349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-02-24T20:46:28.338912Z","caller":"traceutil/trace.go:171","msg":"trace[1217662326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23171; }","duration":"118.80449ms","start":"2026-02-24T20:46:28.220099Z","end":"2026-02-24T20:46:28.338903Z","steps":["trace[1217662326] 'agreement among raft nodes before linearized reading' (duration: 118.710028ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:28.715482Z","caller":"traceutil/trace.go:171","msg":"trace[2016808586] transaction","detail":"{read_only:false; response_revision:23174; number_of_response:1; }","duration":"371.266296ms","start":"2026-02-24T20:46:28.344189Z","end":"2026-02-24T20:46:28.715455Z","steps":["trace[2016808586] 'process raft request' (duration: 371.158793ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:28.715512Z","caller":"traceutil/trace.go:171","msg":"trace[2020265059] transaction","detail":"{read_only:false; response_revision:23173; number_of_response:1; }","duration":"371.313838ms","start":"2026-02-24T20:46:28.344152Z","end":"2026-02-24T20:46:28.715466Z","steps":["trace[2020265059] 'process raft request' (duration: 371.003369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.715646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:28.343537Z","time spent":"372.020378ms","remote":"127.0.0.1:56862","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-02-24T20:46:28.715701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:28.344142Z","time spent":"371.457802ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:28.715713Z","caller":"traceutil/trace.go:171","msg":"trace[402408458] linearizableReadLoop","detail":"{readStateIndex:23988; appliedIndex:23984; }","duration":"365.363307ms","start":"2026-02-24T20:46:28.350323Z","end":"2026-02-24T20:46:28.715686Z","steps":["trace[402408458] 'read index received' (duration: 37.263983ms)","trace[402408458] 'applied index is now lower than readState.Index' (duration: 328.098044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:28.715976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.650476ms","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-02-24T20:46:28.716019Z","caller":"traceutil/trace.go:171","msg":"trace[393970358] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:23175; }","duration":"365.709347ms","start":"2026-02-24T20:46:28.350298Z","end":"2026-02-24T20:46:28.716008Z","steps":["trace[393970358] 'agreement among raft nodes before linearized reading' (duration: 365.556083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.716036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.355777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:46:28.716055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:28.350285Z","time spent":"365.762368ms","remote":"127.0.0.1:56820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":78,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:46:28.716075Z","caller":"traceutil/trace.go:171","msg":"trace[2072655650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23175; }","duration":"108.445679ms","start":"2026-02-24T20:46:28.607618Z","end":"2026-02-24T20:46:28.716064Z","steps":["trace[2072655650] 'agreement among raft nodes before linearized reading' (duration: 108.353197ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:28.71634Z","caller":"traceutil/trace.go:171","msg":"trace[475130669] transaction","detail":"{read_only:false; response_revision:23175; number_of_response:1; }","duration":"372.077138ms","start":"2026-02-24T20:46:28.34425Z","end":"2026-02-24T20:46:28.716328Z","steps":["trace[475130669] 'process raft request' (duration: 371.154244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:28.716409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:28.344218Z","time spent":"372.159181ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:35.048225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.227355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-24T20:46:35.04823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.729458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12012"} {"level":"info","ts":"2026-02-24T20:46:35.048313Z","caller":"traceutil/trace.go:171","msg":"trace[1895337047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23270; }","duration":"277.363758ms","start":"2026-02-24T20:46:34.770931Z","end":"2026-02-24T20:46:35.048295Z","steps":["trace[1895337047] 'range keys from in-memory index tree' (duration: 276.987629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.048365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.51795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:46:35.048376Z","caller":"traceutil/trace.go:171","msg":"trace[2034538195] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23270; }","duration":"336.913273ms","start":"2026-02-24T20:46:34.711435Z","end":"2026-02-24T20:46:35.048348Z","steps":["trace[2034538195] 'range keys from in-memory index tree' (duration: 336.554414ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:35.048426Z","caller":"traceutil/trace.go:171","msg":"trace[1582037739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23270; }","duration":"304.616962ms","start":"2026-02-24T20:46:34.743795Z","end":"2026-02-24T20:46:35.048412Z","steps":["trace[1582037739] 'range keys from in-memory index tree' (duration: 303.993306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.048445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.711415Z","time spent":"337.012616ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12035,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-24T20:46:35.048467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.743774Z","time spent":"304.684785ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T20:46:35.203909Z","caller":"traceutil/trace.go:171","msg":"trace[459901326] transaction","detail":"{read_only:false; response_revision:23271; number_of_response:1; }","duration":"319.00588ms","start":"2026-02-24T20:46:34.884874Z","end":"2026-02-24T20:46:35.20388Z","steps":["trace[459901326] 'process raft request' (duration: 318.846206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.204081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.884842Z","time spent":"319.148974ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:35.204055Z","caller":"traceutil/trace.go:171","msg":"trace[1109169881] linearizableReadLoop","detail":"{readStateIndex:24085; appliedIndex:24085; }","duration":"203.969943ms","start":"2026-02-24T20:46:35.000039Z","end":"2026-02-24T20:46:35.204009Z","steps":["trace[1109169881] 'read index received' (duration: 203.944602ms)","trace[1109169881] 'applied index is now lower than readState.Index' (duration: 21.711µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:35.204344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.275451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-02-24T20:46:35.204416Z","caller":"traceutil/trace.go:171","msg":"trace[2019440016] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:23271; }","duration":"204.364773ms","start":"2026-02-24T20:46:35.000034Z","end":"2026-02-24T20:46:35.204398Z","steps":["trace[2019440016] 'agreement among raft nodes before linearized reading' (duration: 204.140067ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:35.322633Z","caller":"traceutil/trace.go:171","msg":"trace[1559083366] transaction","detail":"{read_only:false; response_revision:23272; number_of_response:1; }","duration":"269.795366ms","start":"2026-02-24T20:46:35.052807Z","end":"2026-02-24T20:46:35.322602Z","steps":["trace[1559083366] 'process raft request' (duration: 267.603897ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:35.322833Z","caller":"traceutil/trace.go:171","msg":"trace[429715545] linearizableReadLoop","detail":"{readStateIndex:24087; appliedIndex:24085; }","duration":"118.615105ms","start":"2026-02-24T20:46:35.204181Z","end":"2026-02-24T20:46:35.322796Z","steps":["trace[429715545] 'read index received' (duration: 116.146578ms)","trace[429715545] 'applied index is now lower than readState.Index' (duration: 2.466587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:35.322959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.876015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:35.323023Z","caller":"traceutil/trace.go:171","msg":"trace[191436210] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:23273; }","duration":"212.00341ms","start":"2026-02-24T20:46:35.111Z","end":"2026-02-24T20:46:35.323004Z","steps":["trace[191436210] 'agreement among raft nodes before linearized reading' (duration: 211.886576ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:35.323005Z","caller":"traceutil/trace.go:171","msg":"trace[948101116] transaction","detail":"{read_only:false; response_revision:23273; number_of_response:1; }","duration":"270.096984ms","start":"2026-02-24T20:46:35.052872Z","end":"2026-02-24T20:46:35.322969Z","steps":["trace[948101116] 'process raft request' (duration: 269.684573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.323048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.815121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:46:35.323107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.08088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12012"} {"level":"info","ts":"2026-02-24T20:46:35.323149Z","caller":"traceutil/trace.go:171","msg":"trace[1557947622] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23273; }","duration":"271.164432ms","start":"2026-02-24T20:46:35.05197Z","end":"2026-02-24T20:46:35.323135Z","steps":["trace[1557947622] 'agreement among raft nodes before linearized reading' (duration: 270.992347ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:35.323147Z","caller":"traceutil/trace.go:171","msg":"trace[1874533499] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:23273; }","duration":"252.011667ms","start":"2026-02-24T20:46:35.07111Z","end":"2026-02-24T20:46:35.323121Z","steps":["trace[1874533499] 'agreement among raft nodes before linearized reading' (duration: 251.799731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.323214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.587409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:46:35.323221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.310715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3c0193df-b545-4ebe-a266-6ebf015789b5\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-02-24T20:46:35.323248Z","caller":"traceutil/trace.go:171","msg":"trace[470651421] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:23273; }","duration":"321.656632ms","start":"2026-02-24T20:46:35.00158Z","end":"2026-02-24T20:46:35.323237Z","steps":["trace[470651421] 'agreement among raft nodes before linearized reading' (duration: 321.57394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.323281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.001573Z","time spent":"321.699773ms","remote":"127.0.0.1:40602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-02-24T20:46:35.323287Z","caller":"traceutil/trace.go:171","msg":"trace[496541764] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3c0193df-b545-4ebe-a266-6ebf015789b5; range_end:; response_count:1; response_revision:23273; }","duration":"115.389567ms","start":"2026-02-24T20:46:35.207878Z","end":"2026-02-24T20:46:35.323267Z","steps":["trace[496541764] 'agreement among raft nodes before linearized reading' (duration: 115.183421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.723057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.223861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:35.723164Z","caller":"traceutil/trace.go:171","msg":"trace[684494879] linearizableReadLoop","detail":"{readStateIndex:24088; appliedIndex:24087; }","duration":"289.891476ms","start":"2026-02-24T20:46:35.433253Z","end":"2026-02-24T20:46:35.723144Z","steps":["trace[684494879] 'read index received' (duration: 53.437698ms)","trace[684494879] 'applied index is now lower than readState.Index' (duration: 236.452568ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:35.723274Z","caller":"traceutil/trace.go:171","msg":"trace[47378946] transaction","detail":"{read_only:false; response_revision:23274; number_of_response:1; }","duration":"298.000145ms","start":"2026-02-24T20:46:35.42526Z","end":"2026-02-24T20:46:35.72326Z","steps":["trace[47378946] 'process raft request' (duration: 61.485556ms)","trace[47378946] 'compare' (duration: 236.105657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:35.723382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.124872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128985"} {"level":"info","ts":"2026-02-24T20:46:35.723419Z","caller":"traceutil/trace.go:171","msg":"trace[1411794598] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:23274; }","duration":"290.194214ms","start":"2026-02-24T20:46:35.433216Z","end":"2026-02-24T20:46:35.72341Z","steps":["trace[1411794598] 'agreement among raft nodes before linearized reading' (duration: 290.010909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.038286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.448472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:36.038396Z","caller":"traceutil/trace.go:171","msg":"trace[139483113] linearizableReadLoop","detail":"{readStateIndex:24089; appliedIndex:24088; }","duration":"315.186816ms","start":"2026-02-24T20:46:35.723192Z","end":"2026-02-24T20:46:36.038379Z","steps":["trace[139483113] 'read index received' (duration: 122.497557ms)","trace[139483113] 'applied index is now lower than readState.Index' (duration: 192.688079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:36.038531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.373105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99\" ","response":"range_response_count:1 size:3698"} {"level":"warn","ts":"2026-02-24T20:46:36.03853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.514325ms","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-02-24T20:46:36.038568Z","caller":"traceutil/trace.go:171","msg":"trace[1398898657] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99; range_end:; response_count:1; response_revision:23275; }","duration":"493.408946ms","start":"2026-02-24T20:46:35.545148Z","end":"2026-02-24T20:46:36.038557Z","steps":["trace[1398898657] 'agreement among raft nodes before linearized reading' (duration: 493.299673ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:36.038576Z","caller":"traceutil/trace.go:171","msg":"trace[1228433782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23275; }","duration":"367.596388ms","start":"2026-02-24T20:46:35.670966Z","end":"2026-02-24T20:46:36.038562Z","steps":["trace[1228433782] 'agreement among raft nodes before linearized reading' (duration: 367.456794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.038602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.54509Z","time spent":"493.503419ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3721,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99\" "} {"level":"warn","ts":"2026-02-24T20:46:36.03861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.670924Z","time spent":"367.67761ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T20:46:36.038583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.91459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:36.038683Z","caller":"traceutil/trace.go:171","msg":"trace[1479146225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23275; }","duration":"432.073794ms","start":"2026-02-24T20:46:35.606585Z","end":"2026-02-24T20:46:36.038659Z","steps":["trace[1479146225] 'agreement among raft nodes before linearized reading' (duration: 431.872189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.038743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.606569Z","time spent":"432.163926ms","remote":"127.0.0.1:56586","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-02-24T20:46:36.038856Z","caller":"traceutil/trace.go:171","msg":"trace[710973784] transaction","detail":"{read_only:false; response_revision:23275; number_of_response:1; }","duration":"358.476623ms","start":"2026-02-24T20:46:35.680271Z","end":"2026-02-24T20:46:36.038747Z","steps":["trace[710973784] 'process raft request' (duration: 165.472966ms)","trace[710973784] 'compare' (duration: 192.225516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:36.039234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.680253Z","time spent":"358.713608ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:36.053096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.40851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:36.053172Z","caller":"traceutil/trace.go:171","msg":"trace[644766076] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:23276; }","duration":"202.531784ms","start":"2026-02-24T20:46:35.850625Z","end":"2026-02-24T20:46:36.053157Z","steps":["trace[644766076] 'agreement among raft nodes before linearized reading' (duration: 202.40203ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:37.88268Z","caller":"traceutil/trace.go:171","msg":"trace[2059211708] transaction","detail":"{read_only:false; response_revision:23290; number_of_response:1; }","duration":"151.080917ms","start":"2026-02-24T20:46:37.731574Z","end":"2026-02-24T20:46:37.882655Z","steps":["trace[2059211708] 'process raft request' (duration: 150.596404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.203966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.483272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:38.204061Z","caller":"traceutil/trace.go:171","msg":"trace[1947281084] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:23290; }","duration":"290.643557ms","start":"2026-02-24T20:46:37.913394Z","end":"2026-02-24T20:46:38.204038Z","steps":["trace[1947281084] 'count revisions from in-memory index tree' (duration: 290.41072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.204105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.115447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99\" ","response":"range_response_count:1 size:3884"} {"level":"info","ts":"2026-02-24T20:46:38.204182Z","caller":"traceutil/trace.go:171","msg":"trace[281194305] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99; range_end:; response_count:1; response_revision:23290; }","duration":"155.25557ms","start":"2026-02-24T20:46:38.048912Z","end":"2026-02-24T20:46:38.204168Z","steps":["trace[281194305] 'range keys from in-memory index tree' (duration: 154.973713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.204567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.361325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:38.204674Z","caller":"traceutil/trace.go:171","msg":"trace[344644367] transaction","detail":"{read_only:false; response_revision:23291; number_of_response:1; }","duration":"146.907625ms","start":"2026-02-24T20:46:38.057745Z","end":"2026-02-24T20:46:38.204653Z","steps":["trace[344644367] 'process raft request' (duration: 18.372005ms)","trace[344644367] 'compare' (duration: 127.79106ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:38.204688Z","caller":"traceutil/trace.go:171","msg":"trace[1399009167] linearizableReadLoop","detail":"{readStateIndex:24106; appliedIndex:24105; }","duration":"141.095809ms","start":"2026-02-24T20:46:38.063553Z","end":"2026-02-24T20:46:38.204649Z","steps":["trace[1399009167] 'read index received' (duration: 12.586529ms)","trace[1399009167] 'applied index is now lower than readState.Index' (duration: 128.50808ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:38.204834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.281914ms","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-02-24T20:46:38.204871Z","caller":"traceutil/trace.go:171","msg":"trace[1869054604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23291; }","duration":"141.346096ms","start":"2026-02-24T20:46:38.063512Z","end":"2026-02-24T20:46:38.204858Z","steps":["trace[1869054604] 'agreement among raft nodes before linearized reading' (duration: 141.203062ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:38.386574Z","caller":"traceutil/trace.go:171","msg":"trace[879451966] transaction","detail":"{read_only:false; response_revision:23292; number_of_response:1; }","duration":"223.486427ms","start":"2026-02-24T20:46:38.163056Z","end":"2026-02-24T20:46:38.386543Z","steps":["trace[879451966] 'process raft request' (duration: 157.4758ms)","trace[879451966] 'compare' (duration: 65.890014ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:38.393121Z","caller":"traceutil/trace.go:171","msg":"trace[697341805] transaction","detail":"{read_only:false; response_revision:23294; number_of_response:1; }","duration":"221.319859ms","start":"2026-02-24T20:46:38.17178Z","end":"2026-02-24T20:46:38.3931Z","steps":["trace[697341805] 'process raft request' (duration: 221.252197ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:38.393312Z","caller":"traceutil/trace.go:171","msg":"trace[1710448538] transaction","detail":"{read_only:false; response_revision:23295; number_of_response:1; }","duration":"205.064061ms","start":"2026-02-24T20:46:38.18823Z","end":"2026-02-24T20:46:38.393294Z","steps":["trace[1710448538] 'process raft request' (duration: 204.837635ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:38.393406Z","caller":"traceutil/trace.go:171","msg":"trace[723241355] transaction","detail":"{read_only:false; response_revision:23293; number_of_response:1; }","duration":"223.144019ms","start":"2026-02-24T20:46:38.170252Z","end":"2026-02-24T20:46:38.393396Z","steps":["trace[723241355] 'process raft request' (duration: 222.647046ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:38.393941Z","caller":"traceutil/trace.go:171","msg":"trace[1624625150] linearizableReadLoop","detail":"{readStateIndex:24110; appliedIndex:24106; }","duration":"189.098082ms","start":"2026-02-24T20:46:38.204823Z","end":"2026-02-24T20:46:38.393921Z","steps":["trace[1624625150] 'read index received' (duration: 115.724437ms)","trace[1624625150] 'applied index is now lower than readState.Index' (duration: 73.372825ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:38.394061Z","caller":"traceutil/trace.go:171","msg":"trace[414702619] transaction","detail":"{read_only:false; response_revision:23296; number_of_response:1; }","duration":"184.596971ms","start":"2026-02-24T20:46:38.209456Z","end":"2026-02-24T20:46:38.394053Z","steps":["trace[414702619] 'process raft request' (duration: 183.774949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.394219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.044276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3214"} {"level":"info","ts":"2026-02-24T20:46:38.3943Z","caller":"traceutil/trace.go:171","msg":"trace[86642459] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:23296; }","duration":"327.15818ms","start":"2026-02-24T20:46:38.067126Z","end":"2026-02-24T20:46:38.394284Z","steps":["trace[86642459] 'agreement among raft nodes before linearized reading' (duration: 326.898413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.394349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.067119Z","time spent":"327.217341ms","remote":"127.0.0.1:36710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3237,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-02-24T20:46:41.171741Z","caller":"traceutil/trace.go:171","msg":"trace[590991395] linearizableReadLoop","detail":"{readStateIndex:24146; appliedIndex:24145; }","duration":"395.347136ms","start":"2026-02-24T20:46:40.776365Z","end":"2026-02-24T20:46:41.171712Z","steps":["trace[590991395] 'read index received' (duration: 395.202192ms)","trace[590991395] 'applied index is now lower than readState.Index' (duration: 143.864µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:41.171868Z","caller":"traceutil/trace.go:171","msg":"trace[662885746] transaction","detail":"{read_only:false; response_revision:23330; number_of_response:1; }","duration":"458.163247ms","start":"2026-02-24T20:46:40.713625Z","end":"2026-02-24T20:46:41.171789Z","steps":["trace[662885746] 'process raft request' (duration: 457.875739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:41.171956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.577043ms","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-02-24T20:46:41.172003Z","caller":"traceutil/trace.go:171","msg":"trace[1359998970] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:23330; }","duration":"395.686616ms","start":"2026-02-24T20:46:40.776303Z","end":"2026-02-24T20:46:41.17199Z","steps":["trace[1359998970] 'agreement among raft nodes before linearized reading' (duration: 395.528151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:41.172035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:40.713599Z","time spent":"458.347892ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10524,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:41.172049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:40.776275Z","time spent":"395.764558ms","remote":"127.0.0.1:56760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-02-24T20:46:41.403846Z","caller":"traceutil/trace.go:171","msg":"trace[477537462] linearizableReadLoop","detail":"{readStateIndex:24147; appliedIndex:24146; }","duration":"232.005496ms","start":"2026-02-24T20:46:41.171809Z","end":"2026-02-24T20:46:41.403815Z","steps":["trace[477537462] 'read index received' (duration: 132.761354ms)","trace[477537462] 'applied index is now lower than readState.Index' (duration: 99.242822ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:41.403964Z","caller":"traceutil/trace.go:171","msg":"trace[1114811621] transaction","detail":"{read_only:false; response_revision:23331; number_of_response:1; }","duration":"359.48216ms","start":"2026-02-24T20:46:41.044467Z","end":"2026-02-24T20:46:41.403949Z","steps":["trace[1114811621] 'process raft request' (duration: 260.085194ms)","trace[1114811621] 'compare' (duration: 99.054217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:41.404015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.035252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:41.404075Z","caller":"traceutil/trace.go:171","msg":"trace[853822026] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:23331; }","duration":"527.192056ms","start":"2026-02-24T20:46:40.876869Z","end":"2026-02-24T20:46:41.404061Z","steps":["trace[853822026] 'agreement among raft nodes before linearized reading' (duration: 527.075643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:41.404072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:41.044443Z","time spent":"359.549792ms","remote":"127.0.0.1:56862","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-02-24T20:46:41.40412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:40.876805Z","time spent":"527.305249ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:46:41.44123Z","caller":"traceutil/trace.go:171","msg":"trace[2683208] transaction","detail":"{read_only:false; response_revision:23333; number_of_response:1; }","duration":"260.979826ms","start":"2026-02-24T20:46:41.180226Z","end":"2026-02-24T20:46:41.441205Z","steps":["trace[2683208] 'process raft request' (duration: 260.855803ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:41.441245Z","caller":"traceutil/trace.go:171","msg":"trace[1622869292] transaction","detail":"{read_only:false; response_revision:23332; number_of_response:1; }","duration":"262.781455ms","start":"2026-02-24T20:46:41.178424Z","end":"2026-02-24T20:46:41.441205Z","steps":["trace[1622869292] 'process raft request' (duration: 262.522499ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:41.441675Z","caller":"traceutil/trace.go:171","msg":"trace[1579392336] transaction","detail":"{read_only:false; response_revision:23334; number_of_response:1; }","duration":"261.379607ms","start":"2026-02-24T20:46:41.180277Z","end":"2026-02-24T20:46:41.441656Z","steps":["trace[1579392336] 'process raft request' (duration: 260.870744ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:41.441892Z","caller":"traceutil/trace.go:171","msg":"trace[1715929460] transaction","detail":"{read_only:false; response_revision:23335; number_of_response:1; }","duration":"261.467409ms","start":"2026-02-24T20:46:41.180414Z","end":"2026-02-24T20:46:41.441882Z","steps":["trace[1715929460] 'process raft request' (duration: 260.994207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:41.441928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.196308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-02-24T20:46:41.442067Z","caller":"traceutil/trace.go:171","msg":"trace[817267726] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99; range_end:; response_count:1; response_revision:23335; }","duration":"266.352602ms","start":"2026-02-24T20:46:41.175667Z","end":"2026-02-24T20:46:41.442019Z","steps":["trace[817267726] 'agreement among raft nodes before linearized reading' (duration: 266.000063ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:41.609233Z","caller":"traceutil/trace.go:171","msg":"trace[1582473378] transaction","detail":"{read_only:false; response_revision:23337; number_of_response:1; }","duration":"160.572005ms","start":"2026-02-24T20:46:41.448614Z","end":"2026-02-24T20:46:41.609186Z","steps":["trace[1582473378] 'process raft request' (duration: 157.462251ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:59.804224Z","caller":"traceutil/trace.go:171","msg":"trace[453314947] linearizableReadLoop","detail":"{readStateIndex:24288; appliedIndex:24287; }","duration":"242.231223ms","start":"2026-02-24T20:46:59.561968Z","end":"2026-02-24T20:46:59.804199Z","steps":["trace[453314947] 'read index received' (duration: 242.012617ms)","trace[453314947] 'applied index is now lower than readState.Index' (duration: 217.406µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:59.804314Z","caller":"traceutil/trace.go:171","msg":"trace[1767906670] transaction","detail":"{read_only:false; response_revision:23468; number_of_response:1; }","duration":"257.72315ms","start":"2026-02-24T20:46:59.546571Z","end":"2026-02-24T20:46:59.804294Z","steps":["trace[1767906670] 'process raft request' (duration: 257.443132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:59.804434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.482568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:59.804487Z","caller":"traceutil/trace.go:171","msg":"trace[1258489264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23468; }","duration":"194.58081ms","start":"2026-02-24T20:46:59.609892Z","end":"2026-02-24T20:46:59.804473Z","steps":["trace[1258489264] 'agreement among raft nodes before linearized reading' (duration: 194.471198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:59.804485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.515821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-02-24T20:46:59.804527Z","caller":"traceutil/trace.go:171","msg":"trace[1161989391] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:23468; }","duration":"242.612284ms","start":"2026-02-24T20:46:59.561906Z","end":"2026-02-24T20:46:59.804519Z","steps":["trace[1161989391] 'agreement among raft nodes before linearized reading' (duration: 242.400458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:59.804812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.939509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:46:59.804861Z","caller":"traceutil/trace.go:171","msg":"trace[548489702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23468; }","duration":"117.024971ms","start":"2026-02-24T20:46:59.687825Z","end":"2026-02-24T20:46:59.80485Z","steps":["trace[548489702] 'agreement among raft nodes before linearized reading' (duration: 116.894658ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:00.350794Z","caller":"traceutil/trace.go:171","msg":"trace[1088107696] transaction","detail":"{read_only:false; response_revision:23469; number_of_response:1; }","duration":"539.383554ms","start":"2026-02-24T20:46:59.811385Z","end":"2026-02-24T20:47:00.350768Z","steps":["trace[1088107696] 'process raft request' (duration: 500.891518ms)","trace[1088107696] 'compare' (duration: 38.324322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:00.350921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:59.811363Z","time spent":"539.491897ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:00.812889Z","caller":"traceutil/trace.go:171","msg":"trace[747342677] transaction","detail":"{read_only:false; response_revision:23470; number_of_response:1; }","duration":"996.912024ms","start":"2026-02-24T20:46:59.815953Z","end":"2026-02-24T20:47:00.812865Z","steps":["trace[747342677] 'process raft request' (duration: 996.693698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:00.813049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:59.815944Z","time spent":"997.023387ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10524,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:00.813175Z","caller":"traceutil/trace.go:171","msg":"trace[997638823] linearizableReadLoop","detail":"{readStateIndex:24290; appliedIndex:24288; }","duration":"975.668342ms","start":"2026-02-24T20:46:59.83748Z","end":"2026-02-24T20:47:00.813148Z","steps":["trace[997638823] 'read index received' (duration: 474.909108ms)","trace[997638823] 'applied index is now lower than readState.Index' (duration: 500.756904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:00.813418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.932729ms","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-02-24T20:47:00.813374Z","caller":"traceutil/trace.go:171","msg":"trace[1159069393] transaction","detail":"{read_only:false; response_revision:23471; number_of_response:1; }","duration":"529.235081ms","start":"2026-02-24T20:47:00.284046Z","end":"2026-02-24T20:47:00.813282Z","steps":["trace[1159069393] 'process raft request' (duration: 528.759098ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:00.813465Z","caller":"traceutil/trace.go:171","msg":"trace[1875503885] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:23471; }","duration":"975.98265ms","start":"2026-02-24T20:46:59.837467Z","end":"2026-02-24T20:47:00.81345Z","steps":["trace[1875503885] 'agreement among raft nodes before linearized reading' (duration: 975.782975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:00.813503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:59.837422Z","time spent":"976.073522ms","remote":"127.0.0.1:56760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-02-24T20:47:00.813729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.284021Z","time spent":"529.510488ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:01.299486Z","caller":"traceutil/trace.go:171","msg":"trace[1146602530] transaction","detail":"{read_only:false; response_revision:23472; number_of_response:1; }","duration":"838.298233ms","start":"2026-02-24T20:47:00.461162Z","end":"2026-02-24T20:47:01.299461Z","steps":["trace[1146602530] 'process raft request' (duration: 838.096607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.299646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.461136Z","time spent":"838.425537ms","remote":"127.0.0.1:56862","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-02-24T20:47:01.299921Z","caller":"traceutil/trace.go:171","msg":"trace[1991721613] transaction","detail":"{read_only:false; response_revision:23473; number_of_response:1; }","duration":"834.07491ms","start":"2026-02-24T20:47:00.465809Z","end":"2026-02-24T20:47:01.299884Z","steps":["trace[1991721613] 'process raft request' (duration: 833.609196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.300107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.465758Z","time spent":"834.247814ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:01.300213Z","caller":"traceutil/trace.go:171","msg":"trace[254597773] transaction","detail":"{read_only:false; response_revision:23474; number_of_response:1; }","duration":"830.871684ms","start":"2026-02-24T20:47:00.469317Z","end":"2026-02-24T20:47:01.300188Z","steps":["trace[254597773] 'process raft request' (duration: 830.542435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.30031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.469304Z","time spent":"830.961906ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:01.300283Z","caller":"traceutil/trace.go:171","msg":"trace[806409143] linearizableReadLoop","detail":"{readStateIndex:24296; appliedIndex:24291; }","duration":"487.011554ms","start":"2026-02-24T20:47:00.813222Z","end":"2026-02-24T20:47:01.300234Z","steps":["trace[806409143] 'read index received' (duration: 486.019117ms)","trace[806409143] 'applied index is now lower than readState.Index' (duration: 990.327µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:01.300432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.965647ms","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-02-24T20:47:01.300438Z","caller":"traceutil/trace.go:171","msg":"trace[537838068] transaction","detail":"{read_only:false; response_revision:23476; number_of_response:1; }","duration":"824.332497ms","start":"2026-02-24T20:47:00.476083Z","end":"2026-02-24T20:47:01.300415Z","steps":["trace[537838068] 'process raft request' (duration: 824.06442ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:01.300481Z","caller":"traceutil/trace.go:171","msg":"trace[1371057158] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23476; }","duration":"707.051169ms","start":"2026-02-24T20:47:00.593417Z","end":"2026-02-24T20:47:01.300468Z","steps":["trace[1371057158] 'agreement among raft nodes before linearized reading' (duration: 706.828333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.300523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.593398Z","time spent":"707.115192ms","remote":"127.0.0.1:56862","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-02-24T20:47:01.300567Z","caller":"traceutil/trace.go:171","msg":"trace[1233346612] transaction","detail":"{read_only:false; response_revision:23475; number_of_response:1; }","duration":"829.741543ms","start":"2026-02-24T20:47:00.470815Z","end":"2026-02-24T20:47:01.300556Z","steps":["trace[1233346612] 'process raft request' (duration: 829.25901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.300622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.470806Z","time spent":"829.786144ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:01.300681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.790361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12012"} {"level":"info","ts":"2026-02-24T20:47:01.300718Z","caller":"traceutil/trace.go:171","msg":"trace[786206247] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23476; }","duration":"508.863373ms","start":"2026-02-24T20:47:00.791845Z","end":"2026-02-24T20:47:01.300708Z","steps":["trace[786206247] 'agreement among raft nodes before linearized reading' (duration: 508.75807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.300752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.791822Z","time spent":"508.923885ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12035,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-24T20:47:01.300787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239839605s","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-02-24T20:47:01.300839Z","caller":"traceutil/trace.go:171","msg":"trace[271256752] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23476; }","duration":"1.239959449s","start":"2026-02-24T20:47:00.060868Z","end":"2026-02-24T20:47:01.300827Z","steps":["trace[271256752] 'agreement among raft nodes before linearized reading' (duration: 1.239869987s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.300884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.060843Z","time spent":"1.240031751s","remote":"127.0.0.1:58042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:47:01.300529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.476071Z","time spent":"824.410039ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:01.301134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.197925ms","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":"warn","ts":"2026-02-24T20:47:01.301162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314357712s","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-02-24T20:47:01.301187Z","caller":"traceutil/trace.go:171","msg":"trace[157400348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23476; }","duration":"804.270897ms","start":"2026-02-24T20:47:00.496891Z","end":"2026-02-24T20:47:01.301161Z","steps":["trace[157400348] 'agreement among raft nodes before linearized reading' (duration: 804.143493ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:01.301198Z","caller":"traceutil/trace.go:171","msg":"trace[439158079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23476; }","duration":"1.314417153s","start":"2026-02-24T20:46:59.98677Z","end":"2026-02-24T20:47:01.301187Z","steps":["trace[439158079] 'agreement among raft nodes before linearized reading' (duration: 1.314310161s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.301162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.267003ms","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-02-24T20:47:01.301229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:59.986752Z","time spent":"1.314470284s","remote":"127.0.0.1:56862","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":"info","ts":"2026-02-24T20:47:01.301276Z","caller":"traceutil/trace.go:171","msg":"trace[1577342345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23476; }","duration":"802.736115ms","start":"2026-02-24T20:47:00.498513Z","end":"2026-02-24T20:47:01.30125Z","steps":["trace[1577342345] 'agreement among raft nodes before linearized reading' (duration: 801.943324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.301222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.496873Z","time spent":"804.342959ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T20:47:01.301865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.498507Z","time spent":"802.832098ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T20:47:01.777074Z","caller":"traceutil/trace.go:171","msg":"trace[2056830506] linearizableReadLoop","detail":"{readStateIndex:24297; appliedIndex:24296; }","duration":"476.635484ms","start":"2026-02-24T20:47:01.300403Z","end":"2026-02-24T20:47:01.777038Z","steps":["trace[2056830506] 'read index received' (duration: 476.464749ms)","trace[2056830506] 'applied index is now lower than readState.Index' (duration: 169.825µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:01.777183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.861759ms","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-02-24T20:47:01.777228Z","caller":"traceutil/trace.go:171","msg":"trace[841178293] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23477; }","duration":"828.954372ms","start":"2026-02-24T20:47:00.948262Z","end":"2026-02-24T20:47:01.777216Z","steps":["trace[841178293] 'agreement among raft nodes before linearized reading' (duration: 828.784937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.777257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.359946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:215"} {"level":"warn","ts":"2026-02-24T20:47:01.777287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.948243Z","time spent":"829.036494ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-02-24T20:47:01.777297Z","caller":"traceutil/trace.go:171","msg":"trace[1599735366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:23477; }","duration":"958.417707ms","start":"2026-02-24T20:47:00.818868Z","end":"2026-02-24T20:47:01.777286Z","steps":["trace[1599735366] 'agreement among raft nodes before linearized reading' (duration: 958.300404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.77733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.818862Z","time spent":"958.459458ms","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-02-24T20:47:01.77756Z","caller":"traceutil/trace.go:171","msg":"trace[1283339814] transaction","detail":"{read_only:false; response_revision:23477; number_of_response:1; }","duration":"950.72519ms","start":"2026-02-24T20:47:00.826825Z","end":"2026-02-24T20:47:01.777551Z","steps":["trace[1283339814] 'process raft request' (duration: 950.044952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.777629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.91128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:204"} {"level":"info","ts":"2026-02-24T20:47:01.777662Z","caller":"traceutil/trace.go:171","msg":"trace[1739619930] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:23477; }","duration":"958.961972ms","start":"2026-02-24T20:47:00.818691Z","end":"2026-02-24T20:47:01.777653Z","steps":["trace[1739619930] 'agreement among raft nodes before linearized reading' (duration: 958.87599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.777698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.818684Z","time spent":"959.007523ms","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-24T20:47:01.777778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.826802Z","time spent":"950.785161ms","remote":"127.0.0.1:57064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9586,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:01.777891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.796704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-02-24T20:47:01.777927Z","caller":"traceutil/trace.go:171","msg":"trace[453934518] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99; range_end:; response_count:1; response_revision:23477; }","duration":"959.879276ms","start":"2026-02-24T20:47:00.818039Z","end":"2026-02-24T20:47:01.777918Z","steps":["trace[453934518] 'agreement among raft nodes before linearized reading' (duration: 959.786874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:01.777964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:00.818017Z","time spent":"959.939857ms","remote":"127.0.0.1:56744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-3f473f2b-0043-420c-b2ed-c809e89e7a99\" "} {"level":"warn","ts":"2026-02-24T20:47:02.278042Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058306525587488,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T20:47:02.396053Z","caller":"traceutil/trace.go:171","msg":"trace[1634486458] transaction","detail":"{read_only:false; response_revision:23478; number_of_response:1; }","duration":"1.090387711s","start":"2026-02-24T20:47:01.305641Z","end":"2026-02-24T20:47:02.396028Z","steps":["trace[1634486458] 'process raft request' (duration: 1.090115894s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.39618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.305629Z","time spent":"1.090475143s","remote":"127.0.0.1:56862","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-02-24T20:47:02.39666Z","caller":"traceutil/trace.go:171","msg":"trace[1872470709] transaction","detail":"{read_only:false; response_revision:23482; number_of_response:1; }","duration":"963.503334ms","start":"2026-02-24T20:47:01.43313Z","end":"2026-02-24T20:47:02.396633Z","steps":["trace[1872470709] 'process raft request' (duration: 963.440913ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:02.396692Z","caller":"traceutil/trace.go:171","msg":"trace[766762318] transaction","detail":"{read_only:false; response_revision:23479; number_of_response:1; }","duration":"1.090949966s","start":"2026-02-24T20:47:01.305708Z","end":"2026-02-24T20:47:02.396658Z","steps":["trace[766762318] 'process raft request' (duration: 1.090226917s)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:02.396763Z","caller":"traceutil/trace.go:171","msg":"trace[1535813360] transaction","detail":"{read_only:false; response_revision:23480; number_of_response:1; }","duration":"1.089715163s","start":"2026-02-24T20:47:01.307018Z","end":"2026-02-24T20:47:02.396733Z","steps":["trace[1535813360] 'process raft request' (duration: 1.089334003s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.396845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.43311Z","time spent":"963.632668ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:02.396949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.30701Z","time spent":"1.089821756s","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:02.397031Z","caller":"traceutil/trace.go:171","msg":"trace[1852231523] transaction","detail":"{read_only:false; response_revision:23481; number_of_response:1; }","duration":"1.089926769s","start":"2026-02-24T20:47:01.307093Z","end":"2026-02-24T20:47:02.39702Z","steps":["trace[1852231523] 'process raft request' (duration: 1.089314202s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.396851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.305689Z","time spent":"1.091080059s","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:02.397093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.307074Z","time spent":"1.08998462s","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:02.816055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.403766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:47:02.816287Z","caller":"traceutil/trace.go:171","msg":"trace[46480038] linearizableReadLoop","detail":"{readStateIndex:24304; appliedIndex:24297; }","duration":"1.03912669s","start":"2026-02-24T20:47:01.777141Z","end":"2026-02-24T20:47:02.816267Z","steps":["trace[46480038] 'read index received' (duration: 618.585706ms)","trace[46480038] 'applied index is now lower than readState.Index' (duration: 420.539734ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:02.816379Z","caller":"traceutil/trace.go:171","msg":"trace[1256009162] transaction","detail":"{read_only:false; response_revision:23484; number_of_response:1; }","duration":"844.196402ms","start":"2026-02-24T20:47:01.97217Z","end":"2026-02-24T20:47:02.816366Z","steps":["trace[1256009162] 'process raft request' (duration: 844.018847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.816457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.972142Z","time spent":"844.272863ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:02.81656Z","caller":"traceutil/trace.go:171","msg":"trace[1434621660] transaction","detail":"{read_only:false; response_revision:23483; number_of_response:1; }","duration":"1.033601632s","start":"2026-02-24T20:47:01.782916Z","end":"2026-02-24T20:47:02.816518Z","steps":["trace[1434621660] 'process raft request' (duration: 744.61657ms)","trace[1434621660] 'compare' (duration: 288.235302ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:02.816777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.7829Z","time spent":"1.033759837s","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:02.816754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209729935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:47:02.816865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511426308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12012"} {"level":"info","ts":"2026-02-24T20:47:02.816872Z","caller":"traceutil/trace.go:171","msg":"trace[1206247000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23484; }","duration":"1.20991445s","start":"2026-02-24T20:47:01.60693Z","end":"2026-02-24T20:47:02.816845Z","steps":["trace[1206247000] 'agreement among raft nodes before linearized reading' (duration: 1.209708794s)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:02.816926Z","caller":"traceutil/trace.go:171","msg":"trace[752049442] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23484; }","duration":"1.511530331s","start":"2026-02-24T20:47:01.30538Z","end":"2026-02-24T20:47:02.81691Z","steps":["trace[752049442] 'agreement among raft nodes before linearized reading' (duration: 1.511340266s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.816944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.606908Z","time spent":"1.210023643s","remote":"127.0.0.1:56586","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-02-24T20:47:02.816975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.305339Z","time spent":"1.511626874s","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12035,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-24T20:47:02.838762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.83076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:47:02.838811Z","caller":"traceutil/trace.go:171","msg":"trace[26253260] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:23484; }","duration":"300.932693ms","start":"2026-02-24T20:47:02.537867Z","end":"2026-02-24T20:47:02.8388Z","steps":["trace[26253260] 'agreement among raft nodes before linearized reading' (duration: 300.84493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.838837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:02.537849Z","time spent":"300.981945ms","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:47:02.839052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.447773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:47:02.839078Z","caller":"traceutil/trace.go:171","msg":"trace[1260170329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23485; }","duration":"468.517525ms","start":"2026-02-24T20:47:02.370554Z","end":"2026-02-24T20:47:02.839071Z","steps":["trace[1260170329] 'agreement among raft nodes before linearized reading' (duration: 468.417982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.839096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:02.370526Z","time spent":"468.565987ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T20:47:02.839182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02794804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T20:47:02.839204Z","caller":"traceutil/trace.go:171","msg":"trace[1618453171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23485; }","duration":"1.02796805s","start":"2026-02-24T20:47:01.811231Z","end":"2026-02-24T20:47:02.839199Z","steps":["trace[1618453171] 'agreement among raft nodes before linearized reading' (duration: 1.027916159s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.83922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.811187Z","time spent":"1.028029652s","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T20:47:02.8396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057255168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:204"} {"level":"info","ts":"2026-02-24T20:47:02.839629Z","caller":"traceutil/trace.go:171","msg":"trace[508525096] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:23485; }","duration":"1.05731551s","start":"2026-02-24T20:47:01.782308Z","end":"2026-02-24T20:47:02.839624Z","steps":["trace[508525096] 'agreement among raft nodes before linearized reading' (duration: 1.057241048s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.839652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.782294Z","time spent":"1.057351421s","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-24T20:47:02.839837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057482584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-02-24T20:47:02.839863Z","caller":"traceutil/trace.go:171","msg":"trace[987489633] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:23485; }","duration":"1.057549886s","start":"2026-02-24T20:47:01.782308Z","end":"2026-02-24T20:47:02.839857Z","steps":["trace[987489633] 'agreement among raft nodes before linearized reading' (duration: 1.057480724s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.839883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.782279Z","time spent":"1.057599857s","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-24T20:47:02.839576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05470733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3214"} {"level":"info","ts":"2026-02-24T20:47:02.839975Z","caller":"traceutil/trace.go:171","msg":"trace[1376888934] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:23485; }","duration":"1.055151232s","start":"2026-02-24T20:47:01.784816Z","end":"2026-02-24T20:47:02.839967Z","steps":["trace[1376888934] 'agreement among raft nodes before linearized reading' (duration: 1.054642488s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:02.839998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:01.784805Z","time spent":"1.055184012s","remote":"127.0.0.1:36710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3237,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-02-24T20:47:03.035812Z","caller":"traceutil/trace.go:171","msg":"trace[2024309900] transaction","detail":"{read_only:false; response_revision:23487; number_of_response:1; }","duration":"186.85471ms","start":"2026-02-24T20:47:02.848917Z","end":"2026-02-24T20:47:03.035771Z","steps":["trace[2024309900] 'process raft request' (duration: 186.619354ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:03.045194Z","caller":"traceutil/trace.go:171","msg":"trace[164037655] transaction","detail":"{read_only:false; response_revision:23488; number_of_response:1; }","duration":"191.676542ms","start":"2026-02-24T20:47:02.853491Z","end":"2026-02-24T20:47:03.045168Z","steps":["trace[164037655] 'process raft request' (duration: 191.297812ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:03.045334Z","caller":"traceutil/trace.go:171","msg":"trace[857745343] transaction","detail":"{read_only:false; response_revision:23489; number_of_response:1; }","duration":"191.790534ms","start":"2026-02-24T20:47:02.853512Z","end":"2026-02-24T20:47:03.045303Z","steps":["trace[857745343] 'process raft request' (duration: 191.433125ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:03.045748Z","caller":"traceutil/trace.go:171","msg":"trace[1460388699] linearizableReadLoop","detail":"{readStateIndex:24311; appliedIndex:24308; }","duration":"191.692452ms","start":"2026-02-24T20:47:02.854043Z","end":"2026-02-24T20:47:03.045736Z","steps":["trace[1460388699] 'read index received' (duration: 182.238777ms)","trace[1460388699] 'applied index is now lower than readState.Index' (duration: 9.453095ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:03.04597Z","caller":"traceutil/trace.go:171","msg":"trace[1945152422] transaction","detail":"{read_only:false; response_revision:23490; number_of_response:1; }","duration":"192.290838ms","start":"2026-02-24T20:47:02.85367Z","end":"2026-02-24T20:47:03.04596Z","steps":["trace[1945152422] 'process raft request' (duration: 191.947379ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:03.046154Z","caller":"traceutil/trace.go:171","msg":"trace[1595550355] transaction","detail":"{read_only:false; response_revision:23491; number_of_response:1; }","duration":"181.759985ms","start":"2026-02-24T20:47:02.864386Z","end":"2026-02-24T20:47:03.046146Z","steps":["trace[1595550355] 'process raft request' (duration: 181.300193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:03.046395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.116589ms","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-02-24T20:47:03.046433Z","caller":"traceutil/trace.go:171","msg":"trace[1128863235] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23491; }","duration":"138.233182ms","start":"2026-02-24T20:47:02.908191Z","end":"2026-02-24T20:47:03.046424Z","steps":["trace[1128863235] 'agreement among raft nodes before linearized reading' (duration: 138.1488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:03.046487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.39741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:47:03.046582Z","caller":"traceutil/trace.go:171","msg":"trace[638752182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23491; }","duration":"192.613197ms","start":"2026-02-24T20:47:02.853945Z","end":"2026-02-24T20:47:03.046559Z","steps":["trace[638752182] 'agreement among raft nodes before linearized reading' (duration: 192.391311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:03.046594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.054002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:47:03.046637Z","caller":"traceutil/trace.go:171","msg":"trace[1888933309] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:23491; }","duration":"133.139645ms","start":"2026-02-24T20:47:02.913485Z","end":"2026-02-24T20:47:03.046625Z","steps":["trace[1888933309] 'agreement among raft nodes before linearized reading' (duration: 133.022711ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:28.708696Z","caller":"traceutil/trace.go:171","msg":"trace[496080627] linearizableReadLoop","detail":"{readStateIndex:24498; appliedIndex:24497; }","duration":"185.887816ms","start":"2026-02-24T20:47:28.522783Z","end":"2026-02-24T20:47:28.708671Z","steps":["trace[496080627] 'read index received' (duration: 185.735212ms)","trace[496080627] 'applied index is now lower than readState.Index' (duration: 151.494µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:28.708743Z","caller":"traceutil/trace.go:171","msg":"trace[952068581] transaction","detail":"{read_only:false; response_revision:23671; number_of_response:1; }","duration":"280.40309ms","start":"2026-02-24T20:47:28.428317Z","end":"2026-02-24T20:47:28.70872Z","steps":["trace[952068581] 'process raft request' (duration: 280.177114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:28.708941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.131892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-02-24T20:47:28.708972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.960025ms","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-02-24T20:47:28.709031Z","caller":"traceutil/trace.go:171","msg":"trace[478531323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23671; }","duration":"186.266176ms","start":"2026-02-24T20:47:28.522746Z","end":"2026-02-24T20:47:28.709012Z","steps":["trace[478531323] 'agreement among raft nodes before linearized reading' (duration: 186.024499ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:28.709032Z","caller":"traceutil/trace.go:171","msg":"trace[1127537922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23671; }","duration":"180.054558ms","start":"2026-02-24T20:47:28.528961Z","end":"2026-02-24T20:47:28.709016Z","steps":["trace[1127537922] 'agreement among raft nodes before linearized reading' (duration: 179.917424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:28.709056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.512228ms","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-02-24T20:47:28.709097Z","caller":"traceutil/trace.go:171","msg":"trace[1320487334] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23671; }","duration":"181.57913ms","start":"2026-02-24T20:47:28.527501Z","end":"2026-02-24T20:47:28.70908Z","steps":["trace[1320487334] 'agreement among raft nodes before linearized reading' (duration: 181.500578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:28.708975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.080723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12012"} {"level":"info","ts":"2026-02-24T20:47:28.709158Z","caller":"traceutil/trace.go:171","msg":"trace[758657418] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23671; }","duration":"185.29807ms","start":"2026-02-24T20:47:28.52385Z","end":"2026-02-24T20:47:28.709148Z","steps":["trace[758657418] 'agreement among raft nodes before linearized reading' (duration: 184.970141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:28.709192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.764653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:47:28.709233Z","caller":"traceutil/trace.go:171","msg":"trace[1238810138] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:23671; }","duration":"100.836925ms","start":"2026-02-24T20:47:28.608382Z","end":"2026-02-24T20:47:28.709219Z","steps":["trace[1238810138] 'agreement among raft nodes before linearized reading' (duration: 100.764483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:28.709245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.256318ms","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-02-24T20:47:28.709282Z","caller":"traceutil/trace.go:171","msg":"trace[1119830442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23671; }","duration":"185.30675ms","start":"2026-02-24T20:47:28.523966Z","end":"2026-02-24T20:47:28.709273Z","steps":["trace[1119830442] 'agreement among raft nodes before linearized reading' (duration: 185.222018ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:28.889309Z","caller":"traceutil/trace.go:171","msg":"trace[44455362] linearizableReadLoop","detail":"{readStateIndex:24499; appliedIndex:24498; }","duration":"175.555968ms","start":"2026-02-24T20:47:28.713722Z","end":"2026-02-24T20:47:28.889278Z","steps":["trace[44455362] 'read index received' (duration: 152.194019ms)","trace[44455362] 'applied index is now lower than readState.Index' (duration: 23.360538ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:28.889407Z","caller":"traceutil/trace.go:171","msg":"trace[2068364065] transaction","detail":"{read_only:false; response_revision:23672; number_of_response:1; }","duration":"175.832405ms","start":"2026-02-24T20:47:28.713504Z","end":"2026-02-24T20:47:28.889337Z","steps":["trace[2068364065] 'process raft request' (duration: 152.396374ms)","trace[2068364065] 'compare' (duration: 23.261536ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:28.88956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.837435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12012"} {"level":"info","ts":"2026-02-24T20:47:28.889622Z","caller":"traceutil/trace.go:171","msg":"trace[1270434551] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23672; }","duration":"176.013839ms","start":"2026-02-24T20:47:28.713595Z","end":"2026-02-24T20:47:28.889609Z","steps":["trace[1270434551] 'agreement among raft nodes before linearized reading' (duration: 175.780113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.29788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.716038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:47:29.298154Z","caller":"traceutil/trace.go:171","msg":"trace[635666823] transaction","detail":"{read_only:false; response_revision:23673; number_of_response:1; }","duration":"584.19207ms","start":"2026-02-24T20:47:28.713923Z","end":"2026-02-24T20:47:29.298115Z","steps":["trace[635666823] 'process raft request' (duration: 433.149563ms)","trace[635666823] 'compare' (duration: 150.519923ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:29.298186Z","caller":"traceutil/trace.go:171","msg":"trace[543989032] linearizableReadLoop","detail":"{readStateIndex:24502; appliedIndex:24499; }","duration":"408.769487ms","start":"2026-02-24T20:47:28.889396Z","end":"2026-02-24T20:47:29.298166Z","steps":["trace[543989032] 'read index received' (duration: 257.624027ms)","trace[543989032] 'applied index is now lower than readState.Index' (duration: 151.14444ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:29.298321Z","caller":"traceutil/trace.go:171","msg":"trace[901010423] transaction","detail":"{read_only:false; response_revision:23674; number_of_response:1; }","duration":"583.982245ms","start":"2026-02-24T20:47:28.714327Z","end":"2026-02-24T20:47:29.298309Z","steps":["trace[901010423] 'process raft request' (duration: 583.645346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.29835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:28.713909Z","time spent":"584.313403ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:29.298399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:28.714298Z","time spent":"584.051897ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:29.29845Z","caller":"traceutil/trace.go:171","msg":"trace[1981835331] transaction","detail":"{read_only:false; response_revision:23676; number_of_response:1; }","duration":"348.054602ms","start":"2026-02-24T20:47:28.950355Z","end":"2026-02-24T20:47:29.298409Z","steps":["trace[1981835331] 'process raft request' (duration: 347.770805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.298629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:28.950336Z","time spent":"348.182205ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:29.298635Z","caller":"traceutil/trace.go:171","msg":"trace[2105124729] transaction","detail":"{read_only:false; response_revision:23675; number_of_response:1; }","duration":"583.600954ms","start":"2026-02-24T20:47:28.715022Z","end":"2026-02-24T20:47:29.298623Z","steps":["trace[2105124729] 'process raft request' (duration: 583.042189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.298717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:28.71501Z","time spent":"583.662886ms","remote":"127.0.0.1:56862","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-02-24T20:47:29.298949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.843791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16641"} {"level":"warn","ts":"2026-02-24T20:47:29.298947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.931328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:47:29.298954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.512725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:47:29.298988Z","caller":"traceutil/trace.go:171","msg":"trace[198296486] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23676; }","duration":"407.898563ms","start":"2026-02-24T20:47:28.891077Z","end":"2026-02-24T20:47:29.298975Z","steps":["trace[198296486] 'agreement among raft nodes before linearized reading' (duration: 407.76434ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:29.298992Z","caller":"traceutil/trace.go:171","msg":"trace[657929295] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:23676; }","duration":"361.01233ms","start":"2026-02-24T20:47:28.937966Z","end":"2026-02-24T20:47:29.298978Z","steps":["trace[657929295] 'agreement among raft nodes before linearized reading' (duration: 360.933428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.299012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:28.891064Z","time spent":"407.942644ms","remote":"127.0.0.1:56774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":16664,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-24T20:47:29.299005Z","caller":"traceutil/trace.go:171","msg":"trace[1852529428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23676; }","duration":"328.563307ms","start":"2026-02-24T20:47:28.970428Z","end":"2026-02-24T20:47:29.298991Z","steps":["trace[1852529428] 'agreement among raft nodes before linearized reading' (duration: 328.430143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.299027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:28.937947Z","time spent":"361.070702ms","remote":"127.0.0.1:40534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:47:29.299056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:28.970396Z","time spent":"328.650909ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T20:47:29.299163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.525584ms","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-02-24T20:47:29.299246Z","caller":"traceutil/trace.go:171","msg":"trace[2004736674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23676; }","duration":"143.656288ms","start":"2026-02-24T20:47:29.155567Z","end":"2026-02-24T20:47:29.299224Z","steps":["trace[2004736674] 'agreement among raft nodes before linearized reading' (duration: 143.400801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.298965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.311568ms","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-02-24T20:47:29.299717Z","caller":"traceutil/trace.go:171","msg":"trace[1671282511] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23676; }","duration":"551.068109ms","start":"2026-02-24T20:47:28.748603Z","end":"2026-02-24T20:47:29.299671Z","steps":["trace[1671282511] 'agreement among raft nodes before linearized reading' (duration: 550.210405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.299769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:28.748591Z","time spent":"551.163491ms","remote":"127.0.0.1:56862","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":"info","ts":"2026-02-24T20:47:29.615745Z","caller":"traceutil/trace.go:171","msg":"trace[508671307] transaction","detail":"{read_only:false; response_revision:23680; number_of_response:1; }","duration":"202.479132ms","start":"2026-02-24T20:47:29.413151Z","end":"2026-02-24T20:47:29.61563Z","steps":["trace[508671307] 'process raft request' (duration: 184.618311ms)","trace[508671307] 'compare' (duration: 17.151682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:29.928189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.984962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:47:29.928838Z","caller":"traceutil/trace.go:171","msg":"trace[1117805828] transaction","detail":"{read_only:false; response_revision:23681; number_of_response:1; }","duration":"514.927206ms","start":"2026-02-24T20:47:29.41384Z","end":"2026-02-24T20:47:29.928767Z","steps":["trace[1117805828] 'process raft request' (duration: 375.249825ms)","trace[1117805828] 'compare' (duration: 138.816548ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:29.928991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.639078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:47:29.929029Z","caller":"traceutil/trace.go:171","msg":"trace[1915981182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23684; }","duration":"322.70686ms","start":"2026-02-24T20:47:29.606314Z","end":"2026-02-24T20:47:29.929021Z","steps":["trace[1915981182] 'agreement among raft nodes before linearized reading' (duration: 322.605137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.929062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:29.6063Z","time spent":"322.755341ms","remote":"127.0.0.1:56586","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-02-24T20:47:29.929145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:29.413822Z","time spent":"515.11133ms","remote":"127.0.0.1:56862","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-02-24T20:47:29.929314Z","caller":"traceutil/trace.go:171","msg":"trace[49116149] transaction","detail":"{read_only:false; response_revision:23684; number_of_response:1; }","duration":"513.756233ms","start":"2026-02-24T20:47:29.415547Z","end":"2026-02-24T20:47:29.929303Z","steps":["trace[49116149] 'process raft request' (duration: 513.27037ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:29.928888Z","caller":"traceutil/trace.go:171","msg":"trace[2001163380] linearizableReadLoop","detail":"{readStateIndex:24511; appliedIndex:24507; }","duration":"322.519225ms","start":"2026-02-24T20:47:29.606348Z","end":"2026-02-24T20:47:29.928867Z","steps":["trace[2001163380] 'read index received' (duration: 182.890195ms)","trace[2001163380] 'applied index is now lower than readState.Index' (duration: 139.627469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:29.929387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:29.415533Z","time spent":"513.812275ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:29.929329Z","caller":"traceutil/trace.go:171","msg":"trace[260088904] transaction","detail":"{read_only:false; response_revision:23683; number_of_response:1; }","duration":"514.882044ms","start":"2026-02-24T20:47:29.414413Z","end":"2026-02-24T20:47:29.929295Z","steps":["trace[260088904] 'process raft request' (duration: 514.36837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.929495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.43137ms","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-02-24T20:47:29.929556Z","caller":"traceutil/trace.go:171","msg":"trace[1191464370] 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:23684; }","duration":"267.548023ms","start":"2026-02-24T20:47:29.661994Z","end":"2026-02-24T20:47:29.929542Z","steps":["trace[1191464370] 'agreement among raft nodes before linearized reading' (duration: 267.42924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.929549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:29.414406Z","time spent":"515.028478ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:29.929553Z","caller":"traceutil/trace.go:171","msg":"trace[1483316834] transaction","detail":"{read_only:false; response_revision:23682; number_of_response:1; }","duration":"515.336706ms","start":"2026-02-24T20:47:29.414156Z","end":"2026-02-24T20:47:29.929493Z","steps":["trace[1483316834] 'process raft request' (duration: 514.569176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:29.929919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:29.414142Z","time spent":"515.681667ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:31.568444Z","caller":"traceutil/trace.go:171","msg":"trace[953556001] transaction","detail":"{read_only:false; response_revision:23686; number_of_response:1; }","duration":"262.058186ms","start":"2026-02-24T20:47:31.306354Z","end":"2026-02-24T20:47:31.568413Z","steps":["trace[953556001] 'process raft request' (duration: 261.896421ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:31.660791Z","caller":"traceutil/trace.go:171","msg":"trace[2037677605] transaction","detail":"{read_only:false; response_revision:23688; number_of_response:1; }","duration":"353.656242ms","start":"2026-02-24T20:47:31.30711Z","end":"2026-02-24T20:47:31.660766Z","steps":["trace[2037677605] 'process raft request' (duration: 353.516858ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:31.660846Z","caller":"traceutil/trace.go:171","msg":"trace[781668921] transaction","detail":"{read_only:false; response_revision:23687; number_of_response:1; }","duration":"353.779605ms","start":"2026-02-24T20:47:31.307033Z","end":"2026-02-24T20:47:31.660812Z","steps":["trace[781668921] 'process raft request' (duration: 352.286206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:31.660915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:31.307083Z","time spent":"353.765215ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:47:31.660901Z","caller":"traceutil/trace.go:171","msg":"trace[208689774] transaction","detail":"{read_only:false; response_revision:23689; number_of_response:1; }","duration":"353.455817ms","start":"2026-02-24T20:47:31.307414Z","end":"2026-02-24T20:47:31.66087Z","steps":["trace[208689774] 'process raft request' (duration: 353.249391ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:31.660944Z","caller":"traceutil/trace.go:171","msg":"trace[309749750] linearizableReadLoop","detail":"{readStateIndex:24516; appliedIndex:24512; }","duration":"315.872065ms","start":"2026-02-24T20:47:31.345028Z","end":"2026-02-24T20:47:31.6609Z","steps":["trace[309749750] 'read index received' (duration: 223.20198ms)","trace[309749750] 'applied index is now lower than readState.Index' (duration: 92.665975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:31.661008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:31.307022Z","time spent":"353.89802ms","remote":"127.0.0.1:56862","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-02-24T20:47:31.661053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:31.307406Z","time spent":"353.565171ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:47:31.661256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.204294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-02-24T20:47:31.661269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.192964ms","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-02-24T20:47:31.661317Z","caller":"traceutil/trace.go:171","msg":"trace[1078972819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23690; }","duration":"316.331417ms","start":"2026-02-24T20:47:31.344971Z","end":"2026-02-24T20:47:31.661302Z","steps":["trace[1078972819] 'agreement among raft nodes before linearized reading' (duration: 316.05083ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:31.661325Z","caller":"traceutil/trace.go:171","msg":"trace[1177476682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23690; }","duration":"316.285727ms","start":"2026-02-24T20:47:31.345025Z","end":"2026-02-24T20:47:31.66131Z","steps":["trace[1177476682] 'agreement among raft nodes before linearized reading' (duration: 316.085571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:31.661378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:31.344945Z","time spent":"316.40501ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T20:47:31.662994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:31.344853Z","time spent":"318.065163ms","remote":"127.0.0.1:56862","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-02-24T20:47:31.876715Z","caller":"traceutil/trace.go:171","msg":"trace[1666100881] transaction","detail":"{read_only:false; response_revision:23691; number_of_response:1; }","duration":"209.858451ms","start":"2026-02-24T20:47:31.666823Z","end":"2026-02-24T20:47:31.876682Z","steps":["trace[1666100881] 'process raft request' (duration: 126.558648ms)","trace[1666100881] 'compare' (duration: 83.154538ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:32.097671Z","caller":"traceutil/trace.go:171","msg":"trace[854453391] transaction","detail":"{read_only:false; response_revision:23692; number_of_response:1; }","duration":"430.707947ms","start":"2026-02-24T20:47:31.666922Z","end":"2026-02-24T20:47:32.09763Z","steps":["trace[854453391] 'process raft request' (duration: 404.422239ms)","trace[854453391] 'compare' (duration: 25.857916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:32.097817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:47:31.666899Z","time spent":"430.844321ms","remote":"127.0.0.1:56862","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-02-24T20:47:32.097837Z","caller":"traceutil/trace.go:171","msg":"trace[1821293063] linearizableReadLoop","detail":"{readStateIndex:24519; appliedIndex:24518; }","duration":"230.205218ms","start":"2026-02-24T20:47:31.867607Z","end":"2026-02-24T20:47:32.097812Z","steps":["trace[1821293063] 'read index received' (duration: 203.756427ms)","trace[1821293063] 'applied index is now lower than readState.Index' (duration: 26.447561ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:32.098209Z","caller":"traceutil/trace.go:171","msg":"trace[910546072] transaction","detail":"{read_only:false; response_revision:23693; number_of_response:1; }","duration":"161.92278ms","start":"2026-02-24T20:47:31.936268Z","end":"2026-02-24T20:47:32.098191Z","steps":["trace[910546072] 'process raft request' (duration: 161.502608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:32.098194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.494238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:47:32.098336Z","caller":"traceutil/trace.go:171","msg":"trace[1496961957] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:23692; }","duration":"230.755754ms","start":"2026-02-24T20:47:31.867548Z","end":"2026-02-24T20:47:32.098304Z","steps":["trace[1496961957] 'agreement among raft nodes before linearized reading' (duration: 230.405185ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:32.098533Z","caller":"traceutil/trace.go:171","msg":"trace[1985347659] transaction","detail":"{read_only:false; response_revision:23696; number_of_response:1; }","duration":"161.810447ms","start":"2026-02-24T20:47:31.936711Z","end":"2026-02-24T20:47:32.098522Z","steps":["trace[1985347659] 'process raft request' (duration: 161.759606ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:32.098555Z","caller":"traceutil/trace.go:171","msg":"trace[1412344744] transaction","detail":"{read_only:false; response_revision:23694; number_of_response:1; }","duration":"162.221358ms","start":"2026-02-24T20:47:31.93629Z","end":"2026-02-24T20:47:32.098511Z","steps":["trace[1412344744] 'process raft request' (duration: 161.852568ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:32.098623Z","caller":"traceutil/trace.go:171","msg":"trace[61807050] transaction","detail":"{read_only:false; response_revision:23695; number_of_response:1; }","duration":"161.92273ms","start":"2026-02-24T20:47:31.936694Z","end":"2026-02-24T20:47:32.098617Z","steps":["trace[61807050] 'process raft request' (duration: 161.681243ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:32.663503Z","caller":"traceutil/trace.go:171","msg":"trace[2014091453] transaction","detail":"{read_only:false; response_revision:23697; number_of_response:1; }","duration":"185.31512ms","start":"2026-02-24T20:47:32.478155Z","end":"2026-02-24T20:47:32.66347Z","steps":["trace[2014091453] 'process raft request' (duration: 185.139355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:33.120377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.112101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:47:33.120526Z","caller":"traceutil/trace.go:171","msg":"trace[1660146559] linearizableReadLoop","detail":"{readStateIndex:24527; appliedIndex:24526; }","duration":"177.258254ms","start":"2026-02-24T20:47:32.943249Z","end":"2026-02-24T20:47:33.120508Z","steps":["trace[1660146559] 'read index received' (duration: 29.906146ms)","trace[1660146559] 'applied index is now lower than readState.Index' (duration: 147.350648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:33.120694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.455579ms","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-02-24T20:47:33.120723Z","caller":"traceutil/trace.go:171","msg":"trace[1445447697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23699; }","duration":"177.564342ms","start":"2026-02-24T20:47:32.943148Z","end":"2026-02-24T20:47:33.120712Z","steps":["trace[1445447697] 'agreement among raft nodes before linearized reading' (duration: 177.407658ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:33.120885Z","caller":"traceutil/trace.go:171","msg":"trace[2114220754] transaction","detail":"{read_only:false; response_revision:23699; number_of_response:1; }","duration":"282.341403ms","start":"2026-02-24T20:47:32.83839Z","end":"2026-02-24T20:47:33.120731Z","steps":["trace[2114220754] 'process raft request' (duration: 134.755838ms)","trace[2114220754] 'compare' (duration: 146.971287ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:33.879805Z","caller":"traceutil/trace.go:171","msg":"trace[809368261] transaction","detail":"{read_only:false; response_revision:23702; number_of_response:1; }","duration":"213.104039ms","start":"2026-02-24T20:47:33.666675Z","end":"2026-02-24T20:47:33.879779Z","steps":["trace[809368261] 'process raft request' (duration: 212.952475ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:33.882696Z","caller":"traceutil/trace.go:171","msg":"trace[444578692] transaction","detail":"{read_only:false; response_revision:23704; number_of_response:1; }","duration":"213.642053ms","start":"2026-02-24T20:47:33.66902Z","end":"2026-02-24T20:47:33.882662Z","steps":["trace[444578692] 'process raft request' (duration: 213.52532ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:33.882719Z","caller":"traceutil/trace.go:171","msg":"trace[1115564789] transaction","detail":"{read_only:false; response_revision:23703; number_of_response:1; }","duration":"214.916077ms","start":"2026-02-24T20:47:33.667786Z","end":"2026-02-24T20:47:33.882702Z","steps":["trace[1115564789] 'process raft request' (duration: 214.63515ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:33.882809Z","caller":"traceutil/trace.go:171","msg":"trace[625279449] transaction","detail":"{read_only:false; response_revision:23705; number_of_response:1; }","duration":"212.923213ms","start":"2026-02-24T20:47:33.66982Z","end":"2026-02-24T20:47:33.882743Z","steps":["trace[625279449] 'process raft request' (duration: 212.762679ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:34.208326Z","caller":"traceutil/trace.go:171","msg":"trace[1146305315] transaction","detail":"{read_only:false; response_revision:23708; number_of_response:1; }","duration":"104.306698ms","start":"2026-02-24T20:47:34.103993Z","end":"2026-02-24T20:47:34.208299Z","steps":["trace[1146305315] 'process raft request' (duration: 104.144914ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:34.208616Z","caller":"traceutil/trace.go:171","msg":"trace[1075620694] transaction","detail":"{read_only:false; response_revision:23709; number_of_response:1; }","duration":"104.118384ms","start":"2026-02-24T20:47:34.104466Z","end":"2026-02-24T20:47:34.208584Z","steps":["trace[1075620694] 'process raft request' (duration: 103.788955ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:34.208642Z","caller":"traceutil/trace.go:171","msg":"trace[225147718] transaction","detail":"{read_only:false; response_revision:23710; number_of_response:1; }","duration":"103.97903ms","start":"2026-02-24T20:47:34.104638Z","end":"2026-02-24T20:47:34.208617Z","steps":["trace[225147718] 'process raft request' (duration: 103.845536ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:34.208701Z","caller":"traceutil/trace.go:171","msg":"trace[421231782] transaction","detail":"{read_only:false; response_revision:23711; number_of_response:1; }","duration":"102.773568ms","start":"2026-02-24T20:47:34.105917Z","end":"2026-02-24T20:47:34.20869Z","steps":["trace[421231782] 'process raft request' (duration: 102.667685ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:37.855025Z","caller":"traceutil/trace.go:171","msg":"trace[672201493] transaction","detail":"{read_only:false; response_revision:23728; number_of_response:1; }","duration":"184.21365ms","start":"2026-02-24T20:47:37.670781Z","end":"2026-02-24T20:47:37.854995Z","steps":["trace[672201493] 'process raft request' (duration: 118.594633ms)","trace[672201493] 'compare' (duration: 65.482084ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:38.190349Z","caller":"traceutil/trace.go:171","msg":"trace[380701947] transaction","detail":"{read_only:false; response_revision:23730; number_of_response:1; }","duration":"209.432949ms","start":"2026-02-24T20:47:37.980892Z","end":"2026-02-24T20:47:38.190325Z","steps":["trace[380701947] 'process raft request' (duration: 190.820208ms)","trace[380701947] 'compare' (duration: 18.334004ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:38.190476Z","caller":"traceutil/trace.go:171","msg":"trace[770032787] transaction","detail":"{read_only:false; response_revision:23731; number_of_response:1; }","duration":"208.709279ms","start":"2026-02-24T20:47:37.981733Z","end":"2026-02-24T20:47:38.190442Z","steps":["trace[770032787] 'process raft request' (duration: 208.491004ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:38.190569Z","caller":"traceutil/trace.go:171","msg":"trace[1287388383] transaction","detail":"{read_only:false; response_revision:23732; number_of_response:1; }","duration":"208.508495ms","start":"2026-02-24T20:47:37.982054Z","end":"2026-02-24T20:47:38.190563Z","steps":["trace[1287388383] 'process raft request' (duration: 208.379031ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:38.190539Z","caller":"traceutil/trace.go:171","msg":"trace[985336365] linearizableReadLoop","detail":"{readStateIndex:24561; appliedIndex:24558; }","duration":"204.793365ms","start":"2026-02-24T20:47:37.985715Z","end":"2026-02-24T20:47:38.190509Z","steps":["trace[985336365] 'read index received' (duration: 185.965889ms)","trace[985336365] 'applied index is now lower than readState.Index' (duration: 18.826546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:38.190725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.99135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:47:38.190749Z","caller":"traceutil/trace.go:171","msg":"trace[1855586780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23732; }","duration":"205.041161ms","start":"2026-02-24T20:47:37.9857Z","end":"2026-02-24T20:47:38.190741Z","steps":["trace[1855586780] 'agreement among raft nodes before linearized reading' (duration: 204.922038ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:38.433471Z","caller":"traceutil/trace.go:171","msg":"trace[1470038440] transaction","detail":"{read_only:false; response_revision:23735; number_of_response:1; }","duration":"214.3124ms","start":"2026-02-24T20:47:38.219119Z","end":"2026-02-24T20:47:38.433431Z","steps":["trace[1470038440] 'process raft request' (duration: 213.54753ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:38.433622Z","caller":"traceutil/trace.go:171","msg":"trace[1092709672] linearizableReadLoop","detail":"{readStateIndex:24565; appliedIndex:24562; }","duration":"212.521372ms","start":"2026-02-24T20:47:38.22105Z","end":"2026-02-24T20:47:38.433572Z","steps":["trace[1092709672] 'read index received' (duration: 53.939673ms)","trace[1092709672] 'applied index is now lower than readState.Index' (duration: 158.580199ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:38.433677Z","caller":"traceutil/trace.go:171","msg":"trace[1157400938] transaction","detail":"{read_only:false; response_revision:23736; number_of_response:1; }","duration":"212.976314ms","start":"2026-02-24T20:47:38.220683Z","end":"2026-02-24T20:47:38.433659Z","steps":["trace[1157400938] 'process raft request' (duration: 212.660006ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:38.433836Z","caller":"traceutil/trace.go:171","msg":"trace[362399962] transaction","detail":"{read_only:false; response_revision:23737; number_of_response:1; }","duration":"212.142233ms","start":"2026-02-24T20:47:38.221679Z","end":"2026-02-24T20:47:38.433821Z","steps":["trace[362399962] 'process raft request' (duration: 211.831744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:38.433912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.828331ms","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-02-24T20:47:38.434012Z","caller":"traceutil/trace.go:171","msg":"trace[962956539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23737; }","duration":"212.928973ms","start":"2026-02-24T20:47:38.221021Z","end":"2026-02-24T20:47:38.43395Z","steps":["trace[962956539] 'agreement among raft nodes before linearized reading' (duration: 212.706437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:50.423045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.502336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T20:47:50.423118Z","caller":"traceutil/trace.go:171","msg":"trace[1993250495] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:23812; }","duration":"191.64218ms","start":"2026-02-24T20:47:50.231461Z","end":"2026-02-24T20:47:50.423103Z","steps":["trace[1993250495] 'count revisions from in-memory index tree' (duration: 191.394634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:50.423663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.628137ms","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-02-24T20:47:50.423651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.184342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-02-24T20:47:50.423748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.311399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-24T20:47:50.423743Z","caller":"traceutil/trace.go:171","msg":"trace[1964912437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23812; }","duration":"183.286855ms","start":"2026-02-24T20:47:50.240437Z","end":"2026-02-24T20:47:50.423724Z","steps":["trace[1964912437] 'range keys from in-memory index tree' (duration: 182.988607ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.423699Z","caller":"traceutil/trace.go:171","msg":"trace[1860824391] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23812; }","duration":"182.683719ms","start":"2026-02-24T20:47:50.241003Z","end":"2026-02-24T20:47:50.423687Z","steps":["trace[1860824391] 'range keys from in-memory index tree' (duration: 182.493753ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.423911Z","caller":"traceutil/trace.go:171","msg":"trace[1827485845] linearizableReadLoop","detail":"{readStateIndex:24644; appliedIndex:24643; }","duration":"100.959319ms","start":"2026-02-24T20:47:50.32294Z","end":"2026-02-24T20:47:50.423899Z","steps":["trace[1827485845] 'read index received' (duration: 53.180622ms)","trace[1827485845] 'applied index is now lower than readState.Index' (duration: 47.777467ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:50.423767Z","caller":"traceutil/trace.go:171","msg":"trace[375767006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23812; }","duration":"182.34618ms","start":"2026-02-24T20:47:50.241416Z","end":"2026-02-24T20:47:50.423762Z","steps":["trace[375767006] 'range keys from in-memory index tree' (duration: 182.232357ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.424122Z","caller":"traceutil/trace.go:171","msg":"trace[108832523] transaction","detail":"{read_only:false; response_revision:23813; number_of_response:1; }","duration":"180.619883ms","start":"2026-02-24T20:47:50.24344Z","end":"2026-02-24T20:47:50.42406Z","steps":["trace[108832523] 'process raft request' (duration: 132.721393ms)","trace[108832523] 'compare' (duration: 46.478252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:47:50.424269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.332549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:47:50.424331Z","caller":"traceutil/trace.go:171","msg":"trace[483757813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23813; }","duration":"101.421101ms","start":"2026-02-24T20:47:50.322896Z","end":"2026-02-24T20:47:50.424317Z","steps":["trace[483757813] 'agreement among raft nodes before linearized reading' (duration: 101.302648ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.588101Z","caller":"traceutil/trace.go:171","msg":"trace[2081763706] transaction","detail":"{read_only:false; response_revision:23815; number_of_response:1; }","duration":"159.569637ms","start":"2026-02-24T20:47:50.428508Z","end":"2026-02-24T20:47:50.588078Z","steps":["trace[2081763706] 'process raft request' (duration: 159.370591ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.588448Z","caller":"traceutil/trace.go:171","msg":"trace[1670297569] transaction","detail":"{read_only:false; response_revision:23816; number_of_response:1; }","duration":"159.560727ms","start":"2026-02-24T20:47:50.428858Z","end":"2026-02-24T20:47:50.588419Z","steps":["trace[1670297569] 'process raft request' (duration: 159.175306ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.588536Z","caller":"traceutil/trace.go:171","msg":"trace[1056017273] transaction","detail":"{read_only:false; response_revision:23817; number_of_response:1; }","duration":"158.21287ms","start":"2026-02-24T20:47:50.430294Z","end":"2026-02-24T20:47:50.588507Z","steps":["trace[1056017273] 'process raft request' (duration: 158.037835ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.588627Z","caller":"traceutil/trace.go:171","msg":"trace[1166971200] transaction","detail":"{read_only:false; response_revision:23818; number_of_response:1; }","duration":"113.301641ms","start":"2026-02-24T20:47:50.475303Z","end":"2026-02-24T20:47:50.588605Z","steps":["trace[1166971200] 'process raft request' (duration: 113.148836ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.588817Z","caller":"traceutil/trace.go:171","msg":"trace[892794913] transaction","detail":"{read_only:false; response_revision:23819; number_of_response:1; }","duration":"113.409473ms","start":"2026-02-24T20:47:50.475398Z","end":"2026-02-24T20:47:50.588808Z","steps":["trace[892794913] 'process raft request' (duration: 113.158496ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:50.588811Z","caller":"traceutil/trace.go:171","msg":"trace[952385054] linearizableReadLoop","detail":"{readStateIndex:24651; appliedIndex:24644; }","duration":"103.771174ms","start":"2026-02-24T20:47:50.485016Z","end":"2026-02-24T20:47:50.588787Z","steps":["trace[952385054] 'read index received' (duration: 28.536558ms)","trace[952385054] 'applied index is now lower than readState.Index' (duration: 75.233516ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:47:50.588963Z","caller":"traceutil/trace.go:171","msg":"trace[1998988537] transaction","detail":"{read_only:false; response_revision:23820; number_of_response:1; }","duration":"113.494506ms","start":"2026-02-24T20:47:50.475452Z","end":"2026-02-24T20:47:50.588946Z","steps":["trace[1998988537] 'process raft request' (duration: 113.253229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:50.589026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.99178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T20:47:50.589072Z","caller":"traceutil/trace.go:171","msg":"trace[1479379057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23820; }","duration":"104.086023ms","start":"2026-02-24T20:47:50.484973Z","end":"2026-02-24T20:47:50.589059Z","steps":["trace[1479379057] 'agreement among raft nodes before linearized reading' (duration: 103.899498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:47:51.500944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.676795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/manila-ssh-keys\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:47:51.501035Z","caller":"traceutil/trace.go:171","msg":"trace[1563139850] range","detail":"{range_begin:/registry/secrets/openstack/manila-ssh-keys; range_end:; response_count:0; response_revision:23822; }","duration":"135.817518ms","start":"2026-02-24T20:47:51.365197Z","end":"2026-02-24T20:47:51.501015Z","steps":["trace[1563139850] 'range keys from in-memory index tree' (duration: 135.587562ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:52.728616Z","caller":"traceutil/trace.go:171","msg":"trace[1166366992] transaction","detail":"{read_only:false; response_revision:23829; number_of_response:1; }","duration":"133.927296ms","start":"2026-02-24T20:47:52.594669Z","end":"2026-02-24T20:47:52.728596Z","steps":["trace[1166366992] 'process raft request' (duration: 133.458514ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:52.728803Z","caller":"traceutil/trace.go:171","msg":"trace[1658177145] transaction","detail":"{read_only:false; response_revision:23830; number_of_response:1; }","duration":"133.924007ms","start":"2026-02-24T20:47:52.594872Z","end":"2026-02-24T20:47:52.728796Z","steps":["trace[1658177145] 'process raft request' (duration: 133.69489ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:52.729179Z","caller":"traceutil/trace.go:171","msg":"trace[1349854434] transaction","detail":"{read_only:false; response_revision:23832; number_of_response:1; }","duration":"113.105905ms","start":"2026-02-24T20:47:52.616063Z","end":"2026-02-24T20:47:52.729169Z","steps":["trace[1349854434] 'process raft request' (duration: 112.961742ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:52.729179Z","caller":"traceutil/trace.go:171","msg":"trace[1574113266] transaction","detail":"{read_only:false; response_revision:23831; number_of_response:1; }","duration":"113.344341ms","start":"2026-02-24T20:47:52.615816Z","end":"2026-02-24T20:47:52.729161Z","steps":["trace[1574113266] 'process raft request' (duration: 112.956211ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:52.729271Z","caller":"traceutil/trace.go:171","msg":"trace[1480782513] transaction","detail":"{read_only:false; response_revision:23833; number_of_response:1; }","duration":"112.702935ms","start":"2026-02-24T20:47:52.616484Z","end":"2026-02-24T20:47:52.729187Z","steps":["trace[1480782513] 'process raft request' (duration: 112.620743ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:52.729307Z","caller":"traceutil/trace.go:171","msg":"trace[350597382] transaction","detail":"{read_only:false; response_revision:23834; number_of_response:1; }","duration":"112.313134ms","start":"2026-02-24T20:47:52.616983Z","end":"2026-02-24T20:47:52.729296Z","steps":["trace[350597382] 'process raft request' (duration: 112.16661ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:57.564065Z","caller":"traceutil/trace.go:171","msg":"trace[1609423095] transaction","detail":"{read_only:false; response_revision:24013; number_of_response:1; }","duration":"104.640578ms","start":"2026-02-24T20:47:57.459398Z","end":"2026-02-24T20:47:57.564038Z","steps":["trace[1609423095] 'process raft request' (duration: 61.752143ms)","trace[1609423095] 'compare' (duration: 42.289889ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:48:34.072382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21472} {"level":"info","ts":"2026-02-24T20:48:34.203117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21472,"took":"126.479436ms","hash":3648271474,"current-db-size-bytes":48525312,"current-db-size":"48 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-02-24T20:48:34.203214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3648271474,"revision":21472,"compact-revision":18690} {"level":"info","ts":"2026-02-24T20:53:34.078957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24297} {"level":"info","ts":"2026-02-24T20:53:34.21462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24297,"took":"131.545034ms","hash":2034668269,"current-db-size-bytes":48525312,"current-db-size":"48 MB","current-db-size-in-use-bytes":32788480,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-02-24T20:53:34.214676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2034668269,"revision":24297,"compact-revision":21472} {"level":"info","ts":"2026-02-24T20:58:34.083944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26836} {"level":"info","ts":"2026-02-24T20:58:34.206955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26836,"took":"118.624616ms","hash":4208720835,"current-db-size-bytes":48525312,"current-db-size":"48 MB","current-db-size-in-use-bytes":28041216,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-24T20:58:34.207007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4208720835,"revision":26836,"compact-revision":24297} {"level":"info","ts":"2026-02-24T20:58:50.870542Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6f1fcbe50562a63a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-24T20:58:50.874874Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-02-24T20:58:50.874943Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-02-24T21:03:34.090324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28908} {"level":"info","ts":"2026-02-24T21:03:34.199807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28908,"took":"105.159604ms","hash":3660226961,"current-db-size-bytes":48525312,"current-db-size":"48 MB","current-db-size-in-use-bytes":29650944,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-24T21:03:34.199864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3660226961,"revision":28908,"compact-revision":26836} {"level":"info","ts":"2026-02-24T21:08:34.097012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31144} {"level":"info","ts":"2026-02-24T21:08:34.251912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31144,"took":"151.475168ms","hash":2636725128,"current-db-size-bytes":50638848,"current-db-size":"51 MB","current-db-size-in-use-bytes":29319168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-24T21:08:34.251988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2636725128,"revision":31144,"compact-revision":28908} {"level":"info","ts":"2026-02-24T21:12:09.838511Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000008c52.wal"} {"level":"info","ts":"2026-02-24T21:13:34.117649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33263} {"level":"info","ts":"2026-02-24T21:13:34.266812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33263,"took":"146.041973ms","hash":4001647202,"current-db-size-bytes":52187136,"current-db-size":"52 MB","current-db-size-in-use-bytes":29986816,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-24T21:13:34.26686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4001647202,"revision":33263,"compact-revision":31144}