{"level":"warn","ts":"2026-09-07T18:16:14.215974Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:16:14.216199Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.91:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.91:2380","--initial-cluster=instance=https://162.253.55.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.91: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-09-07T18:16:14.216288Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:16:14.216301Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-09-07T18:16:14.216324Z","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-09-07T18:16:14.217098Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"]} {"level":"info","ts":"2026-09-07T18:16:14.217244Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.91: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-09-07T18:16:14.221748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.249036ms"} {"level":"info","ts":"2026-09-07T18:16:14.2285Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a5b61ed9120d6b3","cluster-id":"9e0c1e8055c016ad"} {"level":"info","ts":"2026-09-07T18:16:14.228571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T18:16:14.228592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 0"} {"level":"info","ts":"2026-09-07T18:16:14.228606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a5b61ed9120d6b3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T18:16:14.22862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 1"} {"level":"info","ts":"2026-09-07T18:16:14.228654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"warn","ts":"2026-09-07T18:16:14.239343Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T18:16:14.240605Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T18:16:14.241778Z","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-09-07T18:16:14.244554Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a5b61ed9120d6b3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T18:16:14.244823Z","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-09-07T18:16:14.244925Z","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-09-07T18:16:14.244933Z","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-09-07T18:16:14.245189Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a5b61ed9120d6b3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T18:16:14.247262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"info","ts":"2026-09-07T18:16:14.248169Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","added-peer-id":"8a5b61ed9120d6b3","added-peer-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-09-07T18:16:14.24954Z","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-09-07T18:16:14.249678Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-09-07T18:16:14.249722Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-09-07T18:16:14.250029Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a5b61ed9120d6b3","initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T18:16:14.250093Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T18:16:14.429538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T18:16:14.429586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T18:16:14.429613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgPreVoteResp from 8a5b61ed9120d6b3 at term 1"} {"level":"info","ts":"2026-09-07T18:16:14.42963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became candidate at term 2"} {"level":"info","ts":"2026-09-07T18:16:14.429637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgVoteResp from 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-09-07T18:16:14.429648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became leader at term 2"} {"level":"info","ts":"2026-09-07T18:16:14.429661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a5b61ed9120d6b3 elected leader 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-09-07T18:16:14.430634Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a5b61ed9120d6b3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.91:2379]}","request-path":"/0/members/8a5b61ed9120d6b3/attributes","cluster-id":"9e0c1e8055c016ad","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T18:16:14.430872Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:16:14.430916Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:16:14.431037Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T18:16:14.431061Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T18:16:14.431188Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:16:14.432215Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:16:14.432446Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:16:14.432551Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:16:14.433776Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T18:16:14.43444Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.91:2379"} {"level":"warn","ts":"2026-09-07T18:16:19.345832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.74092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:16:19.345942Z","caller":"traceutil/trace.go:171","msg":"trace[1998165190] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"214.321261ms","start":"2026-09-07T18:16:19.131606Z","end":"2026-09-07T18:16:19.345927Z","steps":["trace[1998165190] 'read index received' (duration: 23.959386ms)","trace[1998165190] 'applied index is now lower than readState.Index' (duration: 190.360824ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:16:19.34598Z","caller":"traceutil/trace.go:171","msg":"trace[2030062635] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"217.333086ms","start":"2026-09-07T18:16:19.128604Z","end":"2026-09-07T18:16:19.345937Z","steps":["trace[2030062635] 'process raft request' (duration: 26.990001ms)","trace[2030062635] 'compare' (duration: 189.631649ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:16:19.346043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.433111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:16:19.346075Z","caller":"traceutil/trace.go:171","msg":"trace[707385498] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:263; }","duration":"214.494572ms","start":"2026-09-07T18:16:19.131571Z","end":"2026-09-07T18:16:19.346066Z","steps":["trace[707385498] 'agreement among raft nodes before linearized reading' (duration: 214.403651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:16:25.151705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.024813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:16:25.151818Z","caller":"traceutil/trace.go:171","msg":"trace[272770670] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:397; }","duration":"301.712336ms","start":"2026-09-07T18:16:24.85009Z","end":"2026-09-07T18:16:25.151803Z","steps":["trace[272770670] 'read index received' (duration: 145.214688ms)","trace[272770670] 'applied index is now lower than readState.Index' (duration: 156.496478ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:16:25.152265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.160951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-09-07T18:16:25.152614Z","caller":"traceutil/trace.go:171","msg":"trace[1035418320] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:387; }","duration":"302.548116ms","start":"2026-09-07T18:16:24.850043Z","end":"2026-09-07T18:16:25.152591Z","steps":["trace[1035418320] 'agreement among raft nodes before linearized reading' (duration: 302.151981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:16:25.153011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:16:24.850022Z","time spent":"302.97162ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-09-07T18:16:25.153137Z","caller":"traceutil/trace.go:171","msg":"trace[801471844] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"561.184963ms","start":"2026-09-07T18:16:24.590697Z","end":"2026-09-07T18:16:25.151882Z","steps":["trace[801471844] 'process raft request' (duration: 404.741856ms)","trace[801471844] 'compare' (duration: 155.75368ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:16:25.153235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:16:24.590672Z","time spent":"562.522448ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:16:25.724255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.13265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:16:25.724483Z","caller":"traceutil/trace.go:171","msg":"trace[1541390384] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:398; }","duration":"250.954764ms","start":"2026-09-07T18:16:25.473515Z","end":"2026-09-07T18:16:25.72447Z","steps":["trace[1541390384] 'read index received' (duration: 248.648358ms)","trace[1541390384] 'applied index is now lower than readState.Index' (duration: 2.305776ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:16:25.724545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.026125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T18:16:25.724569Z","caller":"traceutil/trace.go:171","msg":"trace[840131032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"251.081345ms","start":"2026-09-07T18:16:25.473478Z","end":"2026-09-07T18:16:25.724559Z","steps":["trace[840131032] 'agreement among raft nodes before linearized reading' (duration: 251.028624ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:25.724922Z","caller":"traceutil/trace.go:171","msg":"trace[93189469] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"567.439803ms","start":"2026-09-07T18:16:25.157463Z","end":"2026-09-07T18:16:25.724903Z","steps":["trace[93189469] 'process raft request' (duration: 207.594765ms)","trace[93189469] 'compare' (duration: 359.018149ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:16:25.725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:16:25.157438Z","time spent":"567.519743ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:16:25.725035Z","caller":"traceutil/trace.go:171","msg":"trace[2073071531] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"565.042386ms","start":"2026-09-07T18:16:25.159974Z","end":"2026-09-07T18:16:25.725016Z","steps":["trace[2073071531] 'process raft request' (duration: 564.404709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:16:25.725103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:16:25.15996Z","time spent":"565.107616ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:16:25.875859Z","caller":"traceutil/trace.go:171","msg":"trace[182278061] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"141.949439ms","start":"2026-09-07T18:16:25.733886Z","end":"2026-09-07T18:16:25.875836Z","steps":["trace[182278061] 'process raft request' (duration: 103.18816ms)","trace[182278061] 'compare' (duration: 38.320784ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:16:26.063577Z","caller":"traceutil/trace.go:171","msg":"trace[1261244756] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"145.367931ms","start":"2026-09-07T18:16:25.918183Z","end":"2026-09-07T18:16:26.063551Z","steps":["trace[1261244756] 'process raft request' (duration: 98.005998ms)","trace[1261244756] 'compare' (duration: 47.1675ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:16:54.554872Z","caller":"traceutil/trace.go:171","msg":"trace[1523593491] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"169.887821ms","start":"2026-09-07T18:16:54.384962Z","end":"2026-09-07T18:16:54.55485Z","steps":["trace[1523593491] 'process raft request' (duration: 169.772108ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.558963Z","caller":"traceutil/trace.go:171","msg":"trace[960239221] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"154.417928ms","start":"2026-09-07T18:16:54.404512Z","end":"2026-09-07T18:16:54.55893Z","steps":["trace[960239221] 'process raft request' (duration: 154.278585ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.559123Z","caller":"traceutil/trace.go:171","msg":"trace[401328915] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"163.674894ms","start":"2026-09-07T18:16:54.39544Z","end":"2026-09-07T18:16:54.559115Z","steps":["trace[401328915] 'process raft request' (duration: 163.192055ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.559186Z","caller":"traceutil/trace.go:171","msg":"trace[1763540060] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"147.901625ms","start":"2026-09-07T18:16:54.411278Z","end":"2026-09-07T18:16:54.55918Z","steps":["trace[1763540060] 'process raft request' (duration: 147.67256ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.559228Z","caller":"traceutil/trace.go:171","msg":"trace[243192080] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"154.500989ms","start":"2026-09-07T18:16:54.404705Z","end":"2026-09-07T18:16:54.559206Z","steps":["trace[243192080] 'process raft request' (duration: 154.166792ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.559271Z","caller":"traceutil/trace.go:171","msg":"trace[1960407282] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"140.810643ms","start":"2026-09-07T18:16:54.418436Z","end":"2026-09-07T18:16:54.559247Z","steps":["trace[1960407282] 'process raft request' (duration: 140.591079ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.559363Z","caller":"traceutil/trace.go:171","msg":"trace[1722865644] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"120.061553ms","start":"2026-09-07T18:16:54.439295Z","end":"2026-09-07T18:16:54.559357Z","steps":["trace[1722865644] 'process raft request' (duration: 119.783338ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.559324Z","caller":"traceutil/trace.go:171","msg":"trace[1611421002] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"147.15426ms","start":"2026-09-07T18:16:54.412136Z","end":"2026-09-07T18:16:54.55929Z","steps":["trace[1611421002] 'process raft request' (duration: 146.852984ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.55931Z","caller":"traceutil/trace.go:171","msg":"trace[550168564] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"150.838585ms","start":"2026-09-07T18:16:54.408442Z","end":"2026-09-07T18:16:54.559281Z","steps":["trace[550168564] 'process raft request' (duration: 150.472167ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.559686Z","caller":"traceutil/trace.go:171","msg":"trace[548411363] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"154.666003ms","start":"2026-09-07T18:16:54.404568Z","end":"2026-09-07T18:16:54.559233Z","steps":["trace[548411363] 'process raft request' (duration: 154.267845ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.8282Z","caller":"traceutil/trace.go:171","msg":"trace[263037009] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"106.775066ms","start":"2026-09-07T18:16:54.721384Z","end":"2026-09-07T18:16:54.828159Z","steps":["trace[263037009] 'process raft request' (duration: 98.463168ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:16:54.828414Z","caller":"traceutil/trace.go:171","msg":"trace[1440818782] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"109.331097ms","start":"2026-09-07T18:16:54.719054Z","end":"2026-09-07T18:16:54.828385Z","steps":["trace[1440818782] 'process raft request' (duration: 82.740171ms)","trace[1440818782] 'compare' (duration: 17.950882ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:17:00.183153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.050347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-07T18:17:00.183213Z","caller":"traceutil/trace.go:171","msg":"trace[1048568141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"271.168089ms","start":"2026-09-07T18:16:59.912031Z","end":"2026-09-07T18:17:00.183199Z","steps":["trace[1048568141] 'range keys from in-memory index tree' (duration: 270.73639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:17:00.183355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.614217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T18:17:00.183374Z","caller":"traceutil/trace.go:171","msg":"trace[1922217607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"191.70404ms","start":"2026-09-07T18:16:59.991665Z","end":"2026-09-07T18:17:00.183369Z","steps":["trace[1922217607] 'range keys from in-memory index tree' (duration: 191.540346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:17:00.183516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.213466ms","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-09-07T18:17:00.183534Z","caller":"traceutil/trace.go:171","msg":"trace[2099784252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"106.265046ms","start":"2026-09-07T18:17:00.077265Z","end":"2026-09-07T18:17:00.18353Z","steps":["trace[2099784252] 'range keys from in-memory index tree' (duration: 106.137704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:17:01.07303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.13621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:17:01.073207Z","caller":"traceutil/trace.go:171","msg":"trace[1771116470] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"114.315796ms","start":"2026-09-07T18:17:00.958872Z","end":"2026-09-07T18:17:01.073188Z","steps":["trace[1771116470] 'process raft request' (duration: 13.900501ms)","trace[1771116470] 'compare' (duration: 99.943984ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:17:01.780748Z","caller":"traceutil/trace.go:171","msg":"trace[311845145] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"202.91793ms","start":"2026-09-07T18:17:01.577797Z","end":"2026-09-07T18:17:01.780715Z","steps":["trace[311845145] 'read index received' (duration: 201.96959ms)","trace[311845145] 'applied index is now lower than readState.Index' (duration: 947.32µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:17:01.780874Z","caller":"traceutil/trace.go:171","msg":"trace[1065037809] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"537.248901ms","start":"2026-09-07T18:17:01.243601Z","end":"2026-09-07T18:17:01.78085Z","steps":["trace[1065037809] 'process raft request' (duration: 536.378343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:17:01.780889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.074614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:17:01.780999Z","caller":"traceutil/trace.go:171","msg":"trace[1890330715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"203.173136ms","start":"2026-09-07T18:17:01.577755Z","end":"2026-09-07T18:17:01.780928Z","steps":["trace[1890330715] 'agreement among raft nodes before linearized reading' (duration: 203.078044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:17:01.781008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:17:01.243576Z","time spent":"537.357313ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:17:16.272436Z","caller":"traceutil/trace.go:171","msg":"trace[865618368] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"222.610622ms","start":"2026-09-07T18:17:16.0498Z","end":"2026-09-07T18:17:16.27241Z","steps":["trace[865618368] 'process raft request' (duration: 222.464078ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:17:16.486849Z","caller":"traceutil/trace.go:171","msg":"trace[1503663749] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"203.082639ms","start":"2026-09-07T18:17:16.283746Z","end":"2026-09-07T18:17:16.486828Z","steps":["trace[1503663749] 'read index received' (duration: 202.876604ms)","trace[1503663749] 'applied index is now lower than readState.Index' (duration: 204.605µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:17:16.48691Z","caller":"traceutil/trace.go:171","msg":"trace[958301806] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"209.329854ms","start":"2026-09-07T18:17:16.27754Z","end":"2026-09-07T18:17:16.48687Z","steps":["trace[958301806] 'process raft request' (duration: 209.141379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:17:16.487083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.314314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-07T18:17:16.487144Z","caller":"traceutil/trace.go:171","msg":"trace[1951138235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"203.410607ms","start":"2026-09-07T18:17:16.283716Z","end":"2026-09-07T18:17:16.487127Z","steps":["trace[1951138235] 'agreement among raft nodes before linearized reading' (duration: 203.248543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:17:16.487163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.192666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T18:17:16.487206Z","caller":"traceutil/trace.go:171","msg":"trace[1191701437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"202.255888ms","start":"2026-09-07T18:17:16.284938Z","end":"2026-09-07T18:17:16.487194Z","steps":["trace[1191701437] 'agreement among raft nodes before linearized reading' (duration: 202.154125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:17:16.487502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.548175ms","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-09-07T18:17:16.487607Z","caller":"traceutil/trace.go:171","msg":"trace[696739515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"125.709749ms","start":"2026-09-07T18:17:16.361876Z","end":"2026-09-07T18:17:16.487586Z","steps":["trace[696739515] 'agreement among raft nodes before linearized reading' (duration: 125.34857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:38.57523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.484687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c19ee24-58e1-42e8-9bac-55f796c4584d\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-09-07T18:18:38.57523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.433025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c19ee24-58e1-42e8-9bac-55f796c4584d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-07T18:18:38.575402Z","caller":"traceutil/trace.go:171","msg":"trace[1248146263] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5c19ee24-58e1-42e8-9bac-55f796c4584d; range_end:; response_count:1; response_revision:1891; }","duration":"142.686744ms","start":"2026-09-07T18:18:38.432676Z","end":"2026-09-07T18:18:38.575363Z","steps":["trace[1248146263] 'range keys from in-memory index tree' (duration: 142.176886ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:38.575322Z","caller":"traceutil/trace.go:171","msg":"trace[1070576463] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5c19ee24-58e1-42e8-9bac-55f796c4584d; range_end:; response_count:1; response_revision:1891; }","duration":"159.596651ms","start":"2026-09-07T18:18:38.415703Z","end":"2026-09-07T18:18:38.5753Z","steps":["trace[1070576463] 'range keys from in-memory index tree' (duration: 159.211456ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:38.781025Z","caller":"traceutil/trace.go:171","msg":"trace[2726982] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"285.924746ms","start":"2026-09-07T18:18:38.495075Z","end":"2026-09-07T18:18:38.781Z","steps":["trace[2726982] 'process raft request' (duration: 285.775961ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:38.781118Z","caller":"traceutil/trace.go:171","msg":"trace[156728418] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1965; }","duration":"217.225197ms","start":"2026-09-07T18:18:38.563863Z","end":"2026-09-07T18:18:38.781088Z","steps":["trace[156728418] 'read index received' (duration: 217.213596ms)","trace[156728418] 'applied index is now lower than readState.Index' (duration: 9.591µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:18:38.781338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.461874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d31cc69d0a3f16\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-07T18:18:38.781417Z","caller":"traceutil/trace.go:171","msg":"trace[361180421] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d31cc69d0a3f16; range_end:; response_count:1; response_revision:1892; }","duration":"217.594688ms","start":"2026-09-07T18:18:38.563807Z","end":"2026-09-07T18:18:38.781402Z","steps":["trace[361180421] 'agreement among raft nodes before linearized reading' (duration: 217.401872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:38.846658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.715306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:18:38.846764Z","caller":"traceutil/trace.go:171","msg":"trace[618090592] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"261.876624ms","start":"2026-09-07T18:18:38.584871Z","end":"2026-09-07T18:18:38.846747Z","steps":["trace[618090592] 'process raft request' (duration: 261.43804ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:38.846823Z","caller":"traceutil/trace.go:171","msg":"trace[186175197] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"246.466488ms","start":"2026-09-07T18:18:38.600328Z","end":"2026-09-07T18:18:38.846795Z","steps":["trace[186175197] 'process raft request' (duration: 246.104536ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:38.847018Z","caller":"traceutil/trace.go:171","msg":"trace[360907841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1894; }","duration":"267.82175ms","start":"2026-09-07T18:18:38.578882Z","end":"2026-09-07T18:18:38.846704Z","steps":["trace[360907841] 'agreement among raft nodes before linearized reading' (duration: 267.665924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:38.847234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.565077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-07T18:18:38.847418Z","caller":"traceutil/trace.go:171","msg":"trace[1401660377] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1894; }","duration":"124.811195ms","start":"2026-09-07T18:18:38.722566Z","end":"2026-09-07T18:18:38.847377Z","steps":["trace[1401660377] 'agreement among raft nodes before linearized reading' (duration: 124.265787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:39.230703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.175414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:18:39.230989Z","caller":"traceutil/trace.go:171","msg":"trace[1751579990] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"447.11505ms","start":"2026-09-07T18:18:38.783845Z","end":"2026-09-07T18:18:39.23096Z","steps":["trace[1751579990] 'process raft request' (duration: 157.536393ms)","trace[1751579990] 'compare' (duration: 289.003377ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:18:39.23109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:18:38.783824Z","time spent":"447.217843ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:18:39.231565Z","caller":"traceutil/trace.go:171","msg":"trace[468904996] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1967; }","duration":"383.277549ms","start":"2026-09-07T18:18:38.848274Z","end":"2026-09-07T18:18:39.231552Z","steps":["trace[468904996] 'read index received' (duration: 93.02829ms)","trace[468904996] 'applied index is now lower than readState.Index' (duration: 290.248189ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:18:39.231665Z","caller":"traceutil/trace.go:171","msg":"trace[2042316271] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"441.303489ms","start":"2026-09-07T18:18:38.790296Z","end":"2026-09-07T18:18:39.2316Z","steps":["trace[2042316271] 'process raft request' (duration: 440.527274ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:39.231716Z","caller":"traceutil/trace.go:171","msg":"trace[378820298] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"394.986684ms","start":"2026-09-07T18:18:38.836715Z","end":"2026-09-07T18:18:39.231702Z","steps":["trace[378820298] 'process raft request' (duration: 394.774507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:39.23178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:18:38.836687Z","time spent":"395.053166ms","remote":"127.0.0.1:44332","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-09-07T18:18:39.231823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:18:38.790285Z","time spent":"441.452843ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:18:39.232102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.462424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"warn","ts":"2026-09-07T18:18:39.232147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.140664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-07T18:18:39.232169Z","caller":"traceutil/trace.go:171","msg":"trace[1854411220] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1897; }","duration":"379.592088ms","start":"2026-09-07T18:18:38.852562Z","end":"2026-09-07T18:18:39.232154Z","steps":["trace[1854411220] 'agreement among raft nodes before linearized reading' (duration: 379.36526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:39.232219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:18:38.852541Z","time spent":"379.66611ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10691,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-09-07T18:18:39.232201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.89732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:18:39.23218Z","caller":"traceutil/trace.go:171","msg":"trace[1158069013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1897; }","duration":"245.221558ms","start":"2026-09-07T18:18:38.986948Z","end":"2026-09-07T18:18:39.232169Z","steps":["trace[1158069013] 'agreement among raft nodes before linearized reading' (duration: 245.089844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:39.232252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.81001ms","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-09-07T18:18:39.232287Z","caller":"traceutil/trace.go:171","msg":"trace[1643078617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1897; }","duration":"239.867682ms","start":"2026-09-07T18:18:38.992412Z","end":"2026-09-07T18:18:39.23228Z","steps":["trace[1643078617] 'agreement among raft nodes before linearized reading' (duration: 239.784529ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:39.232312Z","caller":"traceutil/trace.go:171","msg":"trace[1673216439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"384.043425ms","start":"2026-09-07T18:18:38.848227Z","end":"2026-09-07T18:18:39.232271Z","steps":["trace[1673216439] 'agreement among raft nodes before linearized reading' (duration: 383.884139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:39.232323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.227732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-07T18:18:39.232356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:18:38.848211Z","time spent":"384.136957ms","remote":"127.0.0.1:44058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T18:18:39.232371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.398583ms","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-09-07T18:18:39.232407Z","caller":"traceutil/trace.go:171","msg":"trace[877557531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1897; }","duration":"250.395307ms","start":"2026-09-07T18:18:38.98199Z","end":"2026-09-07T18:18:39.232385Z","steps":["trace[877557531] 'agreement among raft nodes before linearized reading' (duration: 250.090467ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:39.232463Z","caller":"traceutil/trace.go:171","msg":"trace[875741148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1897; }","duration":"269.543518ms","start":"2026-09-07T18:18:38.962895Z","end":"2026-09-07T18:18:39.232439Z","steps":["trace[875741148] 'agreement among raft nodes before linearized reading' (duration: 269.272259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:45.06245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.302636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:18:45.062571Z","caller":"traceutil/trace.go:171","msg":"trace[687828678] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"173.193968ms","start":"2026-09-07T18:18:44.88936Z","end":"2026-09-07T18:18:45.062554Z","steps":["trace[687828678] 'read index received' (duration: 70.574531ms)","trace[687828678] 'applied index is now lower than readState.Index' (duration: 102.617786ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:18:45.062655Z","caller":"traceutil/trace.go:171","msg":"trace[1878354215] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"186.741366ms","start":"2026-09-07T18:18:44.875869Z","end":"2026-09-07T18:18:45.06261Z","steps":["trace[1878354215] 'process raft request' (duration: 84.157642ms)","trace[1878354215] 'compare' (duration: 102.15484ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:18:45.062917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.540829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-07T18:18:45.063183Z","caller":"traceutil/trace.go:171","msg":"trace[80638623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1964; }","duration":"173.85029ms","start":"2026-09-07T18:18:44.889314Z","end":"2026-09-07T18:18:45.063164Z","steps":["trace[80638623] 'agreement among raft nodes before linearized reading' (duration: 173.416805ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:18:51.81146Z","caller":"traceutil/trace.go:171","msg":"trace[1558098660] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"350.583262ms","start":"2026-09-07T18:18:51.460822Z","end":"2026-09-07T18:18:51.811405Z","steps":["trace[1558098660] 'process raft request' (duration: 305.508643ms)","trace[1558098660] 'compare' (duration: 44.776949ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:18:51.811618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:18:51.460777Z","time spent":"350.756627ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:18:51.811874Z","caller":"traceutil/trace.go:171","msg":"trace[1045808776] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2079; }","duration":"234.208948ms","start":"2026-09-07T18:18:51.577627Z","end":"2026-09-07T18:18:51.811836Z","steps":["trace[1045808776] 'read index received' (duration: 188.782107ms)","trace[1045808776] 'applied index is now lower than readState.Index' (duration: 45.423891ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:18:51.812093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.407984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:18:51.812136Z","caller":"traceutil/trace.go:171","msg":"trace[2141769338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2005; }","duration":"234.532558ms","start":"2026-09-07T18:18:51.577591Z","end":"2026-09-07T18:18:51.812124Z","steps":["trace[2141769338] 'agreement among raft nodes before linearized reading' (duration: 234.396274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:51.812336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.824735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-snhd6\" ","response":"range_response_count:1 size:6146"} {"level":"info","ts":"2026-09-07T18:18:51.813883Z","caller":"traceutil/trace.go:171","msg":"trace[1226228326] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-snhd6; range_end:; response_count:1; response_revision:2005; }","duration":"134.392407ms","start":"2026-09-07T18:18:51.679471Z","end":"2026-09-07T18:18:51.813863Z","steps":["trace[1226228326] 'agreement among raft nodes before linearized reading' (duration: 132.748203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:18:51.812524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.951179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11987"} {"level":"info","ts":"2026-09-07T18:18:51.81406Z","caller":"traceutil/trace.go:171","msg":"trace[403650436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2005; }","duration":"128.524431ms","start":"2026-09-07T18:18:51.685519Z","end":"2026-09-07T18:18:51.814043Z","steps":["trace[403650436] 'agreement among raft nodes before linearized reading' (duration: 126.854696ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:19:07.099944Z","caller":"traceutil/trace.go:171","msg":"trace[1125146007] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2181; }","duration":"148.418017ms","start":"2026-09-07T18:19:06.951503Z","end":"2026-09-07T18:19:07.099921Z","steps":["trace[1125146007] 'read index received' (duration: 148.331704ms)","trace[1125146007] 'applied index is now lower than readState.Index' (duration: 85.143µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:19:07.100225Z","caller":"traceutil/trace.go:171","msg":"trace[810297541] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"155.324193ms","start":"2026-09-07T18:19:06.944675Z","end":"2026-09-07T18:19:07.099999Z","steps":["trace[810297541] 'process raft request' (duration: 155.081895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:19:07.100248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.730578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12384"} {"level":"info","ts":"2026-09-07T18:19:07.100659Z","caller":"traceutil/trace.go:171","msg":"trace[510801662] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2103; }","duration":"149.189223ms","start":"2026-09-07T18:19:06.95145Z","end":"2026-09-07T18:19:07.100639Z","steps":["trace[510801662] 'agreement among raft nodes before linearized reading' (duration: 148.649025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:19:13.175207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.834606ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-07T18:19:13.698032Z","caller":"traceutil/trace.go:171","msg":"trace[1022966039] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"135.477912ms","start":"2026-09-07T18:19:13.562529Z","end":"2026-09-07T18:19:13.698007Z","steps":["trace[1022966039] 'read index received' (duration: 39.040395ms)","trace[1022966039] 'applied index is now lower than readState.Index' (duration: 96.436467ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:19:13.698179Z","caller":"traceutil/trace.go:171","msg":"trace[222984144] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"137.892505ms","start":"2026-09-07T18:19:13.56027Z","end":"2026-09-07T18:19:13.698163Z","steps":["trace[222984144] 'process raft request' (duration: 41.372725ms)","trace[222984144] 'compare' (duration: 96.135587ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:19:13.698565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.01966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-07T18:19:13.698608Z","caller":"traceutil/trace.go:171","msg":"trace[1768293380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2140; }","duration":"136.103093ms","start":"2026-09-07T18:19:13.562493Z","end":"2026-09-07T18:19:13.698596Z","steps":["trace[1768293380] 'agreement among raft nodes before linearized reading' (duration: 135.945298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:19:13.698694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.206222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T18:19:13.698875Z","caller":"traceutil/trace.go:171","msg":"trace[2077474611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2140; }","duration":"106.425629ms","start":"2026-09-07T18:19:13.59238Z","end":"2026-09-07T18:19:13.698805Z","steps":["trace[2077474611] 'agreement among raft nodes before linearized reading' (duration: 106.101268ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:19:13.914039Z","caller":"traceutil/trace.go:171","msg":"trace[1437051582] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"210.988444ms","start":"2026-09-07T18:19:13.703016Z","end":"2026-09-07T18:19:13.914005Z","steps":["trace[1437051582] 'process raft request' (duration: 154.152501ms)","trace[1437051582] 'compare' (duration: 56.608786ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:19:14.113458Z","caller":"traceutil/trace.go:171","msg":"trace[130572545] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2224; }","duration":"123.87654ms","start":"2026-09-07T18:19:13.989556Z","end":"2026-09-07T18:19:14.113432Z","steps":["trace[130572545] 'read index received' (duration: 45.195627ms)","trace[130572545] 'applied index is now lower than readState.Index' (duration: 78.679523ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:19:14.113522Z","caller":"traceutil/trace.go:171","msg":"trace[1288506365] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"178.327011ms","start":"2026-09-07T18:19:13.935146Z","end":"2026-09-07T18:19:14.113473Z","steps":["trace[1288506365] 'process raft request' (duration: 99.596547ms)","trace[1288506365] 'compare' (duration: 78.570399ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:19:14.113706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.141318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-07T18:19:14.113758Z","caller":"traceutil/trace.go:171","msg":"trace[52575790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2144; }","duration":"124.258192ms","start":"2026-09-07T18:19:13.989487Z","end":"2026-09-07T18:19:14.113745Z","steps":["trace[52575790] 'agreement among raft nodes before linearized reading' (duration: 124.057305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:19:14.393508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.335649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-07T18:19:14.393582Z","caller":"traceutil/trace.go:171","msg":"trace[1909419945] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2145; }","duration":"125.417652ms","start":"2026-09-07T18:19:14.268145Z","end":"2026-09-07T18:19:14.393562Z","steps":["trace[1909419945] 'range keys from in-memory index tree' (duration: 125.121992ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:19:14.920897Z","caller":"traceutil/trace.go:171","msg":"trace[11933526] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"310.829197ms","start":"2026-09-07T18:19:14.610037Z","end":"2026-09-07T18:19:14.920866Z","steps":["trace[11933526] 'process raft request' (duration: 310.680082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:19:14.921093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:19:14.610006Z","time spent":"310.975472ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:19:15.379597Z","caller":"traceutil/trace.go:171","msg":"trace[1734378291] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"177.008982ms","start":"2026-09-07T18:19:15.202556Z","end":"2026-09-07T18:19:15.379565Z","steps":["trace[1734378291] 'process raft request' (duration: 169.719553ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:04.147819Z","caller":"traceutil/trace.go:171","msg":"trace[1205372046] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"259.1705ms","start":"2026-09-07T18:21:03.888625Z","end":"2026-09-07T18:21:04.147796Z","steps":["trace[1205372046] 'process raft request' (duration: 259.047418ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:36.495075Z","caller":"traceutil/trace.go:171","msg":"trace[1969558758] linearizableReadLoop","detail":"{readStateIndex:3112; appliedIndex:3111; }","duration":"117.016439ms","start":"2026-09-07T18:21:36.378025Z","end":"2026-09-07T18:21:36.495041Z","steps":["trace[1969558758] 'read index received' (duration: 116.642482ms)","trace[1969558758] 'applied index is now lower than readState.Index' (duration: 372.267µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:21:36.495259Z","caller":"traceutil/trace.go:171","msg":"trace[2112684196] transaction","detail":"{read_only:false; response_revision:3000; number_of_response:1; }","duration":"130.85167ms","start":"2026-09-07T18:21:36.364392Z","end":"2026-09-07T18:21:36.495243Z","steps":["trace[2112684196] 'process raft request' (duration: 130.3242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:21:36.495545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.500458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T18:21:36.495613Z","caller":"traceutil/trace.go:171","msg":"trace[587608809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3000; }","duration":"117.651681ms","start":"2026-09-07T18:21:36.377944Z","end":"2026-09-07T18:21:36.495596Z","steps":["trace[587608809] 'agreement among raft nodes before linearized reading' (duration: 117.395166ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:36.528974Z","caller":"traceutil/trace.go:171","msg":"trace[779302085] transaction","detail":"{read_only:false; response_revision:3001; number_of_response:1; }","duration":"143.629921ms","start":"2026-09-07T18:21:36.385318Z","end":"2026-09-07T18:21:36.528948Z","steps":["trace[779302085] 'process raft request' (duration: 143.475858ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:37.032445Z","caller":"traceutil/trace.go:171","msg":"trace[825447714] transaction","detail":"{read_only:false; response_revision:3005; number_of_response:1; }","duration":"299.021337ms","start":"2026-09-07T18:21:36.733391Z","end":"2026-09-07T18:21:37.032412Z","steps":["trace[825447714] 'process raft request' (duration: 261.898195ms)","trace[825447714] 'compare' (duration: 36.669073ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:26:14.462561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2887} {"level":"info","ts":"2026-09-07T18:26:14.541704Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2887,"took":"77.988355ms","hash":1191988989,"current-db-size-bytes":51621888,"current-db-size":"52 MB","current-db-size-in-use-bytes":26591232,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-07T18:26:14.541765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1191988989,"revision":2887,"compact-revision":-1} {"level":"info","ts":"2026-09-07T18:26:17.602589Z","caller":"traceutil/trace.go:171","msg":"trace[1638716872] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"138.375408ms","start":"2026-09-07T18:26:17.464187Z","end":"2026-09-07T18:26:17.602562Z","steps":["trace[1638716872] 'process raft request' (duration: 138.169271ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:26:31.671262Z","caller":"traceutil/trace.go:171","msg":"trace[1578692902] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"467.708732ms","start":"2026-09-07T18:26:31.203518Z","end":"2026-09-07T18:26:31.671227Z","steps":["trace[1578692902] 'process raft request' (duration: 467.510665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:26:31.671447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:26:31.20349Z","time spent":"467.860666ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11649,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:26:31.688362Z","caller":"traceutil/trace.go:171","msg":"trace[938598324] linearizableReadLoop","detail":"{readStateIndex:5634; appliedIndex:5631; }","duration":"303.625459ms","start":"2026-09-07T18:26:31.384706Z","end":"2026-09-07T18:26:31.688332Z","steps":["trace[938598324] 'read index received' (duration: 286.279856ms)","trace[938598324] 'applied index is now lower than readState.Index' (duration: 17.344433ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:26:31.688525Z","caller":"traceutil/trace.go:171","msg":"trace[518858474] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"389.946259ms","start":"2026-09-07T18:26:31.29855Z","end":"2026-09-07T18:26:31.688496Z","steps":["trace[518858474] 'process raft request' (duration: 389.418503ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:26:31.68867Z","caller":"traceutil/trace.go:171","msg":"trace[871747895] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"382.121444ms","start":"2026-09-07T18:26:31.30653Z","end":"2026-09-07T18:26:31.688652Z","steps":["trace[871747895] 'process raft request' (duration: 381.66712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:26:31.688768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:26:31.298527Z","time spent":"390.057413ms","remote":"127.0.0.1:44332","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-09-07T18:26:31.688981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:26:31.306513Z","time spent":"382.32189ms","remote":"127.0.0.1:44332","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-09-07T18:26:31.689135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.400262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-09-07T18:26:31.689127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.716442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:26:31.689288Z","caller":"traceutil/trace.go:171","msg":"trace[1606860249] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5428; }","duration":"304.607749ms","start":"2026-09-07T18:26:31.384659Z","end":"2026-09-07T18:26:31.689267Z","steps":["trace[1606860249] 'agreement among raft nodes before linearized reading' (duration: 304.232937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:26:31.689339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:26:31.384641Z","time spent":"304.687333ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-07T18:26:31.689365Z","caller":"traceutil/trace.go:171","msg":"trace[245617348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5428; }","duration":"109.944581ms","start":"2026-09-07T18:26:31.579358Z","end":"2026-09-07T18:26:31.689302Z","steps":["trace[245617348] 'agreement among raft nodes before linearized reading' (duration: 109.518067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:26:31.906328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.687362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-07T18:26:31.906416Z","caller":"traceutil/trace.go:171","msg":"trace[766738055] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5435; }","duration":"118.821917ms","start":"2026-09-07T18:26:31.787569Z","end":"2026-09-07T18:26:31.906391Z","steps":["trace[766738055] 'agreement among raft nodes before linearized reading' (duration: 118.604921ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:26:32.262596Z","caller":"traceutil/trace.go:171","msg":"trace[1766051415] linearizableReadLoop","detail":"{readStateIndex:5647; appliedIndex:5646; }","duration":"162.907838ms","start":"2026-09-07T18:26:32.099666Z","end":"2026-09-07T18:26:32.262574Z","steps":["trace[1766051415] 'read index received' (duration: 162.708302ms)","trace[1766051415] 'applied index is now lower than readState.Index' (duration: 198.406µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:26:32.262663Z","caller":"traceutil/trace.go:171","msg":"trace[1704028760] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"264.213247ms","start":"2026-09-07T18:26:31.998425Z","end":"2026-09-07T18:26:32.262638Z","steps":["trace[1704028760] 'process raft request' (duration: 263.98632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:26:32.262879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.184006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:26:32.262959Z","caller":"traceutil/trace.go:171","msg":"trace[191587624] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5441; }","duration":"163.327681ms","start":"2026-09-07T18:26:32.099609Z","end":"2026-09-07T18:26:32.262937Z","steps":["trace[191587624] 'agreement among raft nodes before linearized reading' (duration: 163.063542ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:27:03.304663Z","caller":"traceutil/trace.go:171","msg":"trace[1751358573] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"446.991058ms","start":"2026-09-07T18:27:02.857646Z","end":"2026-09-07T18:27:03.304637Z","steps":["trace[1751358573] 'process raft request' (duration: 442.763725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:03.30482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:27:02.857626Z","time spent":"447.112053ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:30:12.660303Z","caller":"traceutil/trace.go:171","msg":"trace[1779018479] transaction","detail":"{read_only:false; response_revision:7029; number_of_response:1; }","duration":"119.696152ms","start":"2026-09-07T18:30:12.540586Z","end":"2026-09-07T18:30:12.660282Z","steps":["trace[1779018479] 'process raft request' (duration: 119.530419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:30:12.662281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.285158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-09-07T18:30:12.662466Z","caller":"traceutil/trace.go:171","msg":"trace[67985199] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:7029; }","duration":"101.508892ms","start":"2026-09-07T18:30:12.560942Z","end":"2026-09-07T18:30:12.662451Z","steps":["trace[67985199] 'agreement among raft nodes before linearized reading' (duration: 99.344346ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:31:14.469592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5160} {"level":"info","ts":"2026-09-07T18:31:14.556658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5160,"took":"84.432238ms","hash":3751925106,"current-db-size-bytes":51621888,"current-db-size":"52 MB","current-db-size-in-use-bytes":19705856,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-07T18:31:14.556723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3751925106,"revision":5160,"compact-revision":2887}