{"level":"warn","ts":"2026-08-24T07:27:50.542689Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T07:27:50.542845Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-08-24T07:27:50.542975Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T07:27:50.543015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-24T07:27:50.543063Z","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-08-24T07:27:50.543763Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-08-24T07:27:50.543888Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-08-24T07:27:50.548039Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.799758ms"} {"level":"info","ts":"2026-08-24T07:27:50.555352Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-08-24T07:27:50.555466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T07:27:50.555495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-08-24T07:27:50.555533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T07:27:50.55556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-08-24T07:27:50.555635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-08-24T07:27:50.561226Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T07:27:50.563842Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T07:27:50.565035Z","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-08-24T07:27:50.567868Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T07:27:50.568059Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T07:27:50.568234Z","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-08-24T07:27:50.569335Z","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-08-24T07:27:50.569394Z","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-08-24T07:27:50.570467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-08-24T07:27:50.570656Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-24T07:27:50.573496Z","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-08-24T07:27:50.573604Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-24T07:27:50.573663Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-24T07:27:50.573914Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T07:27:50.574006Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T07:27:51.056684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T07:27:51.056757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T07:27:51.056819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-08-24T07:27:51.056852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-08-24T07:27:51.056873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-24T07:27:51.0569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-08-24T07:27:51.056926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-24T07:27:51.05785Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T07:27:51.062547Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T07:27:51.062549Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T07:27:51.062821Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T07:27:51.062897Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T07:27:51.062943Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T07:27:51.062981Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T07:27:51.066574Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T07:27:51.069866Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T07:27:51.069959Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T07:27:51.070241Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"warn","ts":"2026-08-24T07:28:09.805354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.787876ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-08-24T07:28:33.991942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.923733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T07:28:33.992077Z","caller":"traceutil/trace.go:171","msg":"trace[244477750] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"214.098976ms","start":"2026-08-24T07:28:33.777958Z","end":"2026-08-24T07:28:33.992057Z","steps":["trace[244477750] 'process raft request' (duration: 43.615671ms)","trace[244477750] 'compare' (duration: 169.76252ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:28:34.188671Z","caller":"traceutil/trace.go:171","msg":"trace[1360586059] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"151.087972ms","start":"2026-08-24T07:28:34.037538Z","end":"2026-08-24T07:28:34.188626Z","steps":["trace[1360586059] 'process raft request' (duration: 105.48253ms)","trace[1360586059] 'compare' (duration: 45.255725ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:28:34.523112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.398421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T07:28:34.52328Z","caller":"traceutil/trace.go:171","msg":"trace[1386869096] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"296.02317ms","start":"2026-08-24T07:28:34.227225Z","end":"2026-08-24T07:28:34.523248Z","steps":["trace[1386869096] 'process raft request' (duration: 84.1617ms)","trace[1386869096] 'compare' (duration: 211.149456ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:28:34.735888Z","caller":"traceutil/trace.go:171","msg":"trace[1802409600] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"209.895759ms","start":"2026-08-24T07:28:34.525966Z","end":"2026-08-24T07:28:34.735862Z","steps":["trace[1802409600] 'read index received' (duration: 195.45566ms)","trace[1802409600] 'applied index is now lower than readState.Index' (duration: 14.438799ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:28:34.736006Z","caller":"traceutil/trace.go:171","msg":"trace[820661078] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"333.057515ms","start":"2026-08-24T07:28:34.402909Z","end":"2026-08-24T07:28:34.735967Z","steps":["trace[820661078] 'process raft request' (duration: 318.987544ms)","trace[820661078] 'compare' (duration: 13.413188ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:28:34.736102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.100504ms","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-08-24T07:28:34.736208Z","caller":"traceutil/trace.go:171","msg":"trace[2134522402] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:591; }","duration":"210.273576ms","start":"2026-08-24T07:28:34.52591Z","end":"2026-08-24T07:28:34.736184Z","steps":["trace[2134522402] 'agreement among raft nodes before linearized reading' (duration: 210.053582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:28:34.736202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:28:34.402877Z","time spent":"333.203768ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T07:28:35.363377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:28:35.027197Z","time spent":"336.17077ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-24T07:28:35.363423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.059487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T07:28:35.363525Z","caller":"traceutil/trace.go:171","msg":"trace[1850288235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"232.20618ms","start":"2026-08-24T07:28:35.13129Z","end":"2026-08-24T07:28:35.363496Z","steps":["trace[1850288235] 'range keys from in-memory index tree' (duration: 231.886564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:28:35.363529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.459108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T07:28:35.363598Z","caller":"traceutil/trace.go:171","msg":"trace[1307885101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"175.56068ms","start":"2026-08-24T07:28:35.18802Z","end":"2026-08-24T07:28:35.363581Z","steps":["trace[1307885101] 'range keys from in-memory index tree' (duration: 175.330955ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:28:35.363976Z","caller":"traceutil/trace.go:171","msg":"trace[712155164] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"101.312485ms","start":"2026-08-24T07:28:35.262646Z","end":"2026-08-24T07:28:35.363958Z","steps":["trace[712155164] 'process raft request' (duration: 99.736282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:28:50.723298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.981645ms","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-08-24T07:28:50.723454Z","caller":"traceutil/trace.go:171","msg":"trace[1229255867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"161.211358ms","start":"2026-08-24T07:28:50.562216Z","end":"2026-08-24T07:28:50.723428Z","steps":["trace[1229255867] 'range keys from in-memory index tree' (duration: 160.716199ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:29:23.045841Z","caller":"traceutil/trace.go:171","msg":"trace[742570616] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"110.705993ms","start":"2026-08-24T07:29:22.935092Z","end":"2026-08-24T07:29:23.045798Z","steps":["trace[742570616] 'process raft request' (duration: 110.492548ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:30:10.946772Z","caller":"traceutil/trace.go:171","msg":"trace[1131386640] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"128.208365ms","start":"2026-08-24T07:30:10.818536Z","end":"2026-08-24T07:30:10.946745Z","steps":["trace[1131386640] 'read index received' (duration: 127.731366ms)","trace[1131386640] 'applied index is now lower than readState.Index' (duration: 475.529µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:30:10.946885Z","caller":"traceutil/trace.go:171","msg":"trace[1654025896] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"145.534365ms","start":"2026-08-24T07:30:10.801319Z","end":"2026-08-24T07:30:10.946853Z","steps":["trace[1654025896] 'process raft request' (duration: 145.051295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:30:10.947056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.503542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:30:10.947207Z","caller":"traceutil/trace.go:171","msg":"trace[299646084] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1844; }","duration":"128.747257ms","start":"2026-08-24T07:30:10.818425Z","end":"2026-08-24T07:30:10.947172Z","steps":["trace[299646084] 'agreement among raft nodes before linearized reading' (duration: 128.492692ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:30:23.188555Z","caller":"traceutil/trace.go:171","msg":"trace[166148584] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"199.988722ms","start":"2026-08-24T07:30:22.988527Z","end":"2026-08-24T07:30:23.188516Z","steps":["trace[166148584] 'process raft request' (duration: 199.822228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:30:49.765619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.80473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T07:30:49.765807Z","caller":"traceutil/trace.go:171","msg":"trace[1968371642] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"183.575751ms","start":"2026-08-24T07:30:49.582214Z","end":"2026-08-24T07:30:49.765789Z","steps":["trace[1968371642] 'process raft request' (duration: 183.51443ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:30:49.765961Z","caller":"traceutil/trace.go:171","msg":"trace[1116070332] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"184.617973ms","start":"2026-08-24T07:30:49.581313Z","end":"2026-08-24T07:30:49.765931Z","steps":["trace[1116070332] 'process raft request' (duration: 52.409945ms)","trace[1116070332] 'compare' (duration: 131.677197ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:30:49.777012Z","caller":"traceutil/trace.go:171","msg":"trace[682403493] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"141.186414ms","start":"2026-08-24T07:30:49.635791Z","end":"2026-08-24T07:30:49.776977Z","steps":["trace[682403493] 'process raft request' (duration: 141.01553ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:31:01.523129Z","caller":"traceutil/trace.go:171","msg":"trace[589594705] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"172.234996ms","start":"2026-08-24T07:31:01.350857Z","end":"2026-08-24T07:31:01.523092Z","steps":["trace[589594705] 'process raft request' (duration: 172.002391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:31:01.52325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.648116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:31:01.523117Z","caller":"traceutil/trace.go:171","msg":"trace[1795081875] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"130.41505ms","start":"2026-08-24T07:31:01.392584Z","end":"2026-08-24T07:31:01.522999Z","steps":["trace[1795081875] 'read index received' (duration: 130.271067ms)","trace[1795081875] 'applied index is now lower than readState.Index' (duration: 142.833µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:31:01.523299Z","caller":"traceutil/trace.go:171","msg":"trace[2069668838] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2166; }","duration":"130.760617ms","start":"2026-08-24T07:31:01.392525Z","end":"2026-08-24T07:31:01.523286Z","steps":["trace[2069668838] 'agreement among raft nodes before linearized reading' (duration: 130.660735ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:31:52.553423Z","caller":"traceutil/trace.go:171","msg":"trace[967317835] linearizableReadLoop","detail":"{readStateIndex:2716; appliedIndex:2715; }","duration":"172.471923ms","start":"2026-08-24T07:31:52.380919Z","end":"2026-08-24T07:31:52.553391Z","steps":["trace[967317835] 'read index received' (duration: 172.196808ms)","trace[967317835] 'applied index is now lower than readState.Index' (duration: 273.475µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:31:52.553489Z","caller":"traceutil/trace.go:171","msg":"trace[1626397138] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"173.33931ms","start":"2026-08-24T07:31:52.380105Z","end":"2026-08-24T07:31:52.553444Z","steps":["trace[1626397138] 'process raft request' (duration: 172.967603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:31:52.553669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.714799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T07:31:52.553723Z","caller":"traceutil/trace.go:171","msg":"trace[1337656235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2616; }","duration":"172.809501ms","start":"2026-08-24T07:31:52.380896Z","end":"2026-08-24T07:31:52.553706Z","steps":["trace[1337656235] 'agreement among raft nodes before linearized reading' (duration: 172.627496ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:31:52.562921Z","caller":"traceutil/trace.go:171","msg":"trace[69726392] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"106.648607ms","start":"2026-08-24T07:31:52.45626Z","end":"2026-08-24T07:31:52.562909Z","steps":["trace[69726392] 'process raft request' (duration: 106.465393ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:32:26.794408Z","caller":"traceutil/trace.go:171","msg":"trace[1993346194] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"104.565574ms","start":"2026-08-24T07:32:26.689806Z","end":"2026-08-24T07:32:26.794371Z","steps":["trace[1993346194] 'process raft request' (duration: 33.554811ms)","trace[1993346194] 'compare' (duration: 70.815719ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:36:22.686406Z","caller":"traceutil/trace.go:171","msg":"trace[2048530969] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5174; }","duration":"248.061472ms","start":"2026-08-24T07:36:22.438316Z","end":"2026-08-24T07:36:22.686377Z","steps":["trace[2048530969] 'read index received' (duration: 247.879298ms)","trace[2048530969] 'applied index is now lower than readState.Index' (duration: 181.044µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:36:22.686561Z","caller":"traceutil/trace.go:171","msg":"trace[1960667209] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"248.543482ms","start":"2026-08-24T07:36:22.437998Z","end":"2026-08-24T07:36:22.686542Z","steps":["trace[1960667209] 'process raft request' (duration: 248.229026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:22.68689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.548992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-2cd4r.18cead9a50c2d9a2\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-08-24T07:36:22.686947Z","caller":"traceutil/trace.go:171","msg":"trace[951785751] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-2cd4r.18cead9a50c2d9a2; range_end:; response_count:1; response_revision:4986; }","duration":"248.633635ms","start":"2026-08-24T07:36:22.438296Z","end":"2026-08-24T07:36:22.68693Z","steps":["trace[951785751] 'agreement among raft nodes before linearized reading' (duration: 248.448741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:22.687051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.483686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dfk2c\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-24T07:36:22.68711Z","caller":"traceutil/trace.go:171","msg":"trace[1594707265] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dfk2c; range_end:; response_count:1; response_revision:4986; }","duration":"161.581708ms","start":"2026-08-24T07:36:22.525512Z","end":"2026-08-24T07:36:22.687094Z","steps":["trace[1594707265] 'agreement among raft nodes before linearized reading' (duration: 161.383514ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:36.145523Z","caller":"traceutil/trace.go:171","msg":"trace[910748701] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"120.03707ms","start":"2026-08-24T07:36:36.025453Z","end":"2026-08-24T07:36:36.14549Z","steps":["trace[910748701] 'process raft request' (duration: 54.552834ms)","trace[910748701] 'compare' (duration: 64.938915ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:36:36.750669Z","caller":"traceutil/trace.go:171","msg":"trace[1134749405] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"157.536085ms","start":"2026-08-24T07:36:36.593111Z","end":"2026-08-24T07:36:36.750647Z","steps":["trace[1134749405] 'process raft request' (duration: 157.371852ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:43.881548Z","caller":"traceutil/trace.go:171","msg":"trace[482713466] linearizableReadLoop","detail":"{readStateIndex:5632; appliedIndex:5631; }","duration":"136.046016ms","start":"2026-08-24T07:36:43.745478Z","end":"2026-08-24T07:36:43.881524Z","steps":["trace[482713466] 'read index received' (duration: 135.951614ms)","trace[482713466] 'applied index is now lower than readState.Index' (duration: 93.332µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:36:43.881599Z","caller":"traceutil/trace.go:171","msg":"trace[1007237622] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"152.871489ms","start":"2026-08-24T07:36:43.728698Z","end":"2026-08-24T07:36:43.88157Z","steps":["trace[1007237622] 'process raft request' (duration: 152.664165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:43.881807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.298662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-24T07:36:43.881903Z","caller":"traceutil/trace.go:171","msg":"trace[186234638] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5430; }","duration":"136.464376ms","start":"2026-08-24T07:36:43.745421Z","end":"2026-08-24T07:36:43.881885Z","steps":["trace[186234638] 'agreement among raft nodes before linearized reading' (duration: 136.183349ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:44.097503Z","caller":"traceutil/trace.go:171","msg":"trace[1002289160] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"208.760071ms","start":"2026-08-24T07:36:43.888704Z","end":"2026-08-24T07:36:44.097464Z","steps":["trace[1002289160] 'process raft request' (duration: 121.593972ms)","trace[1002289160] 'compare' (duration: 86.945944ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:36:44.097871Z","caller":"traceutil/trace.go:171","msg":"trace[415836841] linearizableReadLoop","detail":"{readStateIndex:5639; appliedIndex:5633; }","duration":"185.607959ms","start":"2026-08-24T07:36:43.912239Z","end":"2026-08-24T07:36:44.097847Z","steps":["trace[415836841] 'read index received' (duration: 98.038531ms)","trace[415836841] 'applied index is now lower than readState.Index' (duration: 87.568368ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:36:44.09792Z","caller":"traceutil/trace.go:171","msg":"trace[169714329] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"208.932345ms","start":"2026-08-24T07:36:43.88896Z","end":"2026-08-24T07:36:44.097893Z","steps":["trace[169714329] 'process raft request' (duration: 208.720211ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:44.098023Z","caller":"traceutil/trace.go:171","msg":"trace[747639179] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"206.924733ms","start":"2026-08-24T07:36:43.89107Z","end":"2026-08-24T07:36:44.097995Z","steps":["trace[747639179] 'process raft request' (duration: 206.724309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:44.098096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.054027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c43e652b-7b3d-4a7d-9e58-d543828d484c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-24T07:36:44.098167Z","caller":"traceutil/trace.go:171","msg":"trace[1498988430] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c43e652b-7b3d-4a7d-9e58-d543828d484c; range_end:; response_count:1; response_revision:5437; }","duration":"210.181329ms","start":"2026-08-24T07:36:43.887975Z","end":"2026-08-24T07:36:44.098156Z","steps":["trace[1498988430] 'agreement among raft nodes before linearized reading' (duration: 210.003965ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:44.098212Z","caller":"traceutil/trace.go:171","msg":"trace[1986193979] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"209.419224ms","start":"2026-08-24T07:36:43.888775Z","end":"2026-08-24T07:36:44.098194Z","steps":["trace[1986193979] 'process raft request' (duration: 208.616368ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:44.098252Z","caller":"traceutil/trace.go:171","msg":"trace[1083292011] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"208.978635ms","start":"2026-08-24T07:36:43.889244Z","end":"2026-08-24T07:36:44.098223Z","steps":["trace[1083292011] 'process raft request' (duration: 208.508206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:44.098258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.850842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:36:44.098347Z","caller":"traceutil/trace.go:171","msg":"trace[497229982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5437; }","duration":"183.997005ms","start":"2026-08-24T07:36:43.914328Z","end":"2026-08-24T07:36:44.098325Z","steps":["trace[497229982] 'agreement among raft nodes before linearized reading' (duration: 183.853423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:44.098517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.883164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6100bc9a-9863-45fd-ba3c-8876ed840e5e\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-08-24T07:36:44.098574Z","caller":"traceutil/trace.go:171","msg":"trace[113222326] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"209.625438ms","start":"2026-08-24T07:36:43.888933Z","end":"2026-08-24T07:36:44.098559Z","steps":["trace[113222326] 'process raft request' (duration: 208.685409ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:44.098625Z","caller":"traceutil/trace.go:171","msg":"trace[1478861090] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6100bc9a-9863-45fd-ba3c-8876ed840e5e; range_end:; response_count:1; response_revision:5437; }","duration":"211.010077ms","start":"2026-08-24T07:36:43.887573Z","end":"2026-08-24T07:36:44.098583Z","steps":["trace[1478861090] 'agreement among raft nodes before linearized reading' (duration: 210.756582ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:44.331063Z","caller":"traceutil/trace.go:171","msg":"trace[400093680] linearizableReadLoop","detail":"{readStateIndex:5641; appliedIndex:5640; }","duration":"133.861382ms","start":"2026-08-24T07:36:44.197174Z","end":"2026-08-24T07:36:44.331036Z","steps":["trace[400093680] 'read index received' (duration: 77.294838ms)","trace[400093680] 'applied index is now lower than readState.Index' (duration: 56.565494ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:36:44.331141Z","caller":"traceutil/trace.go:171","msg":"trace[103787977] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"136.610469ms","start":"2026-08-24T07:36:44.194499Z","end":"2026-08-24T07:36:44.331109Z","steps":["trace[103787977] 'process raft request' (duration: 79.983833ms)","trace[103787977] 'compare' (duration: 56.398751ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:36:44.331323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.137168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T07:36:44.33136Z","caller":"traceutil/trace.go:171","msg":"trace[144016567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5439; }","duration":"134.216329ms","start":"2026-08-24T07:36:44.197133Z","end":"2026-08-24T07:36:44.331349Z","steps":["trace[144016567] 'agreement among raft nodes before linearized reading' (duration: 134.039906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:44.331502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.716557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T07:36:44.331594Z","caller":"traceutil/trace.go:171","msg":"trace[790681174] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:5439; }","duration":"129.84881ms","start":"2026-08-24T07:36:44.201723Z","end":"2026-08-24T07:36:44.331572Z","steps":["trace[790681174] 'agreement among raft nodes before linearized reading' (duration: 129.597485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:44.331591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.835107ms","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-08-24T07:36:44.331658Z","caller":"traceutil/trace.go:171","msg":"trace[167282010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5439; }","duration":"122.95485ms","start":"2026-08-24T07:36:44.208687Z","end":"2026-08-24T07:36:44.331642Z","steps":["trace[167282010] 'agreement among raft nodes before linearized reading' (duration: 122.755546ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:37:51.093809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-08-24T07:37:51.162224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"66.821101ms","hash":908975611,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":34033664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-24T07:37:51.162307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":908975611,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-08-24T07:38:16.183123Z","caller":"traceutil/trace.go:171","msg":"trace[953790083] transaction","detail":"{read_only:false; response_revision:6223; number_of_response:1; }","duration":"114.853499ms","start":"2026-08-24T07:38:16.068231Z","end":"2026-08-24T07:38:16.183084Z","steps":["trace[953790083] 'process raft request' (duration: 114.670635ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:51.10031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6038} {"level":"info","ts":"2026-08-24T07:42:51.194253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6038,"took":"91.722662ms","hash":1668202698,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":21397504,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-08-24T07:42:51.194323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1668202698,"revision":6038,"compact-revision":2960} {"level":"info","ts":"2026-08-24T07:43:02.389239Z","caller":"traceutil/trace.go:171","msg":"trace[619164329] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"212.18215ms","start":"2026-08-24T07:43:02.177023Z","end":"2026-08-24T07:43:02.389205Z","steps":["trace[619164329] 'process raft request' (duration: 211.951146ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:02.470631Z","caller":"traceutil/trace.go:171","msg":"trace[861157665] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"290.292445ms","start":"2026-08-24T07:43:02.180309Z","end":"2026-08-24T07:43:02.470602Z","steps":["trace[861157665] 'process raft request' (duration: 290.117781ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:02.470814Z","caller":"traceutil/trace.go:171","msg":"trace[2041192700] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"132.070945ms","start":"2026-08-24T07:43:02.338707Z","end":"2026-08-24T07:43:02.470778Z","steps":["trace[2041192700] 'process raft request' (duration: 131.847581ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:14.754595Z","caller":"traceutil/trace.go:171","msg":"trace[26402068] transaction","detail":"{read_only:false; response_revision:8384; number_of_response:1; }","duration":"108.862242ms","start":"2026-08-24T07:43:14.645707Z","end":"2026-08-24T07:43:14.754569Z","steps":["trace[26402068] 'process raft request' (duration: 108.636617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:43:15.627091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.578542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-24T07:43:15.62718Z","caller":"traceutil/trace.go:171","msg":"trace[367875782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8386; }","duration":"149.696945ms","start":"2026-08-24T07:43:15.477462Z","end":"2026-08-24T07:43:15.627159Z","steps":["trace[367875782] 'range keys from in-memory index tree' (duration: 149.500371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:43:15.627546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.081386ms","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-08-24T07:43:15.62762Z","caller":"traceutil/trace.go:171","msg":"trace[2135889945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8386; }","duration":"183.191439ms","start":"2026-08-24T07:43:15.444408Z","end":"2026-08-24T07:43:15.627599Z","steps":["trace[2135889945] 'range keys from in-memory index tree' (duration: 182.957713ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:16.801104Z","caller":"traceutil/trace.go:171","msg":"trace[491463836] transaction","detail":"{read_only:false; response_revision:8394; number_of_response:1; }","duration":"113.333202ms","start":"2026-08-24T07:43:16.687728Z","end":"2026-08-24T07:43:16.801061Z","steps":["trace[491463836] 'process raft request' (duration: 16.733281ms)","trace[491463836] 'compare' (duration: 96.376487ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:43:43.638204Z","caller":"traceutil/trace.go:171","msg":"trace[1043351425] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"185.537187ms","start":"2026-08-24T07:43:43.452645Z","end":"2026-08-24T07:43:43.638182Z","steps":["trace[1043351425] 'process raft request' (duration: 185.368303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:43:43.857922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.041052ms","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-08-24T07:43:43.85827Z","caller":"traceutil/trace.go:171","msg":"trace[960611325] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8590; }","duration":"100.279316ms","start":"2026-08-24T07:43:43.757826Z","end":"2026-08-24T07:43:43.858105Z","steps":["trace[960611325] 'range keys from in-memory index tree' (duration: 99.94364ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:47.045116Z","caller":"traceutil/trace.go:171","msg":"trace[2028386070] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"141.385946ms","start":"2026-08-24T07:43:46.903706Z","end":"2026-08-24T07:43:47.045091Z","steps":["trace[2028386070] 'process raft request' (duration: 141.230003ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:47.142029Z","caller":"traceutil/trace.go:171","msg":"trace[1678482184] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"237.509217ms","start":"2026-08-24T07:43:46.904492Z","end":"2026-08-24T07:43:47.142001Z","steps":["trace[1678482184] 'process raft request' (duration: 228.648196ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:54.918879Z","caller":"traceutil/trace.go:171","msg":"trace[619122322] linearizableReadLoop","detail":"{readStateIndex:8958; appliedIndex:8957; }","duration":"100.734256ms","start":"2026-08-24T07:43:54.81812Z","end":"2026-08-24T07:43:54.918854Z","steps":["trace[619122322] 'read index received' (duration: 89.806572ms)","trace[619122322] 'applied index is now lower than readState.Index' (duration: 10.926583ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:43:54.918999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.874529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:43:54.919038Z","caller":"traceutil/trace.go:171","msg":"trace[375189828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8649; }","duration":"100.96862ms","start":"2026-08-24T07:43:54.818059Z","end":"2026-08-24T07:43:54.919028Z","steps":["trace[375189828] 'agreement among raft nodes before linearized reading' (duration: 100.886838ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:54.919022Z","caller":"traceutil/trace.go:171","msg":"trace[1858735457] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"100.926299ms","start":"2026-08-24T07:43:54.818059Z","end":"2026-08-24T07:43:54.918985Z","steps":["trace[1858735457] 'process raft request' (duration: 89.945445ms)","trace[1858735457] 'compare' (duration: 10.739869ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:43:55.823087Z","caller":"traceutil/trace.go:171","msg":"trace[395496376] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"114.792663ms","start":"2026-08-24T07:43:55.708251Z","end":"2026-08-24T07:43:55.823043Z","steps":["trace[395496376] 'process raft request' (duration: 96.251394ms)","trace[395496376] 'compare' (duration: 18.420416ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:44:02.728044Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000236a.wal"} {"level":"info","ts":"2026-08-24T07:45:16.590337Z","caller":"traceutil/trace.go:171","msg":"trace[1372815922] transaction","detail":"{read_only:false; response_revision:9466; number_of_response:1; }","duration":"158.488684ms","start":"2026-08-24T07:45:16.43182Z","end":"2026-08-24T07:45:16.590309Z","steps":["trace[1372815922] 'process raft request' (duration: 116.281363ms)","trace[1372815922] 'compare' (duration: 41.982016ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:45:43.462995Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T07:45:43.468874Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-24T07:45:43.468986Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-24T07:47:51.107502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8121} {"level":"info","ts":"2026-08-24T07:47:51.168318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8121,"took":"58.541891ms","hash":2258914814,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":28381184,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-24T07:47:51.168389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2258914814,"revision":8121,"compact-revision":6038} {"level":"info","ts":"2026-08-24T07:49:32.036498Z","caller":"traceutil/trace.go:171","msg":"trace[1952073118] transaction","detail":"{read_only:false; response_revision:11113; number_of_response:1; }","duration":"127.288611ms","start":"2026-08-24T07:49:31.909149Z","end":"2026-08-24T07:49:32.036438Z","steps":["trace[1952073118] 'process raft request' (duration: 84.00634ms)","trace[1952073118] 'compare' (duration: 43.082657ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:50:54.869535Z","caller":"traceutil/trace.go:171","msg":"trace[1521184956] transaction","detail":"{read_only:false; response_revision:11892; number_of_response:1; }","duration":"242.619157ms","start":"2026-08-24T07:50:54.626891Z","end":"2026-08-24T07:50:54.86951Z","steps":["trace[1521184956] 'process raft request' (duration: 242.482584ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:50:54.870201Z","caller":"traceutil/trace.go:171","msg":"trace[1619898477] linearizableReadLoop","detail":"{readStateIndex:12304; appliedIndex:12304; }","duration":"240.2869ms","start":"2026-08-24T07:50:54.629881Z","end":"2026-08-24T07:50:54.870168Z","steps":["trace[1619898477] 'read index received' (duration: 240.2741ms)","trace[1619898477] 'applied index is now lower than readState.Index' (duration: 10.84µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:50:54.870397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.485104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T07:50:54.870444Z","caller":"traceutil/trace.go:171","msg":"trace[152934515] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11892; }","duration":"240.603456ms","start":"2026-08-24T07:50:54.629829Z","end":"2026-08-24T07:50:54.870432Z","steps":["trace[152934515] 'agreement among raft nodes before linearized reading' (duration: 240.484274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:50:54.923766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.02355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:50:54.923856Z","caller":"traceutil/trace.go:171","msg":"trace[596144883] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11893; }","duration":"227.159603ms","start":"2026-08-24T07:50:54.696674Z","end":"2026-08-24T07:50:54.923834Z","steps":["trace[596144883] 'agreement among raft nodes before linearized reading' (duration: 227.00993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:50:54.923922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.955489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T07:50:54.923946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.980609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:50:54.924018Z","caller":"traceutil/trace.go:171","msg":"trace[2039293852] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11893; }","duration":"227.084371ms","start":"2026-08-24T07:50:54.696914Z","end":"2026-08-24T07:50:54.923998Z","steps":["trace[2039293852] 'agreement among raft nodes before linearized reading' (duration: 226.904278ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:50:54.924023Z","caller":"traceutil/trace.go:171","msg":"trace[1449555243] transaction","detail":"{read_only:false; response_revision:11893; number_of_response:1; }","duration":"253.605881ms","start":"2026-08-24T07:50:54.670387Z","end":"2026-08-24T07:50:54.923993Z","steps":["trace[1449555243] 'process raft request' (duration: 253.146892ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:50:54.924027Z","caller":"traceutil/trace.go:171","msg":"trace[669319343] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11893; }","duration":"227.087531ms","start":"2026-08-24T07:50:54.696925Z","end":"2026-08-24T07:50:54.924012Z","steps":["trace[669319343] 'agreement among raft nodes before linearized reading' (duration: 226.958709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:50:54.923944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.029058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-08-24T07:50:54.924185Z","caller":"traceutil/trace.go:171","msg":"trace[703276578] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11893; }","duration":"224.331005ms","start":"2026-08-24T07:50:54.699843Z","end":"2026-08-24T07:50:54.924174Z","steps":["trace[703276578] 'agreement among raft nodes before linearized reading' (duration: 223.905936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:50:54.923938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.996569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:50:54.924255Z","caller":"traceutil/trace.go:171","msg":"trace[71520335] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11893; }","duration":"227.350786ms","start":"2026-08-24T07:50:54.69689Z","end":"2026-08-24T07:50:54.924241Z","steps":["trace[71520335] 'agreement among raft nodes before linearized reading' (duration: 226.969838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:50:55.95756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.590001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:50:55.957682Z","caller":"traceutil/trace.go:171","msg":"trace[462896973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11900; }","duration":"138.748074ms","start":"2026-08-24T07:50:55.818904Z","end":"2026-08-24T07:50:55.957652Z","steps":["trace[462896973] 'range keys from in-memory index tree' (duration: 138.51745ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:52:51.114216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10478} {"level":"info","ts":"2026-08-24T07:52:51.19204Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10478,"took":"75.633939ms","hash":496964412,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":26193920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-24T07:52:51.192109Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":496964412,"revision":10478,"compact-revision":8121} {"level":"info","ts":"2026-08-24T07:57:26.040163Z","caller":"traceutil/trace.go:171","msg":"trace[1392876722] transaction","detail":"{read_only:false; response_revision:14497; number_of_response:1; }","duration":"145.45908ms","start":"2026-08-24T07:57:25.89468Z","end":"2026-08-24T07:57:26.040139Z","steps":["trace[1392876722] 'process raft request' (duration: 145.273396ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:26.209728Z","caller":"traceutil/trace.go:171","msg":"trace[790536848] linearizableReadLoop","detail":"{readStateIndex:15000; appliedIndex:14998; }","duration":"181.347581ms","start":"2026-08-24T07:57:26.028356Z","end":"2026-08-24T07:57:26.209703Z","steps":["trace[790536848] 'read index received' (duration: 11.611617ms)","trace[790536848] 'applied index is now lower than readState.Index' (duration: 169.735004ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:57:26.209873Z","caller":"traceutil/trace.go:171","msg":"trace[1896535144] transaction","detail":"{read_only:false; response_revision:14498; number_of_response:1; }","duration":"313.379247ms","start":"2026-08-24T07:57:25.896476Z","end":"2026-08-24T07:57:26.209855Z","steps":["trace[1896535144] 'process raft request' (duration: 304.6451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:57:26.209961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.586556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-24T07:57:26.209977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:57:25.896455Z","time spent":"313.468549ms","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T07:57:26.210012Z","caller":"traceutil/trace.go:171","msg":"trace[65669563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14498; }","duration":"181.673867ms","start":"2026-08-24T07:57:26.028321Z","end":"2026-08-24T07:57:26.209995Z","steps":["trace[65669563] 'agreement among raft nodes before linearized reading' (duration: 181.508404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:57:42.658761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.795844ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:57:42.658932Z","caller":"traceutil/trace.go:171","msg":"trace[1305496421] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14596; }","duration":"193.993269ms","start":"2026-08-24T07:57:42.464917Z","end":"2026-08-24T07:57:42.65891Z","steps":["trace[1305496421] 'range keys from in-memory index tree' (duration: 193.774003ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:42.65903Z","caller":"traceutil/trace.go:171","msg":"trace[2106509082] transaction","detail":"{read_only:false; response_revision:14597; number_of_response:1; }","duration":"294.488464ms","start":"2026-08-24T07:57:42.364504Z","end":"2026-08-24T07:57:42.658992Z","steps":["trace[2106509082] 'process raft request' (duration: 261.516453ms)","trace[2106509082] 'compare' (duration: 32.594993ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:57:42.670851Z","caller":"traceutil/trace.go:171","msg":"trace[962124002] transaction","detail":"{read_only:false; response_revision:14598; number_of_response:1; }","duration":"262.231117ms","start":"2026-08-24T07:57:42.408589Z","end":"2026-08-24T07:57:42.67082Z","steps":["trace[962124002] 'process raft request' (duration: 262.033423ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:42.671132Z","caller":"traceutil/trace.go:171","msg":"trace[1911287292] transaction","detail":"{read_only:false; response_revision:14599; number_of_response:1; }","duration":"258.260706ms","start":"2026-08-24T07:57:42.412752Z","end":"2026-08-24T07:57:42.671012Z","steps":["trace[1911287292] 'process raft request' (duration: 258.022801ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:51.12338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12670} {"level":"info","ts":"2026-08-24T07:57:51.195825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12670,"took":"70.009195ms","hash":2507704127,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":27385856,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-24T07:57:51.195888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2507704127,"revision":12670,"compact-revision":10478} {"level":"info","ts":"2026-08-24T07:58:04.159316Z","caller":"traceutil/trace.go:171","msg":"trace[168652777] linearizableReadLoop","detail":"{readStateIndex:15428; appliedIndex:15427; }","duration":"340.975558ms","start":"2026-08-24T07:58:03.818318Z","end":"2026-08-24T07:58:04.159294Z","steps":["trace[168652777] 'read index received' (duration: 340.787594ms)","trace[168652777] 'applied index is now lower than readState.Index' (duration: 187.074µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:58:04.159443Z","caller":"traceutil/trace.go:171","msg":"trace[1783818324] transaction","detail":"{read_only:false; response_revision:14907; number_of_response:1; }","duration":"378.136875ms","start":"2026-08-24T07:58:03.781266Z","end":"2026-08-24T07:58:04.159402Z","steps":["trace[1783818324] 'process raft request' (duration: 377.90454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:58:04.159468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.177928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:58:04.159571Z","caller":"traceutil/trace.go:171","msg":"trace[837333101] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:14907; }","duration":"295.375022ms","start":"2026-08-24T07:58:03.864186Z","end":"2026-08-24T07:58:04.159561Z","steps":["trace[837333101] 'agreement among raft nodes before linearized reading' (duration: 295.229359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:58:04.159629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:58:03.781243Z","time spent":"378.284759ms","remote":"127.0.0.1:46888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T07:58:04.159631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.527429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dfk2c\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-24T07:58:04.159708Z","caller":"traceutil/trace.go:171","msg":"trace[1673752061] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dfk2c; range_end:; response_count:1; response_revision:14907; }","duration":"163.657801ms","start":"2026-08-24T07:58:03.996027Z","end":"2026-08-24T07:58:04.159685Z","steps":["trace[1673752061] 'agreement among raft nodes before linearized reading' (duration: 163.429946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:58:04.159612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.927233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:58:04.159825Z","caller":"traceutil/trace.go:171","msg":"trace[949952593] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:14907; }","duration":"181.191027ms","start":"2026-08-24T07:58:03.978606Z","end":"2026-08-24T07:58:04.159797Z","steps":["trace[949952593] 'agreement among raft nodes before linearized reading' (duration: 180.911682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:58:04.159709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.401287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:58:04.160283Z","caller":"traceutil/trace.go:171","msg":"trace[481785595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14907; }","duration":"342.00774ms","start":"2026-08-24T07:58:03.818261Z","end":"2026-08-24T07:58:04.160269Z","steps":["trace[481785595] 'agreement among raft nodes before linearized reading' (duration: 341.142582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:58:04.160324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:58:03.818242Z","time spent":"342.069542ms","remote":"127.0.0.1:46694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T07:58:55.047322Z","caller":"traceutil/trace.go:171","msg":"trace[797095277] transaction","detail":"{read_only:false; response_revision:15531; number_of_response:1; }","duration":"129.509475ms","start":"2026-08-24T07:58:54.917779Z","end":"2026-08-24T07:58:55.047289Z","steps":["trace[797095277] 'process raft request' (duration: 127.860181ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:58:55.0474Z","caller":"traceutil/trace.go:171","msg":"trace[503444260] linearizableReadLoop","detail":"{readStateIndex:16068; appliedIndex:16067; }","duration":"103.382164ms","start":"2026-08-24T07:58:54.943974Z","end":"2026-08-24T07:58:55.047356Z","steps":["trace[503444260] 'read index received' (duration: 101.681009ms)","trace[503444260] 'applied index is now lower than readState.Index' (duration: 1.698585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:58:55.047577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.579298ms","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-08-24T07:58:55.047617Z","caller":"traceutil/trace.go:171","msg":"trace[1695949769] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15531; }","duration":"103.68891ms","start":"2026-08-24T07:58:54.943918Z","end":"2026-08-24T07:58:55.047607Z","steps":["trace[1695949769] 'agreement among raft nodes before linearized reading' (duration: 103.588018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:58:55.048468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.100828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-08-24T07:58:55.048475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.003496ms","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-08-24T07:58:55.048505Z","caller":"traceutil/trace.go:171","msg":"trace[1109040830] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15532; }","duration":"103.155348ms","start":"2026-08-24T07:58:54.945339Z","end":"2026-08-24T07:58:55.048495Z","steps":["trace[1109040830] 'agreement among raft nodes before linearized reading' (duration: 103.018796ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:58:55.04855Z","caller":"traceutil/trace.go:171","msg":"trace[942484064] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15532; }","duration":"104.090068ms","start":"2026-08-24T07:58:54.944439Z","end":"2026-08-24T07:58:55.048529Z","steps":["trace[942484064] 'agreement among raft nodes before linearized reading' (duration: 103.939705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:58:55.048628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.966535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-08-24T07:58:55.048773Z","caller":"traceutil/trace.go:171","msg":"trace[1337313611] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15532; }","duration":"104.125699ms","start":"2026-08-24T07:58:54.944609Z","end":"2026-08-24T07:58:55.048734Z","steps":["trace[1337313611] 'agreement among raft nodes before linearized reading' (duration: 103.757322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:58:55.048853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.442245ms","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-08-24T07:58:55.048963Z","caller":"traceutil/trace.go:171","msg":"trace[1762486967] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15532; }","duration":"104.549038ms","start":"2026-08-24T07:58:54.944381Z","end":"2026-08-24T07:58:55.04893Z","steps":["trace[1762486967] 'agreement among raft nodes before linearized reading' (duration: 103.68087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:59:29.047884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.066362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T07:59:29.047954Z","caller":"traceutil/trace.go:171","msg":"trace[1374402610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15708; }","duration":"230.183434ms","start":"2026-08-24T07:59:28.817755Z","end":"2026-08-24T07:59:29.047938Z","steps":["trace[1374402610] 'range keys from in-memory index tree' (duration: 229.897399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:59:29.047988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.832779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dfk2c\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-24T07:59:29.048035Z","caller":"traceutil/trace.go:171","msg":"trace[186936360] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dfk2c; range_end:; response_count:1; response_revision:15708; }","duration":"124.902231ms","start":"2026-08-24T07:59:28.92312Z","end":"2026-08-24T07:59:29.048022Z","steps":["trace[186936360] 'range keys from in-memory index tree' (duration: 124.636576ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:59:29.211212Z","caller":"traceutil/trace.go:171","msg":"trace[349522999] transaction","detail":"{read_only:false; response_revision:15710; number_of_response:1; }","duration":"115.47301ms","start":"2026-08-24T07:59:29.095705Z","end":"2026-08-24T07:59:29.211178Z","steps":["trace[349522999] 'process raft request' (duration: 113.93722ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:02:51.130587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14662} {"level":"info","ts":"2026-08-24T08:02:51.206666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14662,"took":"72.993386ms","hash":892403123,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":34361344,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-24T08:02:51.206733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":892403123,"revision":14662,"compact-revision":12670} {"level":"info","ts":"2026-08-24T08:05:32.641163Z","caller":"traceutil/trace.go:171","msg":"trace[1059306011] transaction","detail":"{read_only:false; response_revision:18456; number_of_response:1; }","duration":"119.798179ms","start":"2026-08-24T08:05:32.52133Z","end":"2026-08-24T08:05:32.641128Z","steps":["trace[1059306011] 'process raft request' (duration: 119.617366ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:05:32.694443Z","caller":"traceutil/trace.go:171","msg":"trace[2049248189] linearizableReadLoop","detail":"{readStateIndex:19090; appliedIndex:19088; }","duration":"133.933787ms","start":"2026-08-24T08:05:32.560471Z","end":"2026-08-24T08:05:32.694405Z","steps":["trace[2049248189] 'read index received' (duration: 80.440848ms)","trace[2049248189] 'applied index is now lower than readState.Index' (duration: 53.491419ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:05:32.694736Z","caller":"traceutil/trace.go:171","msg":"trace[1804716004] transaction","detail":"{read_only:false; response_revision:18457; number_of_response:1; }","duration":"170.085254ms","start":"2026-08-24T08:05:32.524612Z","end":"2026-08-24T08:05:32.694697Z","steps":["trace[1804716004] 'process raft request' (duration: 167.563832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:05:32.694762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.250404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-8qsmv\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-08-24T08:05:32.69485Z","caller":"traceutil/trace.go:171","msg":"trace[314304232] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-8qsmv; range_end:; response_count:1; response_revision:18457; }","duration":"134.404897ms","start":"2026-08-24T08:05:32.560427Z","end":"2026-08-24T08:05:32.694832Z","steps":["trace[314304232] 'agreement among raft nodes before linearized reading' (duration: 134.103571ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:05:39.893394Z","caller":"traceutil/trace.go:171","msg":"trace[662985006] transaction","detail":"{read_only:false; response_revision:18497; number_of_response:1; }","duration":"185.039338ms","start":"2026-08-24T08:05:39.70832Z","end":"2026-08-24T08:05:39.893359Z","steps":["trace[662985006] 'process raft request' (duration: 184.851814ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:07:01.017051Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T08:07:01.022333Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-24T08:07:01.022421Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-24T08:07:51.136757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17365} {"level":"info","ts":"2026-08-24T08:07:51.242469Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17365,"took":"101.223182ms","hash":179627045,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":35176448,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-24T08:07:51.242528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":179627045,"revision":17365,"compact-revision":14662} {"level":"info","ts":"2026-08-24T08:09:10.715227Z","caller":"traceutil/trace.go:171","msg":"trace[1839980847] transaction","detail":"{read_only:false; response_revision:20201; number_of_response:1; }","duration":"103.839385ms","start":"2026-08-24T08:09:10.611362Z","end":"2026-08-24T08:09:10.715202Z","steps":["trace[1839980847] 'process raft request' (duration: 103.292874ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:11.198046Z","caller":"traceutil/trace.go:171","msg":"trace[146110825] linearizableReadLoop","detail":"{readStateIndex:20890; appliedIndex:20889; }","duration":"154.778921ms","start":"2026-08-24T08:09:11.043243Z","end":"2026-08-24T08:09:11.198022Z","steps":["trace[146110825] 'read index received' (duration: 154.648799ms)","trace[146110825] 'applied index is now lower than readState.Index' (duration: 129.052µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:09:11.198103Z","caller":"traceutil/trace.go:171","msg":"trace[518363398] transaction","detail":"{read_only:false; response_revision:20202; number_of_response:1; }","duration":"170.203605ms","start":"2026-08-24T08:09:11.027852Z","end":"2026-08-24T08:09:11.198055Z","steps":["trace[518363398] 'process raft request' (duration: 170.035612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:09:11.198403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.133359ms","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-08-24T08:09:11.198439Z","caller":"traceutil/trace.go:171","msg":"trace[567532779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20202; }","duration":"155.240572ms","start":"2026-08-24T08:09:11.043189Z","end":"2026-08-24T08:09:11.19843Z","steps":["trace[567532779] 'agreement among raft nodes before linearized reading' (duration: 154.982245ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:11.498546Z","caller":"traceutil/trace.go:171","msg":"trace[689294815] transaction","detail":"{read_only:false; response_revision:20205; number_of_response:1; }","duration":"103.61564ms","start":"2026-08-24T08:09:11.394902Z","end":"2026-08-24T08:09:11.498518Z","steps":["trace[689294815] 'process raft request' (duration: 17.515436ms)","trace[689294815] 'compare' (duration: 85.995672ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:09:11.763007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.319861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T08:09:11.763243Z","caller":"traceutil/trace.go:171","msg":"trace[1348968868] transaction","detail":"{read_only:false; response_revision:20207; number_of_response:1; }","duration":"203.508424ms","start":"2026-08-24T08:09:11.559705Z","end":"2026-08-24T08:09:11.763213Z","steps":["trace[1348968868] 'process raft request' (duration: 203.432372ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:11.763274Z","caller":"traceutil/trace.go:171","msg":"trace[1527179351] transaction","detail":"{read_only:false; response_revision:20206; number_of_response:1; }","duration":"314.2736ms","start":"2026-08-24T08:09:11.448957Z","end":"2026-08-24T08:09:11.763231Z","steps":["trace[1527179351] 'process raft request' (duration: 191.605912ms)","trace[1527179351] 'compare' (duration: 122.184829ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:09:11.763496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:09:11.448931Z","time spent":"314.444603ms","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:09:11.997763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.9788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T08:09:11.997881Z","caller":"traceutil/trace.go:171","msg":"trace[1134405062] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:20207; }","duration":"109.157244ms","start":"2026-08-24T08:09:11.888692Z","end":"2026-08-24T08:09:11.99785Z","steps":["trace[1134405062] 'count revisions from in-memory index tree' (duration: 108.864007ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:11:36.109555Z","caller":"traceutil/trace.go:171","msg":"trace[296666444] transaction","detail":"{read_only:false; response_revision:21246; number_of_response:1; }","duration":"244.984638ms","start":"2026-08-24T08:11:35.864531Z","end":"2026-08-24T08:11:36.109516Z","steps":["trace[296666444] 'process raft request' (duration: 244.659702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.149912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228411223672428,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:12:08.346922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.978374679s","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-08-24T08:12:08.347018Z","caller":"traceutil/trace.go:171","msg":"trace[1587477107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21402; }","duration":"1.978519412s","start":"2026-08-24T08:12:06.368477Z","end":"2026-08-24T08:12:08.346996Z","steps":["trace[1587477107] 'range keys from in-memory index tree' (duration: 1.978153754s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.347074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.368455Z","time spent":"1.978609403s","remote":"127.0.0.1:46956","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-08-24T08:12:08.348518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044294876s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T08:12:08.348739Z","caller":"traceutil/trace.go:171","msg":"trace[1524800645] transaction","detail":"{read_only:false; response_revision:21403; number_of_response:1; }","duration":"1.883009577s","start":"2026-08-24T08:12:06.465702Z","end":"2026-08-24T08:12:08.348711Z","steps":["trace[1524800645] 'process raft request' (duration: 838.078768ms)","trace[1524800645] 'compare' (duration: 1.044163423s)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:12:08.34957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.465678Z","time spent":"1.88311281s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:08.350171Z","caller":"traceutil/trace.go:171","msg":"trace[27866070] linearizableReadLoop","detail":"{readStateIndex:22135; appliedIndex:22132; }","duration":"1.700914139s","start":"2026-08-24T08:12:06.649197Z","end":"2026-08-24T08:12:08.350111Z","steps":["trace[27866070] 'read index received' (duration: 654.609071ms)","trace[27866070] 'applied index is now lower than readState.Index' (duration: 1.046258507s)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:12:08.349924Z","caller":"traceutil/trace.go:171","msg":"trace[164421958] transaction","detail":"{read_only:false; response_revision:21404; number_of_response:1; }","duration":"1.780459648s","start":"2026-08-24T08:12:06.569436Z","end":"2026-08-24T08:12:08.349896Z","steps":["trace[164421958] 'process raft request' (duration: 1.780173792s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:08.350351Z","caller":"traceutil/trace.go:171","msg":"trace[1829741784] transaction","detail":"{read_only:false; response_revision:21405; number_of_response:1; }","duration":"1.205315155s","start":"2026-08-24T08:12:07.144992Z","end":"2026-08-24T08:12:08.350307Z","steps":["trace[1829741784] 'process raft request' (duration: 1.204842766s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.350418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.701220414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T08:12:08.350462Z","caller":"traceutil/trace.go:171","msg":"trace[1334669641] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:21405; }","duration":"1.701305696s","start":"2026-08-24T08:12:06.649139Z","end":"2026-08-24T08:12:08.350445Z","steps":["trace[1334669641] 'agreement among raft nodes before linearized reading' (duration: 1.701204614s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.350494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.649116Z","time spent":"1.701368988s","remote":"127.0.0.1:50608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":27,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-08-24T08:12:08.350454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.569399Z","time spent":"1.780930808s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:08.350561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.144966Z","time spent":"1.205473899s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:08.350837Z","caller":"traceutil/trace.go:171","msg":"trace[1823941575] transaction","detail":"{read_only:false; response_revision:21406; number_of_response:1; }","duration":"833.549514ms","start":"2026-08-24T08:12:07.517242Z","end":"2026-08-24T08:12:08.350791Z","steps":["trace[1823941575] 'process raft request' (duration: 833.3308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.350972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.517218Z","time spent":"833.679757ms","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:08.351246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627966714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:08.351298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.155787ms","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-08-24T08:12:08.351336Z","caller":"traceutil/trace.go:171","msg":"trace[1912278864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21408; }","duration":"450.191619ms","start":"2026-08-24T08:12:07.901133Z","end":"2026-08-24T08:12:08.351324Z","steps":["trace[1912278864] 'agreement among raft nodes before linearized reading' (duration: 450.079967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.351375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.901073Z","time spent":"450.295121ms","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T08:12:08.351392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013116183s","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-08-24T08:12:08.351425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.020599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-24T08:12:08.351395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.895286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-24T08:12:08.351472Z","caller":"traceutil/trace.go:171","msg":"trace[1410541524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21408; }","duration":"1.013301176s","start":"2026-08-24T08:12:07.338158Z","end":"2026-08-24T08:12:08.35146Z","steps":["trace[1410541524] 'agreement among raft nodes before linearized reading' (duration: 1.013089062s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:08.351481Z","caller":"traceutil/trace.go:171","msg":"trace[37362834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21408; }","duration":"816.104111ms","start":"2026-08-24T08:12:07.535362Z","end":"2026-08-24T08:12:08.351466Z","steps":["trace[37362834] 'agreement among raft nodes before linearized reading' (duration: 815.925077ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:08.351506Z","caller":"traceutil/trace.go:171","msg":"trace[327689960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21408; }","duration":"954.0494ms","start":"2026-08-24T08:12:07.397429Z","end":"2026-08-24T08:12:08.351478Z","steps":["trace[327689960] 'agreement among raft nodes before linearized reading' (duration: 953.815575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.351517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.53535Z","time spent":"816.159632ms","remote":"127.0.0.1:46956","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-08-24T08:12:08.351512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.338115Z","time spent":"1.013388887s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T08:12:08.351549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.397411Z","time spent":"954.130001ms","remote":"127.0.0.1:46956","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-08-24T08:12:08.351521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.957879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:08.351304Z","caller":"traceutil/trace.go:171","msg":"trace[770603545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21408; }","duration":"1.628065426s","start":"2026-08-24T08:12:06.723225Z","end":"2026-08-24T08:12:08.35129Z","steps":["trace[770603545] 'agreement among raft nodes before linearized reading' (duration: 1.627971674s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.351624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.09978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-24T08:12:08.351653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.723202Z","time spent":"1.628445673s","remote":"127.0.0.1:46694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T08:12:08.351665Z","caller":"traceutil/trace.go:171","msg":"trace[1477639880] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21408; }","duration":"402.17067ms","start":"2026-08-24T08:12:07.94948Z","end":"2026-08-24T08:12:08.351651Z","steps":["trace[1477639880] 'agreement among raft nodes before linearized reading' (duration: 402.063448ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:08.351656Z","caller":"traceutil/trace.go:171","msg":"trace[1754239218] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:21408; }","duration":"379.120931ms","start":"2026-08-24T08:12:07.972515Z","end":"2026-08-24T08:12:08.351636Z","steps":["trace[1754239218] 'agreement among raft nodes before linearized reading' (duration: 378.977228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.3517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.949465Z","time spent":"402.227732ms","remote":"127.0.0.1:47022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":91,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-24T08:12:08.351717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.972496Z","time spent":"379.202733ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-08-24T08:12:08.352067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.023169ms","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":"warn","ts":"2026-08-24T08:12:08.352293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53401906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:08.352332Z","caller":"traceutil/trace.go:171","msg":"trace[440966716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21408; }","duration":"1.534109792s","start":"2026-08-24T08:12:06.818213Z","end":"2026-08-24T08:12:08.352323Z","steps":["trace[440966716] 'agreement among raft nodes before linearized reading' (duration: 1.533146262s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.352354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.818183Z","time spent":"1.534165303s","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T08:12:08.352322Z","caller":"traceutil/trace.go:171","msg":"trace[449025391] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21408; }","duration":"875.321046ms","start":"2026-08-24T08:12:07.476966Z","end":"2026-08-24T08:12:08.352287Z","steps":["trace[449025391] 'agreement among raft nodes before linearized reading' (duration: 874.436468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.352575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.476944Z","time spent":"875.543251ms","remote":"127.0.0.1:44314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-24T08:12:40.318726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228411223673660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:12:40.8189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228411223673660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:12:41.322398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228411223673660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:12:41.458251Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.660262631s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T08:12:41.645953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.326425ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T08:12:41.65309Z","caller":"traceutil/trace.go:171","msg":"trace[1105869193] transaction","detail":"{read_only:false; response_revision:21835; number_of_response:1; }","duration":"1.185723526s","start":"2026-08-24T08:12:40.467323Z","end":"2026-08-24T08:12:41.653046Z","steps":["trace[1105869193] 'process raft request' (duration: 1.185270557s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.653296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.467296Z","time spent":"1.18589731s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:41.653309Z","caller":"traceutil/trace.go:171","msg":"trace[1848698333] transaction","detail":"{read_only:false; response_revision:21836; number_of_response:1; }","duration":"1.16383586s","start":"2026-08-24T08:12:40.489437Z","end":"2026-08-24T08:12:41.653273Z","steps":["trace[1848698333] 'process raft request' (duration: 1.163422872s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.653425Z","caller":"traceutil/trace.go:171","msg":"trace[138761940] transaction","detail":"{read_only:false; response_revision:21837; number_of_response:1; }","duration":"1.163882781s","start":"2026-08-24T08:12:40.489511Z","end":"2026-08-24T08:12:41.653394Z","steps":["trace[138761940] 'process raft request' (duration: 1.163656756s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.653487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.489406Z","time spent":"1.163988433s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:41.65356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.489483Z","time spent":"1.163997623s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:41.65377Z","caller":"traceutil/trace.go:171","msg":"trace[991148097] linearizableReadLoop","detail":"{readStateIndex:22586; appliedIndex:22580; }","duration":"1.835334167s","start":"2026-08-24T08:12:39.818421Z","end":"2026-08-24T08:12:41.653755Z","steps":["trace[991148097] 'read index received' (duration: 1.640217743s)","trace[991148097] 'applied index is now lower than readState.Index' (duration: 195.115684ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:12:41.653812Z","caller":"traceutil/trace.go:171","msg":"trace[1057838792] transaction","detail":"{read_only:false; response_revision:21838; number_of_response:1; }","duration":"1.163233219s","start":"2026-08-24T08:12:40.490567Z","end":"2026-08-24T08:12:41.6538Z","steps":["trace[1057838792] 'process raft request' (duration: 1.163083465s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.653853Z","caller":"traceutil/trace.go:171","msg":"trace[196458559] transaction","detail":"{read_only:false; response_revision:21839; number_of_response:1; }","duration":"1.104496083s","start":"2026-08-24T08:12:40.549347Z","end":"2026-08-24T08:12:41.653843Z","steps":["trace[196458559] 'process raft request' (duration: 1.1043692s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.653893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.490555Z","time spent":"1.16330436s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:41.653922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.549326Z","time spent":"1.104554094s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:41.654156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.835654893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.654282Z","caller":"traceutil/trace.go:171","msg":"trace[535033231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21839; }","duration":"1.835880978s","start":"2026-08-24T08:12:39.818368Z","end":"2026-08-24T08:12:41.654249Z","steps":["trace[535033231] 'agreement among raft nodes before linearized reading' (duration: 1.835576742s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.654342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.818349Z","time spent":"1.83598342s","remote":"127.0.0.1:46694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T08:12:41.658744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14565631s","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-08-24T08:12:41.658809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148346605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-24T08:12:41.658826Z","caller":"traceutil/trace.go:171","msg":"trace[475581728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21840; }","duration":"1.145890875s","start":"2026-08-24T08:12:40.512918Z","end":"2026-08-24T08:12:41.658809Z","steps":["trace[475581728] 'agreement among raft nodes before linearized reading' (duration: 1.145684691s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.659043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.512895Z","time spent":"1.146096859s","remote":"127.0.0.1:46956","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-08-24T08:12:41.659274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.335574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.659317Z","caller":"traceutil/trace.go:171","msg":"trace[2029058966] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21840; }","duration":"185.392096ms","start":"2026-08-24T08:12:41.473914Z","end":"2026-08-24T08:12:41.659306Z","steps":["trace[2029058966] 'agreement among raft nodes before linearized reading' (duration: 185.319024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.659535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.059392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"info","ts":"2026-08-24T08:12:41.659628Z","caller":"traceutil/trace.go:171","msg":"trace[1783325014] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21840; }","duration":"137.279145ms","start":"2026-08-24T08:12:41.522296Z","end":"2026-08-24T08:12:41.659575Z","steps":["trace[1783325014] 'agreement among raft nodes before linearized reading' (duration: 137.066571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.659625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.307449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.659702Z","caller":"traceutil/trace.go:171","msg":"trace[132932056] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21840; }","duration":"143.446282ms","start":"2026-08-24T08:12:41.516237Z","end":"2026-08-24T08:12:41.659683Z","steps":["trace[132932056] 'agreement among raft nodes before linearized reading' (duration: 143.303029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.659703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.415638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.659782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.172652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-24T08:12:41.659836Z","caller":"traceutil/trace.go:171","msg":"trace[2085100768] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21840; }","duration":"162.50205ms","start":"2026-08-24T08:12:41.497279Z","end":"2026-08-24T08:12:41.659781Z","steps":["trace[2085100768] 'agreement among raft nodes before linearized reading' (duration: 162.395787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.659845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.623686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.659615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.821027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-24T08:12:41.659918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152760125s","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-08-24T08:12:41.659945Z","caller":"traceutil/trace.go:171","msg":"trace[712482183] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21840; }","duration":"186.280944ms","start":"2026-08-24T08:12:41.473557Z","end":"2026-08-24T08:12:41.659838Z","steps":["trace[712482183] 'agreement among raft nodes before linearized reading' (duration: 186.010828ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.659931Z","caller":"traceutil/trace.go:171","msg":"trace[1888325797] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21840; }","duration":"143.758648ms","start":"2026-08-24T08:12:41.51616Z","end":"2026-08-24T08:12:41.659919Z","steps":["trace[1888325797] 'agreement among raft nodes before linearized reading' (duration: 143.653456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.659949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773120479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.65999Z","caller":"traceutil/trace.go:171","msg":"trace[253273090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21840; }","duration":"1.152844557s","start":"2026-08-24T08:12:40.507118Z","end":"2026-08-24T08:12:41.659963Z","steps":["trace[253273090] 'agreement among raft nodes before linearized reading' (duration: 1.152651083s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134742598s","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-08-24T08:12:41.660038Z","caller":"traceutil/trace.go:171","msg":"trace[742129241] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21840; }","duration":"1.773238051s","start":"2026-08-24T08:12:39.886777Z","end":"2026-08-24T08:12:41.660015Z","steps":["trace[742129241] 'agreement among raft nodes before linearized reading' (duration: 1.773103239s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.507103Z","time spent":"1.152945709s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T08:12:41.66007Z","caller":"traceutil/trace.go:171","msg":"trace[98809002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21840; }","duration":"1.134806s","start":"2026-08-24T08:12:40.525249Z","end":"2026-08-24T08:12:41.660055Z","steps":["trace[98809002] 'agreement among raft nodes before linearized reading' (duration: 1.134701598s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.88676Z","time spent":"1.773326434s","remote":"127.0.0.1:46920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"warn","ts":"2026-08-24T08:12:41.660105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.200883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.660216Z","caller":"traceutil/trace.go:171","msg":"trace[467659552] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21840; }","duration":"163.395197ms","start":"2026-08-24T08:12:41.496797Z","end":"2026-08-24T08:12:41.660192Z","steps":["trace[467659552] 'agreement among raft nodes before linearized reading' (duration: 163.170742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.829834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"warn","ts":"2026-08-24T08:12:41.659793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.800514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"info","ts":"2026-08-24T08:12:41.660321Z","caller":"traceutil/trace.go:171","msg":"trace[1514761563] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21840; }","duration":"184.938466ms","start":"2026-08-24T08:12:41.47536Z","end":"2026-08-24T08:12:41.660299Z","steps":["trace[1514761563] 'agreement among raft nodes before linearized reading' (duration: 184.711301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.752023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.660385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772211171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"warn","ts":"2026-08-24T08:12:41.660394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.461637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.660428Z","caller":"traceutil/trace.go:171","msg":"trace[1142622586] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21840; }","duration":"187.048809ms","start":"2026-08-24T08:12:41.473366Z","end":"2026-08-24T08:12:41.660415Z","steps":["trace[1142622586] 'agreement among raft nodes before linearized reading' (duration: 186.770064ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.660438Z","caller":"traceutil/trace.go:171","msg":"trace[41527054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21840; }","duration":"789.552798ms","start":"2026-08-24T08:12:40.870874Z","end":"2026-08-24T08:12:41.660426Z","steps":["trace[41527054] 'agreement among raft nodes before linearized reading' (duration: 789.497317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.870847Z","time spent":"789.62051ms","remote":"127.0.0.1:46694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T08:12:41.659958Z","caller":"traceutil/trace.go:171","msg":"trace[681680793] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21840; }","duration":"163.223835ms","start":"2026-08-24T08:12:41.496722Z","end":"2026-08-24T08:12:41.659946Z","steps":["trace[681680793] 'agreement among raft nodes before linearized reading' (duration: 162.761836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.416909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.660518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13680257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T08:12:41.660542Z","caller":"traceutil/trace.go:171","msg":"trace[184426090] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21840; }","duration":"163.94239ms","start":"2026-08-24T08:12:41.496588Z","end":"2026-08-24T08:12:41.660531Z","steps":["trace[184426090] 'agreement among raft nodes before linearized reading' (duration: 163.349168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580764651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/octavia-db-sync-msv4l\" ","response":"range_response_count:1 size:6673"} {"level":"info","ts":"2026-08-24T08:12:41.660562Z","caller":"traceutil/trace.go:171","msg":"trace[1860234342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21840; }","duration":"1.136871512s","start":"2026-08-24T08:12:40.523679Z","end":"2026-08-24T08:12:41.66055Z","steps":["trace[1860234342] 'agreement among raft nodes before linearized reading' (duration: 1.136635807s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.659704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77219348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.660618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.523664Z","time spent":"1.136942083s","remote":"127.0.0.1:46956","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-08-24T08:12:41.658734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.62756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.6602Z","caller":"traceutil/trace.go:171","msg":"trace[1098786512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21840; }","duration":"1.149800415s","start":"2026-08-24T08:12:40.510369Z","end":"2026-08-24T08:12:41.660169Z","steps":["trace[1098786512] 'agreement among raft nodes before linearized reading' (duration: 1.148262774s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.660673Z","caller":"traceutil/trace.go:171","msg":"trace[838801468] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21840; }","duration":"186.628701ms","start":"2026-08-24T08:12:41.474031Z","end":"2026-08-24T08:12:41.660659Z","steps":["trace[838801468] 'agreement among raft nodes before linearized reading' (duration: 184.558569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.658808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.704846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.660652Z","caller":"traceutil/trace.go:171","msg":"trace[1677678405] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21840; }","duration":"1.773189051s","start":"2026-08-24T08:12:39.887444Z","end":"2026-08-24T08:12:41.660633Z","steps":["trace[1677678405] 'agreement among raft nodes before linearized reading' (duration: 1.77215297s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.510347Z","time spent":"1.150361766s","remote":"127.0.0.1:46956","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":"info","ts":"2026-08-24T08:12:41.66074Z","caller":"traceutil/trace.go:171","msg":"trace[571761858] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21840; }","duration":"144.652786ms","start":"2026-08-24T08:12:41.516068Z","end":"2026-08-24T08:12:41.660721Z","steps":["trace[571761858] 'agreement among raft nodes before linearized reading' (duration: 142.689486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.659902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.148615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-24T08:12:41.660806Z","caller":"traceutil/trace.go:171","msg":"trace[1475594638] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21840; }","duration":"144.077074ms","start":"2026-08-24T08:12:41.516715Z","end":"2026-08-24T08:12:41.660792Z","steps":["trace[1475594638] 'agreement among raft nodes before linearized reading' (duration: 142.933851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.525234Z","time spent":"1.134875001s","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T08:12:41.660341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.393264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.660346Z","caller":"traceutil/trace.go:171","msg":"trace[288170662] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21840; }","duration":"160.428587ms","start":"2026-08-24T08:12:41.499902Z","end":"2026-08-24T08:12:41.66033Z","steps":["trace[288170662] 'agreement among raft nodes before linearized reading' (duration: 158.903445ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.66087Z","caller":"traceutil/trace.go:171","msg":"trace[1748361379] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:21840; }","duration":"235.987166ms","start":"2026-08-24T08:12:41.424867Z","end":"2026-08-24T08:12:41.660854Z","steps":["trace[1748361379] 'agreement among raft nodes before linearized reading' (duration: 235.315973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.887432Z","time spent":"1.773312793s","remote":"127.0.0.1:46920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" "} {"level":"info","ts":"2026-08-24T08:12:41.660433Z","caller":"traceutil/trace.go:171","msg":"trace[754992638] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21840; }","duration":"1.772270172s","start":"2026-08-24T08:12:39.888148Z","end":"2026-08-24T08:12:41.660419Z","steps":["trace[754992638] 'agreement among raft nodes before linearized reading' (duration: 1.77215064s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.660267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773390174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.661023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.88814Z","time spent":"1.772868084s","remote":"127.0.0.1:46920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5160,"request content":"key:\"/registry/jobs/openstack/octavia-db-sync\" "} {"level":"info","ts":"2026-08-24T08:12:41.661071Z","caller":"traceutil/trace.go:171","msg":"trace[827718259] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21840; }","duration":"1.774231161s","start":"2026-08-24T08:12:39.886822Z","end":"2026-08-24T08:12:41.661053Z","steps":["trace[827718259] 'agreement among raft nodes before linearized reading' (duration: 1.773409415s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.661136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.8868Z","time spent":"1.774319303s","remote":"127.0.0.1:46920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" "} {"level":"info","ts":"2026-08-24T08:12:41.660593Z","caller":"traceutil/trace.go:171","msg":"trace[1500855772] range","detail":"{range_begin:/registry/pods/openstack/octavia-db-sync-msv4l; range_end:; response_count:1; response_revision:21840; }","duration":"1.580849443s","start":"2026-08-24T08:12:40.07973Z","end":"2026-08-24T08:12:41.66058Z","steps":["trace[1500855772] 'agreement among raft nodes before linearized reading' (duration: 1.58072803s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.661206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.079713Z","time spent":"1.581479076s","remote":"127.0.0.1:46888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6695,"request content":"key:\"/registry/pods/openstack/octavia-db-sync-msv4l\" "} {"level":"info","ts":"2026-08-24T08:12:51.142112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19697} {"level":"info","ts":"2026-08-24T08:12:51.289241Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19697,"took":"142.467622ms","hash":4172307805,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":29687808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T08:12:51.289324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4172307805,"revision":19697,"compact-revision":17365} {"level":"warn","ts":"2026-08-24T08:13:13.232659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228411223674339,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:13:13.733559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228411223674339,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:13:13.806867Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.076023463s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T08:13:13.807333Z","caller":"traceutil/trace.go:171","msg":"trace[1653399444] transaction","detail":"{read_only:false; response_revision:22032; number_of_response:1; }","duration":"1.076580974s","start":"2026-08-24T08:13:12.730727Z","end":"2026-08-24T08:13:13.807308Z","steps":["trace[1653399444] 'process raft request' (duration: 1.07640653s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:13.807476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:13:12.730694Z","time spent":"1.076693906s","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":809,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T08:13:13.856124Z","caller":"traceutil/trace.go:171","msg":"trace[165698407] transaction","detail":"{read_only:false; response_revision:22033; number_of_response:1; }","duration":"1.118698332s","start":"2026-08-24T08:13:12.737414Z","end":"2026-08-24T08:13:13.856112Z","steps":["trace[165698407] 'process raft request' (duration: 1.11856817s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:13.856206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:13:12.737399Z","time spent":"1.118759483s","remote":"127.0.0.1:46888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6662,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:13:13.856453Z","caller":"traceutil/trace.go:171","msg":"trace[1183398849] transaction","detail":"{read_only:false; response_revision:22034; number_of_response:1; }","duration":"205.69573ms","start":"2026-08-24T08:13:13.650748Z","end":"2026-08-24T08:13:13.856444Z","steps":["trace[1183398849] 'process raft request' (duration: 205.313592ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:13:13.856758Z","caller":"traceutil/trace.go:171","msg":"trace[2111491716] linearizableReadLoop","detail":"{readStateIndex:22788; appliedIndex:22785; }","duration":"1.12449747s","start":"2026-08-24T08:13:12.732235Z","end":"2026-08-24T08:13:13.856732Z","steps":["trace[2111491716] 'read index received' (duration: 1.074874349s)","trace[2111491716] 'applied index is now lower than readState.Index' (duration: 49.621921ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:13:13.856968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037027569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:13:13.857011Z","caller":"traceutil/trace.go:171","msg":"trace[426793789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22038; }","duration":"1.037138621s","start":"2026-08-24T08:13:12.819861Z","end":"2026-08-24T08:13:13.857Z","steps":["trace[426793789] 'agreement among raft nodes before linearized reading' (duration: 1.037048989s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:13.857032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124785635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-24T08:13:13.857096Z","caller":"traceutil/trace.go:171","msg":"trace[226594658] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:22038; }","duration":"1.124863577s","start":"2026-08-24T08:13:12.732216Z","end":"2026-08-24T08:13:13.857079Z","steps":["trace[226594658] 'agreement among raft nodes before linearized reading' (duration: 1.124617052s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:13.857137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:13:12.732197Z","time spent":"1.124931059s","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":140,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-08-24T08:13:13.857044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:13:12.819834Z","time spent":"1.037202202s","remote":"127.0.0.1:46694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T08:13:14.07897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.158746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T08:13:14.079106Z","caller":"traceutil/trace.go:171","msg":"trace[1758137880] transaction","detail":"{read_only:false; response_revision:22039; number_of_response:1; }","duration":"209.107687ms","start":"2026-08-24T08:13:13.869976Z","end":"2026-08-24T08:13:14.079084Z","steps":["trace[1758137880] 'process raft request' (duration: 36.734408ms)","trace[1758137880] 'compare' (duration: 171.667635ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:13:14.082056Z","caller":"traceutil/trace.go:171","msg":"trace[285294825] transaction","detail":"{read_only:false; response_revision:22040; number_of_response:1; }","duration":"211.519297ms","start":"2026-08-24T08:13:13.870499Z","end":"2026-08-24T08:13:14.082018Z","steps":["trace[285294825] 'process raft request' (duration: 211.263062ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:13:14.082534Z","caller":"traceutil/trace.go:171","msg":"trace[267882958] transaction","detail":"{read_only:false; response_revision:22041; number_of_response:1; }","duration":"211.934376ms","start":"2026-08-24T08:13:13.870574Z","end":"2026-08-24T08:13:14.082509Z","steps":["trace[267882958] 'process raft request' (duration: 211.66441ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:13:14.082783Z","caller":"traceutil/trace.go:171","msg":"trace[1613477148] transaction","detail":"{read_only:false; response_revision:22043; number_of_response:1; }","duration":"212.116989ms","start":"2026-08-24T08:13:13.870655Z","end":"2026-08-24T08:13:14.082772Z","steps":["trace[1613477148] 'process raft request' (duration: 211.971166ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:13:14.082815Z","caller":"traceutil/trace.go:171","msg":"trace[441588660] transaction","detail":"{read_only:false; response_revision:22042; number_of_response:1; }","duration":"212.204241ms","start":"2026-08-24T08:13:13.870571Z","end":"2026-08-24T08:13:14.082775Z","steps":["trace[441588660] 'process raft request' (duration: 211.775403ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:17:51.148827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21894} {"level":"info","ts":"2026-08-24T08:17:51.259195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21894,"took":"105.934358ms","hash":3041595592,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":43831296,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-24T08:17:51.259276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3041595592,"revision":21894,"compact-revision":19697} {"level":"info","ts":"2026-08-24T08:19:53.647948Z","caller":"traceutil/trace.go:171","msg":"trace[1489238467] linearizableReadLoop","detail":"{readStateIndex:26726; appliedIndex:26722; }","duration":"159.713459ms","start":"2026-08-24T08:19:53.488206Z","end":"2026-08-24T08:19:53.64792Z","steps":["trace[1489238467] 'read index received' (duration: 159.297911ms)","trace[1489238467] 'applied index is now lower than readState.Index' (duration: 414.528µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:19:53.648402Z","caller":"traceutil/trace.go:171","msg":"trace[991217755] transaction","detail":"{read_only:false; response_revision:25865; number_of_response:1; }","duration":"175.811547ms","start":"2026-08-24T08:19:53.472578Z","end":"2026-08-24T08:19:53.648389Z","steps":["trace[991217755] 'process raft request' (duration: 175.036492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.648679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.14892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:19:53.648733Z","caller":"traceutil/trace.go:171","msg":"trace[1767229028] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:25868; }","duration":"167.252763ms","start":"2026-08-24T08:19:53.481464Z","end":"2026-08-24T08:19:53.648717Z","steps":["trace[1767229028] 'agreement among raft nodes before linearized reading' (duration: 167.161281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.64875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.099848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:19:53.648952Z","caller":"traceutil/trace.go:171","msg":"trace[113258384] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:25868; }","duration":"167.377054ms","start":"2026-08-24T08:19:53.481553Z","end":"2026-08-24T08:19:53.64893Z","steps":["trace[113258384] 'agreement among raft nodes before linearized reading' (duration: 167.046647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.649021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.923035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-24T08:19:53.649066Z","caller":"traceutil/trace.go:171","msg":"trace[1344923525] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25868; }","duration":"117.016177ms","start":"2026-08-24T08:19:53.532037Z","end":"2026-08-24T08:19:53.649054Z","steps":["trace[1344923525] 'agreement among raft nodes before linearized reading' (duration: 116.875764ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:19:53.649269Z","caller":"traceutil/trace.go:171","msg":"trace[120742297] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"176.359338ms","start":"2026-08-24T08:19:53.472889Z","end":"2026-08-24T08:19:53.649249Z","steps":["trace[120742297] 'process raft request' (duration: 174.905949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.649402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.756007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-08-24T08:19:53.649463Z","caller":"traceutil/trace.go:171","msg":"trace[1399041733] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:25868; }","duration":"103.837958ms","start":"2026-08-24T08:19:53.545611Z","end":"2026-08-24T08:19:53.649449Z","steps":["trace[1399041733] 'agreement among raft nodes before linearized reading' (duration: 103.713586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.649733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.492905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:19:53.649781Z","caller":"traceutil/trace.go:171","msg":"trace[1609225241] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:25868; }","duration":"109.554606ms","start":"2026-08-24T08:19:53.540216Z","end":"2026-08-24T08:19:53.649771Z","steps":["trace[1609225241] 'agreement among raft nodes before linearized reading' (duration: 109.478974ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:19:53.649896Z","caller":"traceutil/trace.go:171","msg":"trace[1471365736] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"176.168354ms","start":"2026-08-24T08:19:53.473707Z","end":"2026-08-24T08:19:53.649875Z","steps":["trace[1471365736] 'process raft request' (duration: 174.132562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.64999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.785281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:19:53.650039Z","caller":"traceutil/trace.go:171","msg":"trace[1285571181] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25868; }","duration":"109.851632ms","start":"2026-08-24T08:19:53.540177Z","end":"2026-08-24T08:19:53.650029Z","steps":["trace[1285571181] 'agreement among raft nodes before linearized reading' (duration: 109.783661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.650208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.246751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:19:53.650244Z","caller":"traceutil/trace.go:171","msg":"trace[1574082896] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25868; }","duration":"110.300901ms","start":"2026-08-24T08:19:53.539935Z","end":"2026-08-24T08:19:53.650236Z","steps":["trace[1574082896] 'agreement among raft nodes before linearized reading' (duration: 110.22436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.650354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.60923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:19:53.650406Z","caller":"traceutil/trace.go:171","msg":"trace[4841955] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25868; }","duration":"168.717632ms","start":"2026-08-24T08:19:53.481677Z","end":"2026-08-24T08:19:53.650395Z","steps":["trace[4841955] 'agreement among raft nodes before linearized reading' (duration: 168.646431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.650421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.928614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:19:53.650459Z","caller":"traceutil/trace.go:171","msg":"trace[1867466005] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:25868; }","duration":"110.986995ms","start":"2026-08-24T08:19:53.539464Z","end":"2026-08-24T08:19:53.65045Z","steps":["trace[1867466005] 'agreement among raft nodes before linearized reading' (duration: 110.934813ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:19:53.651219Z","caller":"traceutil/trace.go:171","msg":"trace[1298961992] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"168.708662ms","start":"2026-08-24T08:19:53.482498Z","end":"2026-08-24T08:19:53.651207Z","steps":["trace[1298961992] 'process raft request' (duration: 165.381054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:19:53.653063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.589511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-08-24T08:19:53.653104Z","caller":"traceutil/trace.go:171","msg":"trace[1728476459] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:25868; }","duration":"170.651932ms","start":"2026-08-24T08:19:53.482442Z","end":"2026-08-24T08:19:53.653094Z","steps":["trace[1728476459] 'agreement among raft nodes before linearized reading' (duration: 167.300813ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:22:51.157308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24963} {"level":"info","ts":"2026-08-24T08:22:51.310493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24963,"took":"147.655477ms","hash":511752604,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T08:22:51.31057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":511752604,"revision":24963,"compact-revision":21894} {"level":"info","ts":"2026-08-24T08:23:11.455093Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006e61.wal"}