{"level":"warn","ts":"2026-10-16T00:10:56.417444Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:10:56.417535Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.113: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.113:2380","--initial-cluster=instance=https://162.253.55.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.113: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-10-16T00:10:56.417605Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:10:56.41762Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-16T00:10:56.417658Z","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-10-16T00:10:56.418472Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"]} {"level":"info","ts":"2026-10-16T00:10:56.418581Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.113: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-10-16T00:10:56.461094Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"40.491471ms"} {"level":"info","ts":"2026-10-16T00:10:56.496998Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3b717bddf98d48e3","cluster-id":"cef0f7c69f0576fc"} {"level":"info","ts":"2026-10-16T00:10:56.49714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:10:56.497181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 0"} {"level":"info","ts":"2026-10-16T00:10:56.497205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3b717bddf98d48e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:10:56.497235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 1"} {"level":"info","ts":"2026-10-16T00:10:56.497322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"warn","ts":"2026-10-16T00:10:56.538904Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:10:56.550155Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:10:56.558159Z","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-10-16T00:10:56.569343Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3b717bddf98d48e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:10:56.569581Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3b717bddf98d48e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:10:56.569786Z","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-10-16T00:10:56.569884Z","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-10-16T00:10:56.569899Z","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-10-16T00:10:56.571753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"info","ts":"2026-10-16T00:10:56.572332Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","added-peer-id":"3b717bddf98d48e3","added-peer-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-16T00:10:56.57471Z","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-10-16T00:10:56.575006Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3b717bddf98d48e3","initial-advertise-peer-urls":["https://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:10:56.57508Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:10:56.575035Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-16T00:10:56.575243Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-16T00:10:56.898093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:10:56.898172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:10:56.898209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgPreVoteResp from 3b717bddf98d48e3 at term 1"} {"level":"info","ts":"2026-10-16T00:10:56.898233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:10:56.898243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgVoteResp from 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-16T00:10:56.898261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became leader at term 2"} {"level":"info","ts":"2026-10-16T00:10:56.898402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3b717bddf98d48e3 elected leader 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-16T00:10:56.899652Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3b717bddf98d48e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.113:2379]}","request-path":"/0/members/3b717bddf98d48e3/attributes","cluster-id":"cef0f7c69f0576fc","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:10:56.899705Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:10:56.899743Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:10:56.899888Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:10:56.900164Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:10:56.900202Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:10:56.901295Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:10:56.901399Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:10:56.90144Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:10:56.903165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:10:56.905881Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.113:2379"} {"level":"info","ts":"2026-10-16T00:11:13.755395Z","caller":"traceutil/trace.go:171","msg":"trace[12238038] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"121.072795ms","start":"2026-10-16T00:11:13.63429Z","end":"2026-10-16T00:11:13.755363Z","steps":["trace[12238038] 'process raft request' (duration: 120.905431ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:51.46132Z","caller":"traceutil/trace.go:171","msg":"trace[870052600] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"334.468225ms","start":"2026-10-16T00:11:51.126815Z","end":"2026-10-16T00:11:51.461283Z","steps":["trace[870052600] 'process raft request' (duration: 271.662358ms)","trace[870052600] 'compare' (duration: 62.686005ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:11:51.461979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:51.126788Z","time spent":"334.637128ms","remote":"127.0.0.1:52840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:11:51.62077Z","caller":"traceutil/trace.go:171","msg":"trace[385326600] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:680; }","duration":"276.126037ms","start":"2026-10-16T00:11:51.344618Z","end":"2026-10-16T00:11:51.620744Z","steps":["trace[385326600] 'read index received' (duration: 53.899637ms)","trace[385326600] 'applied index is now lower than readState.Index' (duration: 222.22539ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:11:51.620902Z","caller":"traceutil/trace.go:171","msg":"trace[995973637] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"345.09093ms","start":"2026-10-16T00:11:51.27576Z","end":"2026-10-16T00:11:51.620851Z","steps":["trace[995973637] 'process raft request' (duration: 344.823214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:51.621148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:51.275741Z","time spent":"345.297775ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:11:51.621705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.044667ms","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-10-16T00:11:51.621868Z","caller":"traceutil/trace.go:171","msg":"trace[820058607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"277.268453ms","start":"2026-10-16T00:11:51.344578Z","end":"2026-10-16T00:11:51.621847Z","steps":["trace[820058607] 'agreement among raft nodes before linearized reading' (duration: 276.287691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:52.054208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.245183ms","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-10-16T00:11:52.05432Z","caller":"traceutil/trace.go:171","msg":"trace[1580748653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"116.411137ms","start":"2026-10-16T00:11:51.937884Z","end":"2026-10-16T00:11:52.054295Z","steps":["trace[1580748653] 'range keys from in-memory index tree' (duration: 116.034259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:52.054305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.095679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7dp65\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-16T00:11:52.05491Z","caller":"traceutil/trace.go:171","msg":"trace[757401632] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7dp65; range_end:; response_count:1; response_revision:656; }","duration":"174.810896ms","start":"2026-10-16T00:11:51.880073Z","end":"2026-10-16T00:11:52.054884Z","steps":["trace[757401632] 'range keys from in-memory index tree' (duration: 173.797252ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:52.16985Z","caller":"traceutil/trace.go:171","msg":"trace[1565861086] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"105.035775ms","start":"2026-10-16T00:11:52.064782Z","end":"2026-10-16T00:11:52.169818Z","steps":["trace[1565861086] 'process raft request' (duration: 104.689348ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:08.34953Z","caller":"traceutil/trace.go:171","msg":"trace[1163112691] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"101.308282ms","start":"2026-10-16T00:12:08.24819Z","end":"2026-10-16T00:12:08.349498Z","steps":["trace[1163112691] 'read index received' (duration: 101.103988ms)","trace[1163112691] 'applied index is now lower than readState.Index' (duration: 202.524µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:12:08.349575Z","caller":"traceutil/trace.go:171","msg":"trace[1639164387] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"105.782681ms","start":"2026-10-16T00:12:08.243759Z","end":"2026-10-16T00:12:08.349542Z","steps":["trace[1639164387] 'process raft request' (duration: 105.547486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:12:08.34978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.566448ms","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-10-16T00:12:08.349864Z","caller":"traceutil/trace.go:171","msg":"trace[1005525372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:820; }","duration":"101.67663ms","start":"2026-10-16T00:12:08.248167Z","end":"2026-10-16T00:12:08.349844Z","steps":["trace[1005525372] 'agreement among raft nodes before linearized reading' (duration: 101.473915ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:36.634843Z","caller":"traceutil/trace.go:171","msg":"trace[1202587829] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"147.310315ms","start":"2026-10-16T00:12:36.487507Z","end":"2026-10-16T00:12:36.634817Z","steps":["trace[1202587829] 'read index received' (duration: 147.062549ms)","trace[1202587829] 'applied index is now lower than readState.Index' (duration: 246.616µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:12:36.634933Z","caller":"traceutil/trace.go:171","msg":"trace[226173815] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"148.774709ms","start":"2026-10-16T00:12:36.486121Z","end":"2026-10-16T00:12:36.634896Z","steps":["trace[226173815] 'process raft request' (duration: 148.538093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:12:36.635122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.604262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-16T00:12:36.635163Z","caller":"traceutil/trace.go:171","msg":"trace[2042410481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1220; }","duration":"147.712904ms","start":"2026-10-16T00:12:36.487439Z","end":"2026-10-16T00:12:36.635152Z","steps":["trace[2042410481] 'agreement among raft nodes before linearized reading' (duration: 147.486779ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:21.14267Z","caller":"traceutil/trace.go:171","msg":"trace[1677510365] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"311.577536ms","start":"2026-10-16T00:13:20.831059Z","end":"2026-10-16T00:13:21.142637Z","steps":["trace[1677510365] 'process raft request' (duration: 311.32093ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:21.142627Z","caller":"traceutil/trace.go:171","msg":"trace[175180665] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"297.698999ms","start":"2026-10-16T00:13:20.844901Z","end":"2026-10-16T00:13:21.1426Z","steps":["trace[175180665] 'read index received' (duration: 297.426543ms)","trace[175180665] 'applied index is now lower than readState.Index' (duration: 271.566µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:13:21.142836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:13:20.831033Z","time spent":"311.721019ms","remote":"127.0.0.1:52586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:13:21.142954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.047627ms","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-10-16T00:13:21.143058Z","caller":"traceutil/trace.go:171","msg":"trace[548947301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1870; }","duration":"298.17513ms","start":"2026-10-16T00:13:20.844843Z","end":"2026-10-16T00:13:21.143018Z","steps":["trace[548947301] 'agreement among raft nodes before linearized reading' (duration: 297.892734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:21.143085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.846662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-10-16T00:13:21.143106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.453905ms","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-10-16T00:13:21.143123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.979694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-09d7d438-e8df-4400-94c4-06fa32ae73c4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-16T00:13:21.143157Z","caller":"traceutil/trace.go:171","msg":"trace[2089993304] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"207.051467ms","start":"2026-10-16T00:13:20.936082Z","end":"2026-10-16T00:13:21.143134Z","steps":["trace[2089993304] 'agreement among raft nodes before linearized reading' (duration: 206.772051ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:21.143169Z","caller":"traceutil/trace.go:171","msg":"trace[304387982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1870; }","duration":"202.551247ms","start":"2026-10-16T00:13:20.940596Z","end":"2026-10-16T00:13:21.143147Z","steps":["trace[304387982] 'agreement among raft nodes before linearized reading' (duration: 202.359413ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:21.143179Z","caller":"traceutil/trace.go:171","msg":"trace[732064830] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-09d7d438-e8df-4400-94c4-06fa32ae73c4; range_end:; response_count:1; response_revision:1870; }","duration":"131.086506ms","start":"2026-10-16T00:13:21.012075Z","end":"2026-10-16T00:13:21.143162Z","steps":["trace[732064830] 'agreement among raft nodes before linearized reading' (duration: 130.804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:21.502005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.451634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:13:21.502083Z","caller":"traceutil/trace.go:171","msg":"trace[1703090273] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1874; }","duration":"116.599286ms","start":"2026-10-16T00:13:21.385464Z","end":"2026-10-16T00:13:21.502064Z","steps":["trace[1703090273] 'count revisions from in-memory index tree' (duration: 116.32677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:21.502173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.454179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-09d7d438-e8df-4400-94c4-06fa32ae73c4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-16T00:13:21.502471Z","caller":"traceutil/trace.go:171","msg":"trace[678489501] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-09d7d438-e8df-4400-94c4-06fa32ae73c4; range_end:; response_count:1; response_revision:1874; }","duration":"133.760416ms","start":"2026-10-16T00:13:21.36859Z","end":"2026-10-16T00:13:21.50235Z","steps":["trace[678489501] 'range keys from in-memory index tree' (duration: 133.144412ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:30.399352Z","caller":"traceutil/trace.go:171","msg":"trace[458048518] linearizableReadLoop","detail":"{readStateIndex:2036; appliedIndex:2035; }","duration":"114.093691ms","start":"2026-10-16T00:13:30.285211Z","end":"2026-10-16T00:13:30.399304Z","steps":["trace[458048518] 'read index received' (duration: 55.384194ms)","trace[458048518] 'applied index is now lower than readState.Index' (duration: 58.707877ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:13:30.399432Z","caller":"traceutil/trace.go:171","msg":"trace[130008931] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"136.57736ms","start":"2026-10-16T00:13:30.26283Z","end":"2026-10-16T00:13:30.399407Z","steps":["trace[130008931] 'process raft request' (duration: 77.761329ms)","trace[130008931] 'compare' (duration: 58.576734ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:13:30.399566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.336137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:13:30.399662Z","caller":"traceutil/trace.go:171","msg":"trace[1282586294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1960; }","duration":"114.506182ms","start":"2026-10-16T00:13:30.285137Z","end":"2026-10-16T00:13:30.399643Z","steps":["trace[1282586294] 'agreement among raft nodes before linearized reading' (duration: 114.312057ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:14:57.058555Z","caller":"traceutil/trace.go:171","msg":"trace[1256497876] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"304.397046ms","start":"2026-10-16T00:14:56.75413Z","end":"2026-10-16T00:14:57.058527Z","steps":["trace[1256497876] 'process raft request' (duration: 283.048857ms)","trace[1256497876] 'compare' (duration: 21.098784ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:14:57.058632Z","caller":"traceutil/trace.go:171","msg":"trace[682468484] linearizableReadLoop","detail":"{readStateIndex:2589; appliedIndex:2588; }","duration":"303.69092ms","start":"2026-10-16T00:14:56.754921Z","end":"2026-10-16T00:14:57.058612Z","steps":["trace[682468484] 'read index received' (duration: 282.276129ms)","trace[682468484] 'applied index is now lower than readState.Index' (duration: 21.412711ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:14:57.058696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:14:56.754108Z","time spent":"304.519048ms","remote":"127.0.0.1:52840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:14:57.058699Z","caller":"traceutil/trace.go:171","msg":"trace[67847197] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"208.19609ms","start":"2026-10-16T00:14:56.850472Z","end":"2026-10-16T00:14:57.058668Z","steps":["trace[67847197] 'process raft request' (duration: 208.151339ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:14:57.058783Z","caller":"traceutil/trace.go:171","msg":"trace[782202641] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"238.234935ms","start":"2026-10-16T00:14:56.820491Z","end":"2026-10-16T00:14:57.058726Z","steps":["trace[782202641] 'process raft request' (duration: 238.036491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:14:57.058853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.942525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-10-16T00:14:57.058904Z","caller":"traceutil/trace.go:171","msg":"trace[1250009130] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2496; }","duration":"304.042427ms","start":"2026-10-16T00:14:56.75485Z","end":"2026-10-16T00:14:57.058893Z","steps":["trace[1250009130] 'agreement among raft nodes before linearized reading' (duration: 303.877313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:14:57.058942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:14:56.754827Z","time spent":"304.107848ms","remote":"127.0.0.1:52760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1468,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-10-16T00:14:57.058934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.788503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-16T00:14:57.059053Z","caller":"traceutil/trace.go:171","msg":"trace[1860415319] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2496; }","duration":"249.950967ms","start":"2026-10-16T00:14:56.809082Z","end":"2026-10-16T00:14:57.059033Z","steps":["trace[1860415319] 'agreement among raft nodes before linearized reading' (duration: 249.717902ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:30.546179Z","caller":"traceutil/trace.go:171","msg":"trace[125071813] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"105.830423ms","start":"2026-10-16T00:15:30.440321Z","end":"2026-10-16T00:15:30.546151Z","steps":["trace[125071813] 'process raft request' (duration: 105.677309ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:30.680215Z","caller":"traceutil/trace.go:171","msg":"trace[436404189] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"175.372996ms","start":"2026-10-16T00:15:30.504751Z","end":"2026-10-16T00:15:30.680124Z","steps":["trace[436404189] 'process raft request' (duration: 131.524094ms)","trace[436404189] 'compare' (duration: 43.464634ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:18:49.156299Z","caller":"traceutil/trace.go:171","msg":"trace[1687747079] transaction","detail":"{read_only:false; response_revision:3905; number_of_response:1; }","duration":"130.431647ms","start":"2026-10-16T00:18:49.025837Z","end":"2026-10-16T00:18:49.156268Z","steps":["trace[1687747079] 'process raft request' (duration: 130.210341ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:16.061467Z","caller":"traceutil/trace.go:171","msg":"trace[391176734] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"107.478945ms","start":"2026-10-16T00:20:15.95394Z","end":"2026-10-16T00:20:16.061419Z","steps":["trace[391176734] 'process raft request' (duration: 40.501631ms)","trace[391176734] 'compare' (duration: 66.747929ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:20:20.377078Z","caller":"traceutil/trace.go:171","msg":"trace[354090422] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"126.288349ms","start":"2026-10-16T00:20:20.250765Z","end":"2026-10-16T00:20:20.377053Z","steps":["trace[354090422] 'process raft request' (duration: 126.125266ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:20.537752Z","caller":"traceutil/trace.go:171","msg":"trace[1755395299] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5326; }","duration":"237.68802ms","start":"2026-10-16T00:20:20.300036Z","end":"2026-10-16T00:20:20.537724Z","steps":["trace[1755395299] 'read index received' (duration: 76.929942ms)","trace[1755395299] 'applied index is now lower than readState.Index' (duration: 160.756778ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:20:20.537822Z","caller":"traceutil/trace.go:171","msg":"trace[1619551834] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"246.201168ms","start":"2026-10-16T00:20:20.291591Z","end":"2026-10-16T00:20:20.537792Z","steps":["trace[1619551834] 'process raft request' (duration: 245.960263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:20.538043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.973556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd6a3716-b426-4251-b58c-985586e3b2f5\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-16T00:20:20.538083Z","caller":"traceutil/trace.go:171","msg":"trace[630773819] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd6a3716-b426-4251-b58c-985586e3b2f5; range_end:; response_count:1; response_revision:5135; }","duration":"238.072429ms","start":"2026-10-16T00:20:20.299997Z","end":"2026-10-16T00:20:20.53807Z","steps":["trace[630773819] 'agreement among raft nodes before linearized reading' (duration: 237.861764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:20.538441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.480347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T00:20:20.538468Z","caller":"traceutil/trace.go:171","msg":"trace[1004958180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5135; }","duration":"143.571999ms","start":"2026-10-16T00:20:20.394888Z","end":"2026-10-16T00:20:20.53846Z","steps":["trace[1004958180] 'agreement among raft nodes before linearized reading' (duration: 143.484447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:20.538579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.767286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:821555"} {"level":"info","ts":"2026-10-16T00:20:20.538646Z","caller":"traceutil/trace.go:171","msg":"trace[608636493] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5135; }","duration":"224.844088ms","start":"2026-10-16T00:20:20.313783Z","end":"2026-10-16T00:20:20.538627Z","steps":["trace[608636493] 'agreement among raft nodes before linearized reading' (duration: 224.229245ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:34.772573Z","caller":"traceutil/trace.go:171","msg":"trace[2035995311] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"121.068964ms","start":"2026-10-16T00:20:34.651467Z","end":"2026-10-16T00:20:34.772536Z","steps":["trace[2035995311] 'process raft request' (duration: 81.509114ms)","trace[2035995311] 'compare' (duration: 39.372776ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:20:34.796148Z","caller":"traceutil/trace.go:171","msg":"trace[571839167] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"143.699483ms","start":"2026-10-16T00:20:34.652428Z","end":"2026-10-16T00:20:34.796127Z","steps":["trace[571839167] 'process raft request' (duration: 143.537569ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:34.796311Z","caller":"traceutil/trace.go:171","msg":"trace[1359957944] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"135.971343ms","start":"2026-10-16T00:20:34.660313Z","end":"2026-10-16T00:20:34.796284Z","steps":["trace[1359957944] 'process raft request' (duration: 135.762099ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:35.494759Z","caller":"traceutil/trace.go:171","msg":"trace[1088116685] linearizableReadLoop","detail":"{readStateIndex:5592; appliedIndex:5591; }","duration":"162.349593ms","start":"2026-10-16T00:20:35.332387Z","end":"2026-10-16T00:20:35.494737Z","steps":["trace[1088116685] 'read index received' (duration: 162.125078ms)","trace[1088116685] 'applied index is now lower than readState.Index' (duration: 223.625µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:20:35.494867Z","caller":"traceutil/trace.go:171","msg":"trace[402896311] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"185.144344ms","start":"2026-10-16T00:20:35.3097Z","end":"2026-10-16T00:20:35.494844Z","steps":["trace[402896311] 'process raft request' (duration: 184.884508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:35.494929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.498736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:20:35.494987Z","caller":"traceutil/trace.go:171","msg":"trace[196984674] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5390; }","duration":"162.632709ms","start":"2026-10-16T00:20:35.332336Z","end":"2026-10-16T00:20:35.494968Z","steps":["trace[196984674] 'agreement among raft nodes before linearized reading' (duration: 162.513406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:35.495092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.975045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-16T00:20:35.495144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.951721ms","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-10-16T00:20:35.495175Z","caller":"traceutil/trace.go:171","msg":"trace[402865659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5390; }","duration":"112.112427ms","start":"2026-10-16T00:20:35.383041Z","end":"2026-10-16T00:20:35.495154Z","steps":["trace[402865659] 'agreement among raft nodes before linearized reading' (duration: 111.873252ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:35.495215Z","caller":"traceutil/trace.go:171","msg":"trace[401812954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5390; }","duration":"111.061524ms","start":"2026-10-16T00:20:35.384133Z","end":"2026-10-16T00:20:35.495195Z","steps":["trace[401812954] 'agreement among raft nodes before linearized reading' (duration: 110.827779ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:43.604774Z","caller":"traceutil/trace.go:171","msg":"trace[1857739393] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"130.986113ms","start":"2026-10-16T00:20:43.47376Z","end":"2026-10-16T00:20:43.604747Z","steps":["trace[1857739393] 'process raft request' (duration: 130.8353ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:57.071523Z","caller":"traceutil/trace.go:171","msg":"trace[1901294456] linearizableReadLoop","detail":"{readStateIndex:5920; appliedIndex:5919; }","duration":"148.750013ms","start":"2026-10-16T00:20:56.922748Z","end":"2026-10-16T00:20:57.071498Z","steps":["trace[1901294456] 'read index received' (duration: 148.486158ms)","trace[1901294456] 'applied index is now lower than readState.Index' (duration: 262.625µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:20:57.071579Z","caller":"traceutil/trace.go:171","msg":"trace[2099118604] transaction","detail":"{read_only:false; response_revision:5711; number_of_response:1; }","duration":"165.272917ms","start":"2026-10-16T00:20:56.906278Z","end":"2026-10-16T00:20:57.071551Z","steps":["trace[2099118604] 'process raft request' (duration: 165.050863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:57.071797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.034379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T00:20:57.071839Z","caller":"traceutil/trace.go:171","msg":"trace[1437153081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5711; }","duration":"149.123171ms","start":"2026-10-16T00:20:56.922705Z","end":"2026-10-16T00:20:57.071828Z","steps":["trace[1437153081] 'agreement among raft nodes before linearized reading' (duration: 148.953088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:57.071936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.246167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-10-16T00:20:57.071995Z","caller":"traceutil/trace.go:171","msg":"trace[1552919885] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5711; }","duration":"116.32146ms","start":"2026-10-16T00:20:56.955655Z","end":"2026-10-16T00:20:57.071977Z","steps":["trace[1552919885] 'agreement among raft nodes before linearized reading' (duration: 116.152226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:57.072076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.508958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-16T00:20:57.072217Z","caller":"traceutil/trace.go:171","msg":"trace[1342946370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5711; }","duration":"118.688141ms","start":"2026-10-16T00:20:56.953506Z","end":"2026-10-16T00:20:57.072194Z","steps":["trace[1342946370] 'agreement among raft nodes before linearized reading' (duration: 118.269992ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:57.307926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2819} {"level":"info","ts":"2026-10-16T00:20:57.562785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2819,"took":"253.357906ms","hash":2255158033,"current-db-size-bytes":56492032,"current-db-size":"56 MB","current-db-size-in-use-bytes":31469568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:20:57.562855Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2255158033,"revision":2819,"compact-revision":-1} {"level":"info","ts":"2026-10-16T00:25:57.316238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5718} {"level":"info","ts":"2026-10-16T00:25:57.408038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5718,"took":"89.873427ms","hash":2363777428,"current-db-size-bytes":56492032,"current-db-size":"56 MB","current-db-size-in-use-bytes":25899008,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T00:25:57.408115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2363777428,"revision":5718,"compact-revision":2819} {"level":"info","ts":"2026-10-16T00:26:52.052952Z","caller":"traceutil/trace.go:171","msg":"trace[1502010523] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"212.130295ms","start":"2026-10-16T00:26:51.840777Z","end":"2026-10-16T00:26:52.052907Z","steps":["trace[1502010523] 'process raft request' (duration: 200.736145ms)","trace[1502010523] 'compare' (duration: 10.410909ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:03.95078Z","caller":"traceutil/trace.go:171","msg":"trace[181000892] linearizableReadLoop","detail":"{readStateIndex:8761; appliedIndex:8760; }","duration":"120.423727ms","start":"2026-10-16T00:27:03.830326Z","end":"2026-10-16T00:27:03.95075Z","steps":["trace[181000892] 'read index received' (duration: 22.982294ms)","trace[181000892] 'applied index is now lower than readState.Index' (duration: 97.439103ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:03.951391Z","caller":"traceutil/trace.go:171","msg":"trace[2046819913] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"352.634802ms","start":"2026-10-16T00:27:03.598732Z","end":"2026-10-16T00:27:03.951367Z","steps":["trace[2046819913] 'process raft request' (duration: 254.644907ms)","trace[2046819913] 'compare' (duration: 96.970362ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:27:03.951525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:27:03.598716Z","time spent":"352.737084ms","remote":"127.0.0.1:52840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:27:03.9516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.268156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:27:03.951664Z","caller":"traceutil/trace.go:171","msg":"trace[154086356] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8462; }","duration":"121.373648ms","start":"2026-10-16T00:27:03.830272Z","end":"2026-10-16T00:27:03.951646Z","steps":["trace[154086356] 'agreement among raft nodes before linearized reading' (duration: 120.99768ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:04.642439Z","caller":"traceutil/trace.go:171","msg":"trace[1046489186] linearizableReadLoop","detail":"{readStateIndex:8765; appliedIndex:8764; }","duration":"357.976188ms","start":"2026-10-16T00:27:04.284441Z","end":"2026-10-16T00:27:04.642417Z","steps":["trace[1046489186] 'read index received' (duration: 357.825545ms)","trace[1046489186] 'applied index is now lower than readState.Index' (duration: 149.313µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:04.642463Z","caller":"traceutil/trace.go:171","msg":"trace[1776176350] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"368.118271ms","start":"2026-10-16T00:27:04.27433Z","end":"2026-10-16T00:27:04.642448Z","steps":["trace[1776176350] 'process raft request' (duration: 367.926457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:04.642543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.08831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:27:04.642568Z","caller":"traceutil/trace.go:171","msg":"trace[1559128513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8466; }","duration":"358.144541ms","start":"2026-10-16T00:27:04.284417Z","end":"2026-10-16T00:27:04.642561Z","steps":["trace[1559128513] 'agreement among raft nodes before linearized reading' (duration: 358.07346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:04.64259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:27:04.284411Z","time spent":"358.174002ms","remote":"127.0.0.1:52554","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-10-16T00:27:04.642593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:27:04.274316Z","time spent":"368.226593ms","remote":"127.0.0.1:52840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:27:04.642763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.442287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-75cd576945-8j4cb.18dedaeaf83e873d\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-16T00:27:04.642787Z","caller":"traceutil/trace.go:171","msg":"trace[768681681] range","detail":"{range_begin:/registry/events/openstack/barbican-api-75cd576945-8j4cb.18dedaeaf83e873d; range_end:; response_count:1; response_revision:8466; }","duration":"178.505208ms","start":"2026-10-16T00:27:04.464275Z","end":"2026-10-16T00:27:04.642781Z","steps":["trace[768681681] 'agreement among raft nodes before linearized reading' (duration: 178.401976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:04.642813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.072939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-16T00:27:04.642902Z","caller":"traceutil/trace.go:171","msg":"trace[2129762161] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8466; }","duration":"305.209132ms","start":"2026-10-16T00:27:04.33767Z","end":"2026-10-16T00:27:04.64288Z","steps":["trace[2129762161] 'agreement among raft nodes before linearized reading' (duration: 304.948407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:04.642951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:27:04.337651Z","time spent":"305.290866ms","remote":"127.0.0.1:52840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-16T00:27:05.263548Z","caller":"traceutil/trace.go:171","msg":"trace[1386031418] transaction","detail":"{read_only:false; response_revision:8470; number_of_response:1; }","duration":"139.635658ms","start":"2026-10-16T00:27:05.123877Z","end":"2026-10-16T00:27:05.263512Z","steps":["trace[1386031418] 'process raft request' (duration: 124.18468ms)","trace[1386031418] 'compare' (duration: 15.301455ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:30.882398Z","caller":"traceutil/trace.go:171","msg":"trace[658221123] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"116.182384ms","start":"2026-10-16T00:27:30.766184Z","end":"2026-10-16T00:27:30.882366Z","steps":["trace[658221123] 'process raft request' (duration: 115.950019ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:31.022918Z","caller":"traceutil/trace.go:171","msg":"trace[1299191886] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"136.216142ms","start":"2026-10-16T00:27:30.886679Z","end":"2026-10-16T00:27:31.022895Z","steps":["trace[1299191886] 'process raft request' (duration: 136.08608ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:31.023458Z","caller":"traceutil/trace.go:171","msg":"trace[1693244423] linearizableReadLoop","detail":"{readStateIndex:8963; appliedIndex:8962; }","duration":"139.167516ms","start":"2026-10-16T00:27:30.884269Z","end":"2026-10-16T00:27:31.023437Z","steps":["trace[1693244423] 'read index received' (duration: 116.994032ms)","trace[1693244423] 'applied index is now lower than readState.Index' (duration: 22.172004ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:27:31.023643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.349331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:27:31.023688Z","caller":"traceutil/trace.go:171","msg":"trace[1950269218] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8658; }","duration":"139.434463ms","start":"2026-10-16T00:27:30.884238Z","end":"2026-10-16T00:27:31.023672Z","steps":["trace[1950269218] 'agreement among raft nodes before linearized reading' (duration: 139.332951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:31.023776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.918846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:27:31.023836Z","caller":"traceutil/trace.go:171","msg":"trace[132879968] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8658; }","duration":"124.993137ms","start":"2026-10-16T00:27:30.898828Z","end":"2026-10-16T00:27:31.023821Z","steps":["trace[132879968] 'agreement among raft nodes before linearized reading' (duration: 124.858244ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:45.045195Z","caller":"traceutil/trace.go:171","msg":"trace[1211682859] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"325.823216ms","start":"2026-10-16T00:27:44.719341Z","end":"2026-10-16T00:27:45.045164Z","steps":["trace[1211682859] 'process raft request' (duration: 325.53339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:45.045354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:27:44.719328Z","time spent":"325.944078ms","remote":"127.0.0.1:52750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4630,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:27:45.643153Z","caller":"traceutil/trace.go:171","msg":"trace[1917053284] transaction","detail":"{read_only:false; response_revision:8778; number_of_response:1; }","duration":"139.995505ms","start":"2026-10-16T00:27:45.503137Z","end":"2026-10-16T00:27:45.643132Z","steps":["trace[1917053284] 'process raft request' (duration: 139.822072ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:45.825161Z","caller":"traceutil/trace.go:171","msg":"trace[2124042765] transaction","detail":"{read_only:false; response_revision:8779; number_of_response:1; }","duration":"131.329536ms","start":"2026-10-16T00:27:45.693786Z","end":"2026-10-16T00:27:45.825116Z","steps":["trace[2124042765] 'process raft request' (duration: 84.670155ms)","trace[2124042765] 'compare' (duration: 46.447426ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:46.326997Z","caller":"traceutil/trace.go:171","msg":"trace[1162887959] linearizableReadLoop","detail":"{readStateIndex:9093; appliedIndex:9092; }","duration":"117.755648ms","start":"2026-10-16T00:27:46.209216Z","end":"2026-10-16T00:27:46.326972Z","steps":["trace[1162887959] 'read index received' (duration: 20.331476ms)","trace[1162887959] 'applied index is now lower than readState.Index' (duration: 97.423112ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:46.327134Z","caller":"traceutil/trace.go:171","msg":"trace[1252994878] transaction","detail":"{read_only:false; response_revision:8782; number_of_response:1; }","duration":"142.178162ms","start":"2026-10-16T00:27:46.184943Z","end":"2026-10-16T00:27:46.327122Z","steps":["trace[1252994878] 'process raft request' (duration: 91.590555ms)","trace[1252994878] 'compare' (duration: 50.295661ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:27:46.328389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.15005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-16T00:27:46.328465Z","caller":"traceutil/trace.go:171","msg":"trace[737278622] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8782; }","duration":"119.261952ms","start":"2026-10-16T00:27:46.209183Z","end":"2026-10-16T00:27:46.328445Z","steps":["trace[737278622] 'agreement among raft nodes before linearized reading' (duration: 118.173428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:46.372484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.871509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7gsd5\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-10-16T00:27:46.37258Z","caller":"traceutil/trace.go:171","msg":"trace[365106315] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7gsd5; range_end:; response_count:1; response_revision:8785; }","duration":"122.009702ms","start":"2026-10-16T00:27:46.250548Z","end":"2026-10-16T00:27:46.372557Z","steps":["trace[365106315] 'agreement among raft nodes before linearized reading' (duration: 121.738766ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:28:02.481223Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002430.wal"} {"level":"info","ts":"2026-10-16T00:29:00.758318Z","caller":"traceutil/trace.go:171","msg":"trace[559452039] transaction","detail":"{read_only:false; response_revision:9457; number_of_response:1; }","duration":"126.064193ms","start":"2026-10-16T00:29:00.63222Z","end":"2026-10-16T00:29:00.758285Z","steps":["trace[559452039] 'process raft request' (duration: 59.783295ms)","trace[559452039] 'compare' (duration: 65.91057ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:29:18.720962Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:29:18.729692Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T00:29:18.729829Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T00:30:57.324304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7897} {"level":"info","ts":"2026-10-16T00:30:57.402846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7897,"took":"76.470941ms","hash":4105610580,"current-db-size-bytes":56492032,"current-db-size":"56 MB","current-db-size-in-use-bytes":28811264,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-16T00:30:57.402923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4105610580,"revision":7897,"compact-revision":5718} {"level":"warn","ts":"2026-10-16T00:32:38.705205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.578589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m2lhk\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-16T00:32:38.705312Z","caller":"traceutil/trace.go:171","msg":"trace[269063484] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m2lhk; range_end:; response_count:1; response_revision:11368; }","duration":"134.732651ms","start":"2026-10-16T00:32:38.570556Z","end":"2026-10-16T00:32:38.705289Z","steps":["trace[269063484] 'range keys from in-memory index tree' (duration: 134.433345ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:32:39.049679Z","caller":"traceutil/trace.go:171","msg":"trace[644353808] transaction","detail":"{read_only:false; response_revision:11370; number_of_response:1; }","duration":"181.585642ms","start":"2026-10-16T00:32:38.868057Z","end":"2026-10-16T00:32:39.049643Z","steps":["trace[644353808] 'process raft request' (duration: 119.009567ms)","trace[644353808] 'compare' (duration: 62.404401ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:32:39.114453Z","caller":"traceutil/trace.go:171","msg":"trace[1784299179] transaction","detail":"{read_only:false; response_revision:11371; number_of_response:1; }","duration":"208.793839ms","start":"2026-10-16T00:32:38.905638Z","end":"2026-10-16T00:32:39.114432Z","steps":["trace[1784299179] 'process raft request' (duration: 208.617895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:32:39.409847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.686615ms","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-10-16T00:32:39.409938Z","caller":"traceutil/trace.go:171","msg":"trace[929564157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11373; }","duration":"116.811198ms","start":"2026-10-16T00:32:39.293103Z","end":"2026-10-16T00:32:39.409914Z","steps":["trace[929564157] 'range keys from in-memory index tree' (duration: 116.511842ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:35:57.332589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10390} {"level":"info","ts":"2026-10-16T00:35:57.428433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10390,"took":"93.568254ms","hash":4086720942,"current-db-size-bytes":56492032,"current-db-size":"56 MB","current-db-size-in-use-bytes":27213824,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T00:35:57.428502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4086720942,"revision":10390,"compact-revision":7897} {"level":"info","ts":"2026-10-16T00:38:30.19163Z","caller":"traceutil/trace.go:171","msg":"trace[922868181] linearizableReadLoop","detail":"{readStateIndex:14239; appliedIndex:14238; }","duration":"106.932614ms","start":"2026-10-16T00:38:30.084682Z","end":"2026-10-16T00:38:30.191614Z","steps":["trace[922868181] 'read index received' (duration: 106.770129ms)","trace[922868181] 'applied index is now lower than readState.Index' (duration: 161.935µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:38:30.191654Z","caller":"traceutil/trace.go:171","msg":"trace[1247632023] transaction","detail":"{read_only:false; response_revision:13768; number_of_response:1; }","duration":"115.267227ms","start":"2026-10-16T00:38:30.076374Z","end":"2026-10-16T00:38:30.191642Z","steps":["trace[1247632023] 'process raft request' (duration: 115.127553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:38:30.191721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.029216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:38:30.191867Z","caller":"traceutil/trace.go:171","msg":"trace[367223131] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:13768; }","duration":"107.21977ms","start":"2026-10-16T00:38:30.084641Z","end":"2026-10-16T00:38:30.191861Z","steps":["trace[367223131] 'agreement among raft nodes before linearized reading' (duration: 107.040556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:05.502704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.197228ms","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-10-16T00:39:05.502815Z","caller":"traceutil/trace.go:171","msg":"trace[1479663959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13971; }","duration":"162.32627ms","start":"2026-10-16T00:39:05.340447Z","end":"2026-10-16T00:39:05.502774Z","steps":["trace[1479663959] 'range keys from in-memory index tree' (duration: 162.052064ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:39:06.28213Z","caller":"traceutil/trace.go:171","msg":"trace[260814712] transaction","detail":"{read_only:false; response_revision:13975; number_of_response:1; }","duration":"305.502061ms","start":"2026-10-16T00:39:05.976598Z","end":"2026-10-16T00:39:06.2821Z","steps":["trace[260814712] 'process raft request' (duration: 305.254656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:06.28234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:39:05.976572Z","time spent":"305.666035ms","remote":"127.0.0.1:52840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:39:06.293319Z","caller":"traceutil/trace.go:171","msg":"trace[118968865] linearizableReadLoop","detail":"{readStateIndex:14456; appliedIndex:14454; }","duration":"190.625654ms","start":"2026-10-16T00:39:06.102654Z","end":"2026-10-16T00:39:06.293279Z","steps":["trace[118968865] 'read index received' (duration: 179.190533ms)","trace[118968865] 'applied index is now lower than readState.Index' (duration: 11.433141ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:39:06.29366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.459215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-d9q47\" ","response":"range_response_count:1 size:12364"} {"level":"warn","ts":"2026-10-16T00:39:06.293656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.848849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:39:06.29371Z","caller":"traceutil/trace.go:171","msg":"trace[1915051840] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-d9q47; range_end:; response_count:1; response_revision:13975; }","duration":"147.568647ms","start":"2026-10-16T00:39:06.146125Z","end":"2026-10-16T00:39:06.293694Z","steps":["trace[1915051840] 'agreement among raft nodes before linearized reading' (duration: 147.371273ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:39:06.29378Z","caller":"traceutil/trace.go:171","msg":"trace[1696376111] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:13975; }","duration":"191.113825ms","start":"2026-10-16T00:39:06.102609Z","end":"2026-10-16T00:39:06.293723Z","steps":["trace[1696376111] 'agreement among raft nodes before linearized reading' (duration: 190.815528ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:57.513923Z","caller":"traceutil/trace.go:171","msg":"trace[1678807233] transaction","detail":"{read_only:false; response_revision:15106; number_of_response:1; }","duration":"131.960813ms","start":"2026-10-16T00:40:57.381932Z","end":"2026-10-16T00:40:57.513893Z","steps":["trace[1678807233] 'process raft request' (duration: 102.786391ms)","trace[1678807233] 'compare' (duration: 29.018619ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:40:57.514245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.330074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-16T00:40:57.514285Z","caller":"traceutil/trace.go:171","msg":"trace[450470538] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15106; }","duration":"148.430905ms","start":"2026-10-16T00:40:57.365844Z","end":"2026-10-16T00:40:57.514275Z","steps":["trace[450470538] 'agreement among raft nodes before linearized reading' (duration: 148.258662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:57.514414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.721026ms","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-10-16T00:40:57.514491Z","caller":"traceutil/trace.go:171","msg":"trace[1417125640] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15106; }","duration":"150.842439ms","start":"2026-10-16T00:40:57.363633Z","end":"2026-10-16T00:40:57.514475Z","steps":["trace[1417125640] 'agreement among raft nodes before linearized reading' (duration: 150.673645ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:57.593174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12662} {"level":"info","ts":"2026-10-16T00:40:57.593326Z","caller":"traceutil/trace.go:171","msg":"trace[1160031881] transaction","detail":"{read_only:false; response_revision:15107; number_of_response:1; }","duration":"175.159453ms","start":"2026-10-16T00:40:57.418152Z","end":"2026-10-16T00:40:57.593311Z","steps":["trace[1160031881] 'process raft request' (duration: 174.993959ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:57.593433Z","caller":"traceutil/trace.go:171","msg":"trace[1829222187] compact","detail":"{revision:12662; response_revision:15107; }","duration":"177.855883ms","start":"2026-10-16T00:40:57.41555Z","end":"2026-10-16T00:40:57.593405Z","steps":["trace[1829222187] 'process raft request' (duration: 148.993758ms)","trace[1829222187] 'check and update compact revision' (duration: 28.504167ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:40:57.593573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.407784ms","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-10-16T00:40:57.593651Z","caller":"traceutil/trace.go:171","msg":"trace[271625329] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15107; }","duration":"108.522067ms","start":"2026-10-16T00:40:57.485112Z","end":"2026-10-16T00:40:57.593634Z","steps":["trace[271625329] 'agreement among raft nodes before linearized reading' (duration: 108.259981ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:57.904178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12662,"took":"307.72621ms","hash":1079291376,"current-db-size-bytes":56492032,"current-db-size":"56 MB","current-db-size-in-use-bytes":29900800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T00:40:57.904383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1079291376,"revision":12662,"compact-revision":10390} {"level":"info","ts":"2026-10-16T00:45:57.600513Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15105} {"level":"info","ts":"2026-10-16T00:45:57.701555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15105,"took":"98.175361ms","hash":1680888600,"current-db-size-bytes":56492032,"current-db-size":"56 MB","current-db-size-in-use-bytes":31449088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T00:45:57.70162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1680888600,"revision":15105,"compact-revision":12662} {"level":"warn","ts":"2026-10-16T00:46:18.148502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.754326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:46:18.148608Z","caller":"traceutil/trace.go:171","msg":"trace[1458627386] linearizableReadLoop","detail":"{readStateIndex:18137; appliedIndex:18136; }","duration":"128.825139ms","start":"2026-10-16T00:46:18.019767Z","end":"2026-10-16T00:46:18.148592Z","steps":["trace[1458627386] 'read index received' (duration: 41.89µs)","trace[1458627386] 'applied index is now lower than readState.Index' (duration: 128.781419ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:46:18.148638Z","caller":"traceutil/trace.go:171","msg":"trace[1444844664] transaction","detail":"{read_only:false; response_revision:17539; number_of_response:1; }","duration":"222.947053ms","start":"2026-10-16T00:46:17.925659Z","end":"2026-10-16T00:46:18.148606Z","steps":["trace[1444844664] 'process raft request' (duration: 25.822809ms)","trace[1444844664] 'compare' (duration: 196.543431ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:46:18.14874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.967853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:46:18.148782Z","caller":"traceutil/trace.go:171","msg":"trace[1778049778] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:17539; }","duration":"129.045883ms","start":"2026-10-16T00:46:18.019726Z","end":"2026-10-16T00:46:18.148772Z","steps":["trace[1778049778] 'agreement among raft nodes before linearized reading' (duration: 128.950421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:46:18.148927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.774109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T00:46:18.14901Z","caller":"traceutil/trace.go:171","msg":"trace[1535048286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17539; }","duration":"124.895052ms","start":"2026-10-16T00:46:18.024094Z","end":"2026-10-16T00:46:18.148989Z","steps":["trace[1535048286] 'agreement among raft nodes before linearized reading' (duration: 124.670757ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:46:23.134993Z","caller":"traceutil/trace.go:171","msg":"trace[2001355573] transaction","detail":"{read_only:false; response_revision:17578; number_of_response:1; }","duration":"103.190534ms","start":"2026-10-16T00:46:23.031768Z","end":"2026-10-16T00:46:23.134958Z","steps":["trace[2001355573] 'process raft request' (duration: 102.98283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:46:23.554451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.726536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:46:23.554567Z","caller":"traceutil/trace.go:171","msg":"trace[473817962] linearizableReadLoop","detail":"{readStateIndex:18179; appliedIndex:18178; }","duration":"269.611361ms","start":"2026-10-16T00:46:23.284934Z","end":"2026-10-16T00:46:23.554545Z","steps":["trace[473817962] 'read index received' (duration: 121.702891ms)","trace[473817962] 'applied index is now lower than readState.Index' (duration: 147.90672ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:46:23.554811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.691316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:46:23.55487Z","caller":"traceutil/trace.go:171","msg":"trace[1296536892] transaction","detail":"{read_only:false; response_revision:17580; number_of_response:1; }","duration":"275.756917ms","start":"2026-10-16T00:46:23.279076Z","end":"2026-10-16T00:46:23.554833Z","steps":["trace[1296536892] 'process raft request' (duration: 127.570071ms)","trace[1296536892] 'compare' (duration: 147.631874ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:46:23.554929Z","caller":"traceutil/trace.go:171","msg":"trace[41505065] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17580; }","duration":"231.842939ms","start":"2026-10-16T00:46:23.323043Z","end":"2026-10-16T00:46:23.554886Z","steps":["trace[41505065] 'agreement among raft nodes before linearized reading' (duration: 231.674406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:46:23.554754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.806436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:46:23.555022Z","caller":"traceutil/trace.go:171","msg":"trace[71764520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17580; }","duration":"270.091381ms","start":"2026-10-16T00:46:23.284887Z","end":"2026-10-16T00:46:23.554979Z","steps":["trace[71764520] 'agreement among raft nodes before linearized reading' (duration: 269.770264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:46:23.944014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.814187ms","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-10-16T00:46:23.944093Z","caller":"traceutil/trace.go:171","msg":"trace[2084184329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17580; }","duration":"245.95249ms","start":"2026-10-16T00:46:23.698122Z","end":"2026-10-16T00:46:23.944075Z","steps":["trace[2084184329] 'range keys from in-memory index tree' (duration: 245.589443ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:49:20.829653Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:49:20.835839Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T00:49:20.836061Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T00:50:57.607783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17407} {"level":"info","ts":"2026-10-16T00:50:57.706407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17407,"took":"95.140672ms","hash":2293680588,"current-db-size-bytes":56492032,"current-db-size":"56 MB","current-db-size-in-use-bytes":35610624,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T00:50:57.706478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2293680588,"revision":17407,"compact-revision":15105} {"level":"info","ts":"2026-10-16T00:53:51.116622Z","caller":"traceutil/trace.go:171","msg":"trace[1698768497] transaction","detail":"{read_only:false; response_revision:21294; number_of_response:1; }","duration":"113.48998ms","start":"2026-10-16T00:53:51.003101Z","end":"2026-10-16T00:53:51.116591Z","steps":["trace[1698768497] 'process raft request' (duration: 109.824339ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:51.405384Z","caller":"traceutil/trace.go:171","msg":"trace[2096053482] linearizableReadLoop","detail":"{readStateIndex:22009; appliedIndex:22008; }","duration":"120.741611ms","start":"2026-10-16T00:53:51.284592Z","end":"2026-10-16T00:53:51.405334Z","steps":["trace[2096053482] 'read index received' (duration: 120.503665ms)","trace[2096053482] 'applied index is now lower than readState.Index' (duration: 236.826µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:53:51.405531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.891714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:53:51.405569Z","caller":"traceutil/trace.go:171","msg":"trace[2034621634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21295; }","duration":"121.000256ms","start":"2026-10-16T00:53:51.284557Z","end":"2026-10-16T00:53:51.405558Z","steps":["trace[2034621634] 'agreement among raft nodes before linearized reading' (duration: 120.895424ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:51.405527Z","caller":"traceutil/trace.go:171","msg":"trace[979560422] transaction","detail":"{read_only:false; response_revision:21295; number_of_response:1; }","duration":"284.238071ms","start":"2026-10-16T00:53:51.121171Z","end":"2026-10-16T00:53:51.405409Z","steps":["trace[979560422] 'process raft request' (duration: 284.019136ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:51.820412Z","caller":"traceutil/trace.go:171","msg":"trace[65124481] transaction","detail":"{read_only:false; response_revision:21296; number_of_response:1; }","duration":"113.591653ms","start":"2026-10-16T00:53:51.706797Z","end":"2026-10-16T00:53:51.820389Z","steps":["trace[65124481] 'process raft request' (duration: 113.439648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:52.35746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.30383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T00:53:52.357607Z","caller":"traceutil/trace.go:171","msg":"trace[347577292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21298; }","duration":"106.491564ms","start":"2026-10-16T00:53:52.251095Z","end":"2026-10-16T00:53:52.357587Z","steps":["trace[347577292] 'range keys from in-memory index tree' (duration: 105.897451ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:52.485838Z","caller":"traceutil/trace.go:171","msg":"trace[1732751896] transaction","detail":"{read_only:false; response_revision:21299; number_of_response:1; }","duration":"123.647075ms","start":"2026-10-16T00:53:52.362169Z","end":"2026-10-16T00:53:52.485816Z","steps":["trace[1732751896] 'process raft request' (duration: 123.518212ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:52.626304Z","caller":"traceutil/trace.go:171","msg":"trace[1415102531] transaction","detail":"{read_only:false; response_revision:21300; number_of_response:1; }","duration":"263.560852ms","start":"2026-10-16T00:53:52.362715Z","end":"2026-10-16T00:53:52.626276Z","steps":["trace[1415102531] 'process raft request' (duration: 263.402608ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:52.647484Z","caller":"traceutil/trace.go:171","msg":"trace[463360487] transaction","detail":"{read_only:false; response_revision:21301; number_of_response:1; }","duration":"126.402007ms","start":"2026-10-16T00:53:52.52106Z","end":"2026-10-16T00:53:52.647462Z","steps":["trace[463360487] 'process raft request' (duration: 126.204352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:52.900262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.891906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:53:52.90063Z","caller":"traceutil/trace.go:171","msg":"trace[141307883] transaction","detail":"{read_only:false; response_revision:21302; number_of_response:1; }","duration":"246.185817ms","start":"2026-10-16T00:53:52.654411Z","end":"2026-10-16T00:53:52.900597Z","steps":["trace[141307883] 'process raft request' (duration: 119.876092ms)","trace[141307883] 'compare' (duration: 125.689491ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:53:52.900646Z","caller":"traceutil/trace.go:171","msg":"trace[84891808] transaction","detail":"{read_only:false; response_revision:21303; number_of_response:1; }","duration":"143.953336ms","start":"2026-10-16T00:53:52.75667Z","end":"2026-10-16T00:53:52.900623Z","steps":["trace[84891808] 'process raft request' (duration: 143.812753ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:57.614898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20083} {"level":"info","ts":"2026-10-16T00:55:57.732877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20083,"took":"114.244447ms","hash":1651493500,"current-db-size-bytes":56492032,"current-db-size":"56 MB","current-db-size-in-use-bytes":32096256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:55:57.732945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1651493500,"revision":20083,"compact-revision":17407} {"level":"warn","ts":"2026-10-16T00:57:38.958089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.89054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-10-16T00:57:38.958208Z","caller":"traceutil/trace.go:171","msg":"trace[9370774] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23768; }","duration":"135.040414ms","start":"2026-10-16T00:57:38.823146Z","end":"2026-10-16T00:57:38.958186Z","steps":["trace[9370774] 'range keys from in-memory index tree' (duration: 134.684256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:59:03.061419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.507939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-10-16T00:59:03.061569Z","caller":"traceutil/trace.go:171","msg":"trace[628490423] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24644; }","duration":"102.675521ms","start":"2026-10-16T00:59:02.95884Z","end":"2026-10-16T00:59:03.061516Z","steps":["trace[628490423] 'range keys from in-memory index tree' (duration: 102.239132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:59:03.06188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.07422ms","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-10-16T00:59:03.061926Z","caller":"traceutil/trace.go:171","msg":"trace[1680504651] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24644; }","duration":"103.149842ms","start":"2026-10-16T00:59:02.958764Z","end":"2026-10-16T00:59:03.061914Z","steps":["trace[1680504651] 'range keys from in-memory index tree' (duration: 103.011279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:59:03.062318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.50839ms","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-10-16T00:59:03.062383Z","caller":"traceutil/trace.go:171","msg":"trace[2049434969] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24644; }","duration":"103.601962ms","start":"2026-10-16T00:59:02.958765Z","end":"2026-10-16T00:59:03.062367Z","steps":["trace[2049434969] 'range keys from in-memory index tree' (duration: 103.425458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:59:03.062496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.694194ms","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-10-16T00:59:03.062534Z","caller":"traceutil/trace.go:171","msg":"trace[1961177234] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24644; }","duration":"103.759275ms","start":"2026-10-16T00:59:02.958764Z","end":"2026-10-16T00:59:03.062523Z","steps":["trace[1961177234] 'range keys from in-memory index tree' (duration: 103.632952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:59:03.062626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.785315ms","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-10-16T00:59:03.062662Z","caller":"traceutil/trace.go:171","msg":"trace[1605685364] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24644; }","duration":"103.831557ms","start":"2026-10-16T00:59:02.95882Z","end":"2026-10-16T00:59:03.062652Z","steps":["trace[1605685364] 'range keys from in-memory index tree' (duration: 103.754965ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:59:55.534438Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006561.wal"} {"level":"warn","ts":"2026-10-16T01:00:01.843988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.572371ms","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-10-16T01:00:01.844173Z","caller":"traceutil/trace.go:171","msg":"trace[497832323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25242; }","duration":"118.759865ms","start":"2026-10-16T01:00:01.725393Z","end":"2026-10-16T01:00:01.844153Z","steps":["trace[497832323] 'range keys from in-memory index tree' (duration: 118.221784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:00:56.521378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.658692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T01:00:56.521473Z","caller":"traceutil/trace.go:171","msg":"trace[1101551059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25817; }","duration":"236.797625ms","start":"2026-10-16T01:00:56.284655Z","end":"2026-10-16T01:00:56.521453Z","steps":["trace[1101551059] 'range keys from in-memory index tree' (duration: 236.575121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:00:56.521743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.058811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-16T01:00:56.521826Z","caller":"traceutil/trace.go:171","msg":"trace[1893449756] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25817; }","duration":"232.177094ms","start":"2026-10-16T01:00:56.289631Z","end":"2026-10-16T01:00:56.521808Z","steps":["trace[1893449756] 'range keys from in-memory index tree' (duration: 231.836056ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:00:56.719266Z","caller":"traceutil/trace.go:171","msg":"trace[1686267054] linearizableReadLoop","detail":"{readStateIndex:26649; appliedIndex:26648; }","duration":"148.706295ms","start":"2026-10-16T01:00:56.570531Z","end":"2026-10-16T01:00:56.719237Z","steps":["trace[1686267054] 'read index received' (duration: 148.522721ms)","trace[1686267054] 'applied index is now lower than readState.Index' (duration: 182.524µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:00:56.719413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.858248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T01:00:56.7195Z","caller":"traceutil/trace.go:171","msg":"trace[122174939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25818; }","duration":"148.971191ms","start":"2026-10-16T01:00:56.570508Z","end":"2026-10-16T01:00:56.719479Z","steps":["trace[122174939] 'agreement among raft nodes before linearized reading' (duration: 148.826118ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:00:56.719692Z","caller":"traceutil/trace.go:171","msg":"trace[1369362009] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"192.881879ms","start":"2026-10-16T01:00:56.526774Z","end":"2026-10-16T01:00:56.719656Z","steps":["trace[1369362009] 'process raft request' (duration: 192.257516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:00:56.915807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.815537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T01:00:56.916273Z","caller":"traceutil/trace.go:171","msg":"trace[62043392] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"335.099951ms","start":"2026-10-16T01:00:56.581147Z","end":"2026-10-16T01:00:56.916247Z","steps":["trace[62043392] 'process raft request' (duration: 230.755742ms)","trace[62043392] 'compare' (duration: 103.664334ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:00:56.916385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:56.581131Z","time spent":"335.216154ms","remote":"127.0.0.1:52840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:00:56.916893Z","caller":"traceutil/trace.go:171","msg":"trace[1202764877] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"172.740404ms","start":"2026-10-16T01:00:56.744141Z","end":"2026-10-16T01:00:56.916881Z","steps":["trace[1202764877] 'process raft request' (duration: 172.695093ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:00:56.917126Z","caller":"traceutil/trace.go:171","msg":"trace[711245619] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"222.816829ms","start":"2026-10-16T01:00:56.694299Z","end":"2026-10-16T01:00:56.917115Z","steps":["trace[711245619] 'process raft request' (duration: 222.349667ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:00:56.917278Z","caller":"traceutil/trace.go:171","msg":"trace[1400953123] linearizableReadLoop","detail":"{readStateIndex:26651; appliedIndex:26649; }","duration":"197.90569ms","start":"2026-10-16T01:00:56.719334Z","end":"2026-10-16T01:00:56.91724Z","steps":["trace[1400953123] 'read index received' (duration: 92.502608ms)","trace[1400953123] 'applied index is now lower than readState.Index' (duration: 105.399362ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:00:56.917405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.940456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T01:00:56.917444Z","caller":"traceutil/trace.go:171","msg":"trace[2051246080] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25821; }","duration":"323.987397ms","start":"2026-10-16T01:00:56.593446Z","end":"2026-10-16T01:00:56.917433Z","steps":["trace[2051246080] 'agreement among raft nodes before linearized reading' (duration: 323.894525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:00:56.917472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:56.593383Z","time spent":"324.079849ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-10-16T01:00:56.917473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.929924ms","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-10-16T01:00:56.917555Z","caller":"traceutil/trace.go:171","msg":"trace[882656183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25821; }","duration":"249.019456ms","start":"2026-10-16T01:00:56.668513Z","end":"2026-10-16T01:00:56.917533Z","steps":["trace[882656183] 'agreement among raft nodes before linearized reading' (duration: 248.791051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:00:56.91773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.917483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T01:00:56.917767Z","caller":"traceutil/trace.go:171","msg":"trace[816683391] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25821; }","duration":"100.994794ms","start":"2026-10-16T01:00:56.816763Z","end":"2026-10-16T01:00:56.917758Z","steps":["trace[816683391] 'agreement among raft nodes before linearized reading' (duration: 100.941423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:00:56.917854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.403182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T01:00:56.917984Z","caller":"traceutil/trace.go:171","msg":"trace[116763997] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25821; }","duration":"105.983745ms","start":"2026-10-16T01:00:56.811979Z","end":"2026-10-16T01:00:56.917962Z","steps":["trace[116763997] 'agreement among raft nodes before linearized reading' (duration: 105.412132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:00:56.918014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.538101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-16T01:00:56.918405Z","caller":"traceutil/trace.go:171","msg":"trace[528875533] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25821; }","duration":"103.97141ms","start":"2026-10-16T01:00:56.814413Z","end":"2026-10-16T01:00:56.918385Z","steps":["trace[528875533] 'agreement among raft nodes before linearized reading' (duration: 103.48601ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:00:57.206517Z","caller":"traceutil/trace.go:171","msg":"trace[417439295] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"213.641726ms","start":"2026-10-16T01:00:56.992849Z","end":"2026-10-16T01:00:57.206491Z","steps":["trace[417439295] 'process raft request' (duration: 213.583374ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:00:57.206778Z","caller":"traceutil/trace.go:171","msg":"trace[537364598] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"283.955354ms","start":"2026-10-16T01:00:56.922806Z","end":"2026-10-16T01:00:57.206762Z","steps":["trace[537364598] 'process raft request' (duration: 283.482054ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:00:57.626986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22380} {"level":"info","ts":"2026-10-16T01:00:57.742756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22380,"took":"111.612438ms","hash":880869002,"current-db-size-bytes":57765888,"current-db-size":"58 MB","current-db-size-in-use-bytes":46739456,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-10-16T01:00:57.74282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":880869002,"revision":22380,"compact-revision":20083} {"level":"info","ts":"2026-10-16T01:02:14.67824Z","caller":"traceutil/trace.go:171","msg":"trace[179630708] linearizableReadLoop","detail":"{readStateIndex:27339; appliedIndex:27338; }","duration":"131.61821ms","start":"2026-10-16T01:02:14.546598Z","end":"2026-10-16T01:02:14.678217Z","steps":["trace[179630708] 'read index received' (duration: 131.450866ms)","trace[179630708] 'applied index is now lower than readState.Index' (duration: 166.574µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:02:14.678454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.836254ms","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-10-16T01:02:14.678502Z","caller":"traceutil/trace.go:171","msg":"trace[2044346064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26490; }","duration":"131.930176ms","start":"2026-10-16T01:02:14.546558Z","end":"2026-10-16T01:02:14.678488Z","steps":["trace[2044346064] 'agreement among raft nodes before linearized reading' (duration: 131.758472ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:14.678442Z","caller":"traceutil/trace.go:171","msg":"trace[1193853143] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"153.689865ms","start":"2026-10-16T01:02:14.524729Z","end":"2026-10-16T01:02:14.678419Z","steps":["trace[1193853143] 'process raft request' (duration: 153.301157ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:14.840504Z","caller":"traceutil/trace.go:171","msg":"trace[1039942321] transaction","detail":"{read_only:false; response_revision:26491; number_of_response:1; }","duration":"163.445311ms","start":"2026-10-16T01:02:14.677032Z","end":"2026-10-16T01:02:14.840477Z","steps":["trace[1039942321] 'process raft request' (duration: 122.658012ms)","trace[1039942321] 'compare' (duration: 40.606955ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T01:02:14.840777Z","caller":"traceutil/trace.go:171","msg":"trace[815087601] transaction","detail":"{read_only:false; response_revision:26493; number_of_response:1; }","duration":"117.536949ms","start":"2026-10-16T01:02:14.723214Z","end":"2026-10-16T01:02:14.840751Z","steps":["trace[815087601] 'process raft request' (duration: 117.410596ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:14.841051Z","caller":"traceutil/trace.go:171","msg":"trace[545553279] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"158.063323ms","start":"2026-10-16T01:02:14.682934Z","end":"2026-10-16T01:02:14.840997Z","steps":["trace[545553279] 'process raft request' (duration: 157.49791ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:15.450605Z","caller":"traceutil/trace.go:171","msg":"trace[1082449261] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"253.84418ms","start":"2026-10-16T01:02:15.196726Z","end":"2026-10-16T01:02:15.450571Z","steps":["trace[1082449261] 'process raft request' (duration: 253.657386ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:15.486871Z","caller":"traceutil/trace.go:171","msg":"trace[827166230] linearizableReadLoop","detail":"{readStateIndex:27345; appliedIndex:27343; }","duration":"254.744512ms","start":"2026-10-16T01:02:15.232104Z","end":"2026-10-16T01:02:15.486849Z","steps":["trace[827166230] 'read index received' (duration: 218.256348ms)","trace[827166230] 'applied index is now lower than readState.Index' (duration: 36.487274ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T01:02:15.486978Z","caller":"traceutil/trace.go:171","msg":"trace[2103298308] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"260.753794ms","start":"2026-10-16T01:02:15.226191Z","end":"2026-10-16T01:02:15.486945Z","steps":["trace[2103298308] 'process raft request' (duration: 260.538239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:15.487093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.117542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T01:02:15.48714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.991497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/senlin-api-7b85ff6b64-scgrn.18dedc1e7c3b7d73\" ","response":"range_response_count:1 size:1170"} {"level":"info","ts":"2026-10-16T01:02:15.487173Z","caller":"traceutil/trace.go:171","msg":"trace[342702845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26496; }","duration":"202.222344ms","start":"2026-10-16T01:02:15.284914Z","end":"2026-10-16T01:02:15.487137Z","steps":["trace[342702845] 'agreement among raft nodes before linearized reading' (duration: 202.085601ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:15.487206Z","caller":"traceutil/trace.go:171","msg":"trace[509533806] range","detail":"{range_begin:/registry/events/openstack/senlin-api-7b85ff6b64-scgrn.18dedc1e7c3b7d73; range_end:; response_count:1; response_revision:26496; }","duration":"255.129481ms","start":"2026-10-16T01:02:15.232063Z","end":"2026-10-16T01:02:15.487192Z","steps":["trace[509533806] 'agreement among raft nodes before linearized reading' (duration: 254.929806ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:05:57.634606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25827} {"level":"info","ts":"2026-10-16T01:05:57.812124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25827,"took":"172.55643ms","hash":1419952321,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":36814848,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-16T01:05:57.812192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1419952321,"revision":25827,"compact-revision":22380} {"level":"info","ts":"2026-10-16T01:06:29.440982Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:06:29.448141Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T01:06:29.448385Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T01:10:57.641956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28842} {"level":"info","ts":"2026-10-16T01:10:57.800028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28842,"took":"153.392451ms","hash":3609411552,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":33083392,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:10:57.800105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3609411552,"revision":28842,"compact-revision":25827} {"level":"info","ts":"2026-10-16T01:15:57.649509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31130} {"level":"info","ts":"2026-10-16T01:15:57.791768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31130,"took":"135.651189ms","hash":3805629933,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":31424512,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:15:57.791851Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3805629933,"revision":31130,"compact-revision":28842} {"level":"info","ts":"2026-10-16T01:20:57.656139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33427} {"level":"info","ts":"2026-10-16T01:20:57.811137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33427,"took":"150.264962ms","hash":91966443,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":31649792,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:20:57.81121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":91966443,"revision":33427,"compact-revision":31130} {"level":"info","ts":"2026-10-16T01:25:57.6632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35725} {"level":"info","ts":"2026-10-16T01:25:57.829749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35725,"took":"162.480784ms","hash":2537804805,"current-db-size-bytes":61685760,"current-db-size":"62 MB","current-db-size-in-use-bytes":33189888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:25:57.829866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2537804805,"revision":35725,"compact-revision":33427} {"level":"info","ts":"2026-10-16T01:27:37.758895Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:27:37.765979Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T01:27:37.766117Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T01:30:57.672265Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38025} {"level":"info","ts":"2026-10-16T01:30:57.821295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38025,"took":"143.452981ms","hash":1088840514,"current-db-size-bytes":62959616,"current-db-size":"63 MB","current-db-size-in-use-bytes":31641600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:30:57.821443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1088840514,"revision":38025,"compact-revision":35725}