{"level":"warn","ts":"2026-02-26T06:17:26.412774Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T06:17:26.412869Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T06:17:26.412934Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T06:17:26.412948Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-26T06:17:26.412965Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T06:17:26.413564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-02-26T06:17:26.413637Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T06:17:26.417673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.827493ms"} {"level":"info","ts":"2026-02-26T06:17:26.425542Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-02-26T06:17:26.425717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T06:17:26.42575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-02-26T06:17:26.425774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T06:17:26.425795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-02-26T06:17:26.425865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-02-26T06:17:26.440182Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T06:17:26.441573Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T06:17:26.442675Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-26T06:17:26.445617Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T06:17:26.445923Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T06:17:26.44618Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T06:17:26.446358Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T06:17:26.446371Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T06:17:26.448121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-02-26T06:17:26.448317Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-26T06:17:26.451146Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T06:17:26.451312Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-26T06:17:26.451379Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-26T06:17:26.451608Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T06:17:26.45166Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T06:17:27.426706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T06:17:27.42677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T06:17:27.426795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-02-26T06:17:27.426816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-02-26T06:17:27.426846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-26T06:17:27.426862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-02-26T06:17:27.426881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-26T06:17:27.428032Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T06:17:27.428179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T06:17:27.428224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T06:17:27.428391Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:27.429581Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:27.42982Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:27.429892Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:27.431589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T06:17:27.431706Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-02-26T06:17:27.431906Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T06:17:27.431967Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T06:17:45.590568Z","caller":"traceutil/trace.go:171","msg":"trace[811489098] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"125.897903ms","start":"2026-02-26T06:17:45.464648Z","end":"2026-02-26T06:17:45.590546Z","steps":["trace[811489098] 'read index received' (duration: 125.73182ms)","trace[811489098] 'applied index is now lower than readState.Index' (duration: 165.183µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:45.590634Z","caller":"traceutil/trace.go:171","msg":"trace[1319875375] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"159.787573ms","start":"2026-02-26T06:17:45.430802Z","end":"2026-02-26T06:17:45.59059Z","steps":["trace[1319875375] 'process raft request' (duration: 159.62287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:45.590896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.19949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T06:17:45.591105Z","caller":"traceutil/trace.go:171","msg":"trace[2036027664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:447; }","duration":"126.486556ms","start":"2026-02-26T06:17:45.464582Z","end":"2026-02-26T06:17:45.591068Z","steps":["trace[2036027664] 'agreement among raft nodes before linearized reading' (duration: 126.099938ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:51.358651Z","caller":"traceutil/trace.go:171","msg":"trace[12507695] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"225.630735ms","start":"2026-02-26T06:17:51.132995Z","end":"2026-02-26T06:17:51.358626Z","steps":["trace[12507695] 'process raft request' (duration: 225.484642ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:53.629798Z","caller":"traceutil/trace.go:171","msg":"trace[1649816580] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"263.191446ms","start":"2026-02-26T06:17:53.366582Z","end":"2026-02-26T06:17:53.629774Z","steps":["trace[1649816580] 'process raft request' (duration: 263.030113ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:03.704062Z","caller":"traceutil/trace.go:171","msg":"trace[147574178] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"170.252024ms","start":"2026-02-26T06:18:03.533779Z","end":"2026-02-26T06:18:03.704031Z","steps":["trace[147574178] 'read index received' (duration: 170.016349ms)","trace[147574178] 'applied index is now lower than readState.Index' (duration: 234.585µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:03.704125Z","caller":"traceutil/trace.go:171","msg":"trace[417450322] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"260.511205ms","start":"2026-02-26T06:18:03.443576Z","end":"2026-02-26T06:18:03.704087Z","steps":["trace[417450322] 'process raft request' (duration: 260.303851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:03.704256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.455788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:03.704306Z","caller":"traceutil/trace.go:171","msg":"trace[290655573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"170.55887ms","start":"2026-02-26T06:18:03.533733Z","end":"2026-02-26T06:18:03.704292Z","steps":["trace[290655573] 'agreement among raft nodes before linearized reading' (duration: 170.466707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:04.16912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.558691ms","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-02-26T06:18:04.169227Z","caller":"traceutil/trace.go:171","msg":"trace[2031380126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"229.703664ms","start":"2026-02-26T06:18:03.939496Z","end":"2026-02-26T06:18:04.169199Z","steps":["trace[2031380126] 'range keys from in-memory index tree' (duration: 229.390588ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:04.481678Z","caller":"traceutil/trace.go:171","msg":"trace[424644250] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"307.581417ms","start":"2026-02-26T06:18:04.174072Z","end":"2026-02-26T06:18:04.481653Z","steps":["trace[424644250] 'process raft request' (duration: 307.455465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:04.482594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:04.174042Z","time spent":"307.70974ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:05.575221Z","caller":"traceutil/trace.go:171","msg":"trace[440528059] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"133.648512ms","start":"2026-02-26T06:18:05.441518Z","end":"2026-02-26T06:18:05.575166Z","steps":["trace[440528059] 'process raft request' (duration: 133.523929ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.575272Z","caller":"traceutil/trace.go:171","msg":"trace[909792190] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"133.57962ms","start":"2026-02-26T06:18:05.441668Z","end":"2026-02-26T06:18:05.575248Z","steps":["trace[909792190] 'process raft request' (duration: 133.419467ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.575341Z","caller":"traceutil/trace.go:171","msg":"trace[496845618] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"133.56907ms","start":"2026-02-26T06:18:05.441738Z","end":"2026-02-26T06:18:05.575307Z","steps":["trace[496845618] 'process raft request' (duration: 133.476978ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.575429Z","caller":"traceutil/trace.go:171","msg":"trace[1896914750] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"133.655801ms","start":"2026-02-26T06:18:05.441711Z","end":"2026-02-26T06:18:05.575367Z","steps":["trace[1896914750] 'process raft request' (duration: 133.435377ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.575196Z","caller":"traceutil/trace.go:171","msg":"trace[1923541925] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"133.689602ms","start":"2026-02-26T06:18:05.441441Z","end":"2026-02-26T06:18:05.57513Z","steps":["trace[1923541925] 'process raft request' (duration: 123.955406ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.575747Z","caller":"traceutil/trace.go:171","msg":"trace[1621730317] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"133.066731ms","start":"2026-02-26T06:18:05.442664Z","end":"2026-02-26T06:18:05.575731Z","steps":["trace[1621730317] 'process raft request' (duration: 132.591601ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.575749Z","caller":"traceutil/trace.go:171","msg":"trace[554789948] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"132.549071ms","start":"2026-02-26T06:18:05.443181Z","end":"2026-02-26T06:18:05.57573Z","steps":["trace[554789948] 'process raft request' (duration: 132.391687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:05.575756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.00741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2251"} {"level":"info","ts":"2026-02-26T06:18:05.575839Z","caller":"traceutil/trace.go:171","msg":"trace[1729775933] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:604; }","duration":"133.111612ms","start":"2026-02-26T06:18:05.442708Z","end":"2026-02-26T06:18:05.57582Z","steps":["trace[1729775933] 'agreement among raft nodes before linearized reading' (duration: 132.974339ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.638156Z","caller":"traceutil/trace.go:171","msg":"trace[134897105] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"146.940607ms","start":"2026-02-26T06:18:05.491183Z","end":"2026-02-26T06:18:05.638124Z","steps":["trace[134897105] 'process raft request' (duration: 146.661292ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.639121Z","caller":"traceutil/trace.go:171","msg":"trace[792459796] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"116.685818ms","start":"2026-02-26T06:18:05.521778Z","end":"2026-02-26T06:18:05.638464Z","steps":["trace[792459796] 'process raft request' (duration: 116.25582ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.714545Z","caller":"traceutil/trace.go:171","msg":"trace[1182439726] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"127.401712ms","start":"2026-02-26T06:18:05.587081Z","end":"2026-02-26T06:18:05.714483Z","steps":["trace[1182439726] 'process raft request' (duration: 97.864376ms)","trace[1182439726] 'compare' (duration: 29.273131ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:06.261177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.598583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:18:06.261529Z","caller":"traceutil/trace.go:171","msg":"trace[966768388] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"447.746503ms","start":"2026-02-26T06:18:05.813761Z","end":"2026-02-26T06:18:06.261508Z","steps":["trace[966768388] 'process raft request' (duration: 447.651752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.261623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:05.813731Z","time spent":"447.847866ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:06.261765Z","caller":"traceutil/trace.go:171","msg":"trace[1988001195] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"451.564727ms","start":"2026-02-26T06:18:05.810186Z","end":"2026-02-26T06:18:06.261751Z","steps":["trace[1988001195] 'read index received' (duration: 4.920794ms)","trace[1988001195] 'applied index is now lower than readState.Index' (duration: 446.642603ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:06.261767Z","caller":"traceutil/trace.go:171","msg":"trace[1609905427] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"493.890269ms","start":"2026-02-26T06:18:05.767818Z","end":"2026-02-26T06:18:06.261708Z","steps":["trace[1609905427] 'process raft request' (duration: 493.502572ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:06.262016Z","caller":"traceutil/trace.go:171","msg":"trace[1834103302] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"447.172113ms","start":"2026-02-26T06:18:05.814818Z","end":"2026-02-26T06:18:06.26199Z","steps":["trace[1834103302] 'process raft request' (duration: 446.643502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.262084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:05.767808Z","time spent":"494.094453ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:06.26218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:05.814809Z","time spent":"447.287246ms","remote":"127.0.0.1:55066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T06:18:06.262271Z","caller":"traceutil/trace.go:171","msg":"trace[1411696290] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"496.289716ms","start":"2026-02-26T06:18:05.765779Z","end":"2026-02-26T06:18:06.262069Z","steps":["trace[1411696290] 'process raft request' (duration: 49.318606ms)","trace[1411696290] 'compare' (duration: 445.41729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:06.262532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:05.765741Z","time spent":"496.719474ms","remote":"127.0.0.1:55186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1376,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T06:18:06.262713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.346036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:70645"} {"level":"info","ts":"2026-02-26T06:18:06.262785Z","caller":"traceutil/trace.go:171","msg":"trace[543004578] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:619; }","duration":"497.462278ms","start":"2026-02-26T06:18:05.7653Z","end":"2026-02-26T06:18:06.262762Z","steps":["trace[543004578] 'agreement among raft nodes before linearized reading' (duration: 496.56697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.262837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:05.765269Z","time spent":"497.55678ms","remote":"127.0.0.1:55036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":70669,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-02-26T06:18:06.263002Z","caller":"traceutil/trace.go:171","msg":"trace[1928925753] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"448.151102ms","start":"2026-02-26T06:18:05.814831Z","end":"2026-02-26T06:18:06.262982Z","steps":["trace[1928925753] 'process raft request' (duration: 447.04276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.263105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:05.814796Z","time spent":"448.256324ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:06.263472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.29619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:06.263545Z","caller":"traceutil/trace.go:171","msg":"trace[1630255498] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:621; }","duration":"128.406212ms","start":"2026-02-26T06:18:06.135117Z","end":"2026-02-26T06:18:06.263523Z","steps":["trace[1630255498] 'agreement among raft nodes before linearized reading' (duration: 128.28719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.263844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.603337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:06.264863Z","caller":"traceutil/trace.go:171","msg":"trace[1139536014] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:621; }","duration":"115.556546ms","start":"2026-02-26T06:18:06.149228Z","end":"2026-02-26T06:18:06.264784Z","steps":["trace[1139536014] 'agreement among raft nodes before linearized reading' (duration: 114.581047ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:06.264925Z","caller":"traceutil/trace.go:171","msg":"trace[204440089] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"425.119011ms","start":"2026-02-26T06:18:05.83975Z","end":"2026-02-26T06:18:06.264869Z","steps":["trace[204440089] 'process raft request' (duration: 423.221654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.265362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:05.839716Z","time spent":"425.530449ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59681,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:06.265584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.725338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:06.265676Z","caller":"traceutil/trace.go:171","msg":"trace[1016650035] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:621; }","duration":"116.8362ms","start":"2026-02-26T06:18:06.14881Z","end":"2026-02-26T06:18:06.265646Z","steps":["trace[1016650035] 'agreement among raft nodes before linearized reading' (duration: 116.700228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.265747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.920151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:06.265857Z","caller":"traceutil/trace.go:171","msg":"trace[1762054437] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:621; }","duration":"117.044054ms","start":"2026-02-26T06:18:06.148762Z","end":"2026-02-26T06:18:06.265806Z","steps":["trace[1762054437] 'agreement among raft nodes before linearized reading' (duration: 116.892861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.266028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.546863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:06.266069Z","caller":"traceutil/trace.go:171","msg":"trace[933750819] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:621; }","duration":"130.597924ms","start":"2026-02-26T06:18:06.135459Z","end":"2026-02-26T06:18:06.266057Z","steps":["trace[933750819] 'agreement among raft nodes before linearized reading' (duration: 130.516853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.266198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.015728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:18:06.266313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.842069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:06.266365Z","caller":"traceutil/trace.go:171","msg":"trace[293877186] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:621; }","duration":"130.939491ms","start":"2026-02-26T06:18:06.135411Z","end":"2026-02-26T06:18:06.266351Z","steps":["trace[293877186] 'agreement among raft nodes before linearized reading' (duration: 130.844659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:06.266385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.016977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:18:06.267127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.027916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:06.267506Z","caller":"traceutil/trace.go:171","msg":"trace[1470599127] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:621; }","duration":"102.419183ms","start":"2026-02-26T06:18:06.165055Z","end":"2026-02-26T06:18:06.267474Z","steps":["trace[1470599127] 'agreement among raft nodes before linearized reading' (duration: 101.992265ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:06.267885Z","caller":"traceutil/trace.go:171","msg":"trace[1958430157] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:621; }","duration":"101.121128ms","start":"2026-02-26T06:18:06.165314Z","end":"2026-02-26T06:18:06.266436Z","steps":["trace[1958430157] 'agreement among raft nodes before linearized reading' (duration: 101.006826ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:06.268334Z","caller":"traceutil/trace.go:171","msg":"trace[2070710265] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:621; }","duration":"100.14824ms","start":"2026-02-26T06:18:06.16611Z","end":"2026-02-26T06:18:06.266258Z","steps":["trace[2070710265] 'agreement among raft nodes before linearized reading' (duration: 99.978007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:40.617994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.130012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T06:18:40.61813Z","caller":"traceutil/trace.go:171","msg":"trace[707964451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"113.253244ms","start":"2026-02-26T06:18:40.504814Z","end":"2026-02-26T06:18:40.618067Z","steps":["trace[707964451] 'range keys from in-memory index tree' (duration: 112.968117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:40.618136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.758927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T06:18:40.618229Z","caller":"traceutil/trace.go:171","msg":"trace[1821031238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"113.88212ms","start":"2026-02-26T06:18:40.504329Z","end":"2026-02-26T06:18:40.618211Z","steps":["trace[1821031238] 'range keys from in-memory index tree' (duration: 113.419587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:40.617995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.748264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T06:18:40.618366Z","caller":"traceutil/trace.go:171","msg":"trace[1172954003] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1065; }","duration":"138.175204ms","start":"2026-02-26T06:18:40.480183Z","end":"2026-02-26T06:18:40.618358Z","steps":["trace[1172954003] 'range keys from in-memory index tree' (duration: 137.548548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:40.618368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.351774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T06:18:40.618428Z","caller":"traceutil/trace.go:171","msg":"trace[640408694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"105.461267ms","start":"2026-02-26T06:18:40.512951Z","end":"2026-02-26T06:18:40.618413Z","steps":["trace[640408694] 'range keys from in-memory index tree' (duration: 104.823221ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:08.61518Z","caller":"traceutil/trace.go:171","msg":"trace[1952297891] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"106.562357ms","start":"2026-02-26T06:19:08.508597Z","end":"2026-02-26T06:19:08.615159Z","steps":["trace[1952297891] 'process raft request' (duration: 106.364783ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:10.868008Z","caller":"traceutil/trace.go:171","msg":"trace[170936735] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"110.548885ms","start":"2026-02-26T06:19:10.757431Z","end":"2026-02-26T06:19:10.86798Z","steps":["trace[170936735] 'process raft request' (duration: 66.079798ms)","trace[170936735] 'compare' (duration: 43.959167ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:11.022482Z","caller":"traceutil/trace.go:171","msg":"trace[1720331716] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"149.842271ms","start":"2026-02-26T06:19:10.872622Z","end":"2026-02-26T06:19:11.022464Z","steps":["trace[1720331716] 'process raft request' (duration: 149.738439ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:11.022482Z","caller":"traceutil/trace.go:171","msg":"trace[143393824] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"150.070056ms","start":"2026-02-26T06:19:10.87238Z","end":"2026-02-26T06:19:11.02245Z","steps":["trace[143393824] 'process raft request' (duration: 149.829612ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:11.022672Z","caller":"traceutil/trace.go:171","msg":"trace[1150303348] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"149.347451ms","start":"2026-02-26T06:19:10.873304Z","end":"2026-02-26T06:19:11.022651Z","steps":["trace[1150303348] 'process raft request' (duration: 149.113267ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:11.022726Z","caller":"traceutil/trace.go:171","msg":"trace[16357390] linearizableReadLoop","detail":"{readStateIndex:1640; appliedIndex:1635; }","duration":"118.709434ms","start":"2026-02-26T06:19:10.903999Z","end":"2026-02-26T06:19:11.022709Z","steps":["trace[16357390] 'read index received' (duration: 53.543644ms)","trace[16357390] 'applied index is now lower than readState.Index' (duration: 65.16466ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:11.022936Z","caller":"traceutil/trace.go:171","msg":"trace[306141083] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"147.479925ms","start":"2026-02-26T06:19:10.875405Z","end":"2026-02-26T06:19:11.022885Z","steps":["trace[306141083] 'process raft request' (duration: 147.18899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:11.023109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.066661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T06:19:11.023175Z","caller":"traceutil/trace.go:171","msg":"trace[990129919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1579; }","duration":"119.185623ms","start":"2026-02-26T06:19:10.903972Z","end":"2026-02-26T06:19:11.023157Z","steps":["trace[990129919] 'agreement among raft nodes before linearized reading' (duration: 118.972669ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:11.529244Z","caller":"traceutil/trace.go:171","msg":"trace[184147944] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"153.88688ms","start":"2026-02-26T06:19:11.375299Z","end":"2026-02-26T06:19:11.529186Z","steps":["trace[184147944] 'process raft request' (duration: 153.419981ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:22.122642Z","caller":"traceutil/trace.go:171","msg":"trace[993346017] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"188.078375ms","start":"2026-02-26T06:19:21.934258Z","end":"2026-02-26T06:19:22.122337Z","steps":["trace[993346017] 'process raft request' (duration: 187.886412ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:55.297515Z","caller":"traceutil/trace.go:171","msg":"trace[2100586380] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"102.398566ms","start":"2026-02-26T06:19:55.19509Z","end":"2026-02-26T06:19:55.297488Z","steps":["trace[2100586380] 'process raft request' (duration: 102.239273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:56.595875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.610391ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-26T06:19:56.596047Z","caller":"traceutil/trace.go:171","msg":"trace[115793571] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"924.045392ms","start":"2026-02-26T06:19:55.671971Z","end":"2026-02-26T06:19:56.596016Z","steps":["trace[115793571] 'read index received' (duration: 76.256217ms)","trace[115793571] 'applied index is now lower than readState.Index' (duration: 847.787165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:56.596371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.385748ms","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":"info","ts":"2026-02-26T06:19:56.596416Z","caller":"traceutil/trace.go:171","msg":"trace[362203382] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"924.48808ms","start":"2026-02-26T06:19:55.671911Z","end":"2026-02-26T06:19:56.596399Z","steps":["trace[362203382] 'agreement among raft nodes before linearized reading' (duration: 924.226465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:56.59646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:55.67189Z","time spent":"924.560401ms","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-26T06:19:56.805923Z","caller":"traceutil/trace.go:171","msg":"trace[1492300314] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"209.815969ms","start":"2026-02-26T06:19:56.596082Z","end":"2026-02-26T06:19:56.805898Z","steps":["trace[1492300314] 'read index received' (duration: 209.795529ms)","trace[1492300314] 'applied index is now lower than readState.Index' (duration: 19.19µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:56.805984Z","caller":"traceutil/trace.go:171","msg":"trace[2055275585] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"537.344193ms","start":"2026-02-26T06:19:56.268593Z","end":"2026-02-26T06:19:56.805937Z","steps":["trace[2055275585] 'process raft request' (duration: 537.163399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:56.80615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047918094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T06:19:56.806206Z","caller":"traceutil/trace.go:171","msg":"trace[754676845] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1852; }","duration":"1.048055598s","start":"2026-02-26T06:19:55.758139Z","end":"2026-02-26T06:19:56.806194Z","steps":["trace[754676845] 'agreement among raft nodes before linearized reading' (duration: 1.047867244s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:56.806231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:55.758109Z","time spent":"1.048115628s","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T06:19:56.806269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:56.268564Z","time spent":"537.536747ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:56.806259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.636273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:56.806373Z","caller":"traceutil/trace.go:171","msg":"trace[115147231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1852; }","duration":"272.815866ms","start":"2026-02-26T06:19:56.533519Z","end":"2026-02-26T06:19:56.806335Z","steps":["trace[115147231] 'agreement among raft nodes before linearized reading' (duration: 272.584822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:56.806438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.308814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-02-26T06:19:56.806523Z","caller":"traceutil/trace.go:171","msg":"trace[1962655021] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1852; }","duration":"927.454446ms","start":"2026-02-26T06:19:55.879045Z","end":"2026-02-26T06:19:56.8065Z","steps":["trace[1962655021] 'agreement among raft nodes before linearized reading' (duration: 927.171531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:56.806566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:55.879022Z","time spent":"927.535538ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-02-26T06:21:19.819533Z","caller":"traceutil/trace.go:171","msg":"trace[1446241296] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"103.716891ms","start":"2026-02-26T06:21:19.715784Z","end":"2026-02-26T06:21:19.8195Z","steps":["trace[1446241296] 'process raft request' (duration: 103.537298ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:21:20.177393Z","caller":"traceutil/trace.go:171","msg":"trace[2053501853] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"115.572373ms","start":"2026-02-26T06:21:20.061798Z","end":"2026-02-26T06:21:20.17737Z","steps":["trace[2053501853] 'process raft request' (duration: 115.39493ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:21:20.334408Z","caller":"traceutil/trace.go:171","msg":"trace[240674157] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"201.522405ms","start":"2026-02-26T06:21:20.132858Z","end":"2026-02-26T06:21:20.33438Z","steps":["trace[240674157] 'process raft request' (duration: 146.452132ms)","trace[240674157] 'compare' (duration: 54.967371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:23:04.483729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.791457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T06:23:04.48381Z","caller":"traceutil/trace.go:171","msg":"trace[1500100456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2805; }","duration":"139.933671ms","start":"2026-02-26T06:23:04.34386Z","end":"2026-02-26T06:23:04.483793Z","steps":["trace[1500100456] 'range keys from in-memory index tree' (duration: 139.597294ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:23:50.638454Z","caller":"traceutil/trace.go:171","msg":"trace[1952136971] linearizableReadLoop","detail":"{readStateIndex:3347; appliedIndex:3346; }","duration":"140.135939ms","start":"2026-02-26T06:23:50.498297Z","end":"2026-02-26T06:23:50.638433Z","steps":["trace[1952136971] 'read index received' (duration: 139.969286ms)","trace[1952136971] 'applied index is now lower than readState.Index' (duration: 165.753µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:23:50.638509Z","caller":"traceutil/trace.go:171","msg":"trace[864969774] transaction","detail":"{read_only:false; response_revision:3221; number_of_response:1; }","duration":"157.248767ms","start":"2026-02-26T06:23:50.481227Z","end":"2026-02-26T06:23:50.638476Z","steps":["trace[864969774] 'process raft request' (duration: 157.079164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:23:50.638645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.347073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T06:23:50.638678Z","caller":"traceutil/trace.go:171","msg":"trace[266675778] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:3221; }","duration":"140.450425ms","start":"2026-02-26T06:23:50.498219Z","end":"2026-02-26T06:23:50.638669Z","steps":["trace[266675778] 'agreement among raft nodes before linearized reading' (duration: 140.320963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:23:50.6388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.320152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T06:23:50.6388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.166254ms","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-02-26T06:23:50.638851Z","caller":"traceutil/trace.go:171","msg":"trace[208664253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3221; }","duration":"119.386414ms","start":"2026-02-26T06:23:50.519442Z","end":"2026-02-26T06:23:50.638828Z","steps":["trace[208664253] 'agreement among raft nodes before linearized reading' (duration: 119.268571ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:23:50.638865Z","caller":"traceutil/trace.go:171","msg":"trace[163803682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3221; }","duration":"125.286527ms","start":"2026-02-26T06:23:50.513561Z","end":"2026-02-26T06:23:50.638847Z","steps":["trace[163803682] 'agreement among raft nodes before linearized reading' (duration: 125.042382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:23:50.638869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.794157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T06:23:50.638948Z","caller":"traceutil/trace.go:171","msg":"trace[479133812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3221; }","duration":"104.881489ms","start":"2026-02-26T06:23:50.534047Z","end":"2026-02-26T06:23:50.638928Z","steps":["trace[479133812] 'agreement among raft nodes before linearized reading' (duration: 104.647214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:23:50.638938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.937147ms","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-02-26T06:23:50.639059Z","caller":"traceutil/trace.go:171","msg":"trace[524108529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3221; }","duration":"112.093571ms","start":"2026-02-26T06:23:50.526935Z","end":"2026-02-26T06:23:50.639028Z","steps":["trace[524108529] 'agreement among raft nodes before linearized reading' (duration: 111.768644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:23:50.639151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.753986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:23:50.639316Z","caller":"traceutil/trace.go:171","msg":"trace[2093900766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3221; }","duration":"105.269177ms","start":"2026-02-26T06:23:50.534027Z","end":"2026-02-26T06:23:50.639296Z","steps":["trace[2093900766] 'agreement among raft nodes before linearized reading' (duration: 104.692885ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:17.305158Z","caller":"traceutil/trace.go:171","msg":"trace[597593209] transaction","detail":"{read_only:false; response_revision:4361; number_of_response:1; }","duration":"161.679034ms","start":"2026-02-26T06:25:17.14345Z","end":"2026-02-26T06:25:17.305129Z","steps":["trace[597593209] 'process raft request' (duration: 161.49541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:17.537794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.709344ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:25:17.537884Z","caller":"traceutil/trace.go:171","msg":"trace[1874473441] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4361; }","duration":"145.805615ms","start":"2026-02-26T06:25:17.392065Z","end":"2026-02-26T06:25:17.537871Z","steps":["trace[1874473441] 'range keys from in-memory index tree' (duration: 145.692503ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:17.53792Z","caller":"traceutil/trace.go:171","msg":"trace[737729300] transaction","detail":"{read_only:false; response_revision:4362; number_of_response:1; }","duration":"228.358262ms","start":"2026-02-26T06:25:17.309531Z","end":"2026-02-26T06:25:17.537889Z","steps":["trace[737729300] 'process raft request' (duration: 161.293656ms)","trace[737729300] 'compare' (duration: 66.939993ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:17.549803Z","caller":"traceutil/trace.go:171","msg":"trace[1424039754] linearizableReadLoop","detail":"{readStateIndex:4537; appliedIndex:4533; }","duration":"191.087975ms","start":"2026-02-26T06:25:17.358689Z","end":"2026-02-26T06:25:17.549776Z","steps":["trace[1424039754] 'read index received' (duration: 112.13517ms)","trace[1424039754] 'applied index is now lower than readState.Index' (duration: 78.951385ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:17.549855Z","caller":"traceutil/trace.go:171","msg":"trace[1160205135] transaction","detail":"{read_only:false; response_revision:4364; number_of_response:1; }","duration":"238.026286ms","start":"2026-02-26T06:25:17.311799Z","end":"2026-02-26T06:25:17.549825Z","steps":["trace[1160205135] 'process raft request' (duration: 237.905214ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:17.54988Z","caller":"traceutil/trace.go:171","msg":"trace[126305889] transaction","detail":"{read_only:false; response_revision:4363; number_of_response:1; }","duration":"238.133649ms","start":"2026-02-26T06:25:17.311718Z","end":"2026-02-26T06:25:17.549851Z","steps":["trace[126305889] 'process raft request' (duration: 237.863033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:17.550087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.380771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-098a67e3-3249-4491-8a74-324a8ee001d0\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-02-26T06:25:17.549913Z","caller":"traceutil/trace.go:171","msg":"trace[1137326661] transaction","detail":"{read_only:false; response_revision:4365; number_of_response:1; }","duration":"238.089387ms","start":"2026-02-26T06:25:17.311793Z","end":"2026-02-26T06:25:17.549882Z","steps":["trace[1137326661] 'process raft request' (duration: 237.945334ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:17.550153Z","caller":"traceutil/trace.go:171","msg":"trace[1019934083] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-098a67e3-3249-4491-8a74-324a8ee001d0; range_end:; response_count:1; response_revision:4365; }","duration":"191.486693ms","start":"2026-02-26T06:25:17.358646Z","end":"2026-02-26T06:25:17.550133Z","steps":["trace[1019934083] 'agreement among raft nodes before linearized reading' (duration: 191.267459ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:26.794458Z","caller":"traceutil/trace.go:171","msg":"trace[1139816207] linearizableReadLoop","detail":"{readStateIndex:4735; appliedIndex:4733; }","duration":"165.860668ms","start":"2026-02-26T06:25:26.62857Z","end":"2026-02-26T06:25:26.794431Z","steps":["trace[1139816207] 'read index received' (duration: 64.265979ms)","trace[1139816207] 'applied index is now lower than readState.Index' (duration: 101.593679ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:26.794545Z","caller":"traceutil/trace.go:171","msg":"trace[965084930] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"179.425041ms","start":"2026-02-26T06:25:26.615075Z","end":"2026-02-26T06:25:26.7945Z","steps":["trace[965084930] 'process raft request' (duration: 138.788144ms)","trace[965084930] 'compare' (duration: 40.439143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:25:26.794735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.290715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:25:26.794786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.194815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-02-26T06:25:26.79488Z","caller":"traceutil/trace.go:171","msg":"trace[548020066] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4556; }","duration":"166.330099ms","start":"2026-02-26T06:25:26.628529Z","end":"2026-02-26T06:25:26.794859Z","steps":["trace[548020066] 'agreement among raft nodes before linearized reading' (duration: 166.051873ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:26.794803Z","caller":"traceutil/trace.go:171","msg":"trace[1993689531] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4556; }","duration":"140.429048ms","start":"2026-02-26T06:25:26.654359Z","end":"2026-02-26T06:25:26.794788Z","steps":["trace[1993689531] 'agreement among raft nodes before linearized reading' (duration: 140.285245ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:33.088039Z","caller":"traceutil/trace.go:171","msg":"trace[1504087033] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4848; }","duration":"125.942478ms","start":"2026-02-26T06:25:32.962081Z","end":"2026-02-26T06:25:33.088023Z","steps":["trace[1504087033] 'read index received' (duration: 102.312µs)","trace[1504087033] 'applied index is now lower than readState.Index' (duration: 125.839306ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:33.088092Z","caller":"traceutil/trace.go:171","msg":"trace[299909148] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"126.018159ms","start":"2026-02-26T06:25:32.962045Z","end":"2026-02-26T06:25:33.088064Z","steps":["trace[299909148] 'process raft request' (duration: 124.833685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:33.088358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.267834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-02-26T06:25:33.088414Z","caller":"traceutil/trace.go:171","msg":"trace[458029937] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4669; }","duration":"126.376786ms","start":"2026-02-26T06:25:32.96203Z","end":"2026-02-26T06:25:33.088406Z","steps":["trace[458029937] 'agreement among raft nodes before linearized reading' (duration: 126.081941ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:33.089772Z","caller":"traceutil/trace.go:171","msg":"trace[267481512] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"126.616881ms","start":"2026-02-26T06:25:32.963016Z","end":"2026-02-26T06:25:33.089633Z","steps":["trace[267481512] 'process raft request' (duration: 126.274094ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:35.108572Z","caller":"traceutil/trace.go:171","msg":"trace[461282623] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"416.921136ms","start":"2026-02-26T06:25:34.691627Z","end":"2026-02-26T06:25:35.108548Z","steps":["trace[461282623] 'process raft request' (duration: 416.771403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:35.108756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:25:34.691605Z","time spent":"417.025749ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:25:35.109093Z","caller":"traceutil/trace.go:171","msg":"trace[545093547] linearizableReadLoop","detail":"{readStateIndex:4869; appliedIndex:4868; }","duration":"407.388025ms","start":"2026-02-26T06:25:34.701675Z","end":"2026-02-26T06:25:35.109063Z","steps":["trace[545093547] 'read index received' (duration: 406.62351ms)","trace[545093547] 'applied index is now lower than readState.Index' (duration: 756.715µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:25:35.109241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.531308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:25:35.109359Z","caller":"traceutil/trace.go:171","msg":"trace[539796810] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:4689; }","duration":"407.680182ms","start":"2026-02-26T06:25:34.701659Z","end":"2026-02-26T06:25:35.109339Z","steps":["trace[539796810] 'agreement among raft nodes before linearized reading' (duration: 407.503858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:35.109406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:25:34.701651Z","time spent":"407.745333ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:25:35.109381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.456509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-17c1941a-5f00-4ff7-9ff2-d1c2d7af516d\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-02-26T06:25:35.109484Z","caller":"traceutil/trace.go:171","msg":"trace[333182871] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-17c1941a-5f00-4ff7-9ff2-d1c2d7af516d; range_end:; response_count:1; response_revision:4689; }","duration":"148.573801ms","start":"2026-02-26T06:25:34.96089Z","end":"2026-02-26T06:25:35.109464Z","steps":["trace[333182871] 'agreement among raft nodes before linearized reading' (duration: 148.385968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:35.109569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.767188ms","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":"warn","ts":"2026-02-26T06:25:35.109577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.048623ms","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-02-26T06:25:35.109719Z","caller":"traceutil/trace.go:171","msg":"trace[1331016992] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4689; }","duration":"168.228066ms","start":"2026-02-26T06:25:34.941462Z","end":"2026-02-26T06:25:35.10969Z","steps":["trace[1331016992] 'agreement among raft nodes before linearized reading' (duration: 167.876509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:35.109738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.170981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-02-26T06:25:35.109773Z","caller":"traceutil/trace.go:171","msg":"trace[2104661734] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4689; }","duration":"260.240962ms","start":"2026-02-26T06:25:34.849522Z","end":"2026-02-26T06:25:35.109763Z","steps":["trace[2104661734] 'agreement among raft nodes before linearized reading' (duration: 260.10698ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:35.109658Z","caller":"traceutil/trace.go:171","msg":"trace[1575518657] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4689; }","duration":"332.920611ms","start":"2026-02-26T06:25:34.776724Z","end":"2026-02-26T06:25:35.109644Z","steps":["trace[1575518657] 'agreement among raft nodes before linearized reading' (duration: 332.541993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:35.10996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:25:34.776715Z","time spent":"333.225147ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-02-26T06:25:35.223327Z","caller":"traceutil/trace.go:171","msg":"trace[2023948381] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4869; }","duration":"106.828115ms","start":"2026-02-26T06:25:35.116469Z","end":"2026-02-26T06:25:35.223297Z","steps":["trace[2023948381] 'read index received' (duration: 44.204428ms)","trace[2023948381] 'applied index is now lower than readState.Index' (duration: 62.621947ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:35.223393Z","caller":"traceutil/trace.go:171","msg":"trace[1973800431] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"108.12126ms","start":"2026-02-26T06:25:35.115234Z","end":"2026-02-26T06:25:35.223355Z","steps":["trace[1973800431] 'process raft request' (duration: 102.555938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:25:35.223561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.068419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-02-26T06:25:35.223607Z","caller":"traceutil/trace.go:171","msg":"trace[229961031] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4691; }","duration":"107.148181ms","start":"2026-02-26T06:25:35.116446Z","end":"2026-02-26T06:25:35.223595Z","steps":["trace[229961031] 'agreement among raft nodes before linearized reading' (duration: 106.960427ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.072017Z","caller":"traceutil/trace.go:171","msg":"trace[696032042] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"129.134371ms","start":"2026-02-26T06:25:46.942856Z","end":"2026-02-26T06:25:47.071991Z","steps":["trace[696032042] 'process raft request' (duration: 115.165221ms)","trace[696032042] 'compare' (duration: 13.740825ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:25:47.074529Z","caller":"traceutil/trace.go:171","msg":"trace[1854956569] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"131.59689ms","start":"2026-02-26T06:25:46.9429Z","end":"2026-02-26T06:25:47.074497Z","steps":["trace[1854956569] 'process raft request' (duration: 131.394276ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.074844Z","caller":"traceutil/trace.go:171","msg":"trace[173268108] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"131.819335ms","start":"2026-02-26T06:25:46.943011Z","end":"2026-02-26T06:25:47.07483Z","steps":["trace[173268108] 'process raft request' (duration: 131.427957ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.075151Z","caller":"traceutil/trace.go:171","msg":"trace[705621563] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"131.804825ms","start":"2026-02-26T06:25:46.943331Z","end":"2026-02-26T06:25:47.075136Z","steps":["trace[705621563] 'process raft request' (duration: 131.326835ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.868764Z","caller":"traceutil/trace.go:171","msg":"trace[1683036508] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"155.732686ms","start":"2026-02-26T06:25:47.712997Z","end":"2026-02-26T06:25:47.868729Z","steps":["trace[1683036508] 'process raft request' (duration: 155.539232ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.901542Z","caller":"traceutil/trace.go:171","msg":"trace[335256719] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"188.189386ms","start":"2026-02-26T06:25:47.713322Z","end":"2026-02-26T06:25:47.901511Z","steps":["trace[335256719] 'process raft request' (duration: 187.740197ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.901571Z","caller":"traceutil/trace.go:171","msg":"trace[1078668776] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"187.631236ms","start":"2026-02-26T06:25:47.713918Z","end":"2026-02-26T06:25:47.901549Z","steps":["trace[1078668776] 'process raft request' (duration: 187.188317ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:25:47.901779Z","caller":"traceutil/trace.go:171","msg":"trace[1575655346] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"188.645465ms","start":"2026-02-26T06:25:47.713121Z","end":"2026-02-26T06:25:47.901766Z","steps":["trace[1575655346] 'process raft request' (duration: 187.806508ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:27:27.455044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2632} {"level":"info","ts":"2026-02-26T06:27:27.513638Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2632,"took":"57.138747ms","hash":26785653,"current-db-size-bytes":41517056,"current-db-size":"42 MB","current-db-size-in-use-bytes":30748672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-26T06:27:27.513725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":26785653,"revision":2632,"compact-revision":-1} {"level":"info","ts":"2026-02-26T06:31:16.936338Z","caller":"traceutil/trace.go:171","msg":"trace[494924599] transaction","detail":"{read_only:false; response_revision:7268; number_of_response:1; }","duration":"100.495287ms","start":"2026-02-26T06:31:16.835812Z","end":"2026-02-26T06:31:16.936307Z","steps":["trace[494924599] 'process raft request' (duration: 100.282202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:29.387427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.080637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-02-26T06:31:29.387509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.939154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:31:29.387551Z","caller":"traceutil/trace.go:171","msg":"trace[1790558545] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7327; }","duration":"195.229819ms","start":"2026-02-26T06:31:29.19227Z","end":"2026-02-26T06:31:29.3875Z","steps":["trace[1790558545] 'range keys from in-memory index tree' (duration: 194.904092ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:29.387572Z","caller":"traceutil/trace.go:171","msg":"trace[1727784514] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:7327; }","duration":"146.055737ms","start":"2026-02-26T06:31:29.2415Z","end":"2026-02-26T06:31:29.387555Z","steps":["trace[1727784514] 'count revisions from in-memory index tree' (duration: 145.843762ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:29.740742Z","caller":"traceutil/trace.go:171","msg":"trace[668248435] transaction","detail":"{read_only:false; response_revision:7328; number_of_response:1; }","duration":"347.99999ms","start":"2026-02-26T06:31:29.392698Z","end":"2026-02-26T06:31:29.740698Z","steps":["trace[668248435] 'process raft request' (duration: 347.739505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:29.74104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:29.392646Z","time spent":"348.209844ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:31:29.75035Z","caller":"traceutil/trace.go:171","msg":"trace[860331215] linearizableReadLoop","detail":"{readStateIndex:7598; appliedIndex:7596; }","duration":"294.980942ms","start":"2026-02-26T06:31:29.455334Z","end":"2026-02-26T06:31:29.750315Z","steps":["trace[860331215] 'read index received' (duration: 285.170113ms)","trace[860331215] 'applied index is now lower than readState.Index' (duration: 9.809529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:31:29.750541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.187696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T06:31:29.750569Z","caller":"traceutil/trace.go:171","msg":"trace[1236542071] transaction","detail":"{read_only:false; response_revision:7329; number_of_response:1; }","duration":"354.3776ms","start":"2026-02-26T06:31:29.396114Z","end":"2026-02-26T06:31:29.750492Z","steps":["trace[1236542071] 'process raft request' (duration: 353.995693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:29.750681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.811772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-02-26T06:31:29.750699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.26806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T06:31:29.750729Z","caller":"traceutil/trace.go:171","msg":"trace[1545945972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7329; }","duration":"147.911995ms","start":"2026-02-26T06:31:29.602797Z","end":"2026-02-26T06:31:29.750709Z","steps":["trace[1545945972] 'agreement among raft nodes before linearized reading' (duration: 147.834693ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:29.750593Z","caller":"traceutil/trace.go:171","msg":"trace[1795883769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7329; }","duration":"295.307488ms","start":"2026-02-26T06:31:29.455267Z","end":"2026-02-26T06:31:29.750575Z","steps":["trace[1795883769] 'agreement among raft nodes before linearized reading' (duration: 295.196876ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:29.750798Z","caller":"traceutil/trace.go:171","msg":"trace[1572709939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7329; }","duration":"255.397373ms","start":"2026-02-26T06:31:29.495377Z","end":"2026-02-26T06:31:29.750774Z","steps":["trace[1572709939] 'agreement among raft nodes before linearized reading' (duration: 255.181599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:29.750822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:29.396087Z","time spent":"354.593264ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:31:29.855882Z","caller":"traceutil/trace.go:171","msg":"trace[2124605589] transaction","detail":"{read_only:false; response_revision:7331; number_of_response:1; }","duration":"100.692741ms","start":"2026-02-26T06:31:29.755162Z","end":"2026-02-26T06:31:29.855855Z","steps":["trace[2124605589] 'process raft request' (duration: 100.554969ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:29.856201Z","caller":"traceutil/trace.go:171","msg":"trace[1678395265] transaction","detail":"{read_only:false; response_revision:7332; number_of_response:1; }","duration":"100.832834ms","start":"2026-02-26T06:31:29.755349Z","end":"2026-02-26T06:31:29.856181Z","steps":["trace[1678395265] 'process raft request' (duration: 100.458566ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:29.856356Z","caller":"traceutil/trace.go:171","msg":"trace[911720645] transaction","detail":"{read_only:false; response_revision:7334; number_of_response:1; }","duration":"100.745183ms","start":"2026-02-26T06:31:29.755591Z","end":"2026-02-26T06:31:29.856336Z","steps":["trace[911720645] 'process raft request' (duration: 100.621761ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:29.856367Z","caller":"traceutil/trace.go:171","msg":"trace[2030579664] transaction","detail":"{read_only:false; response_revision:7333; number_of_response:1; }","duration":"100.789114ms","start":"2026-02-26T06:31:29.755484Z","end":"2026-02-26T06:31:29.856273Z","steps":["trace[2030579664] 'process raft request' (duration: 100.59478ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:56.174537Z","caller":"traceutil/trace.go:171","msg":"trace[152153362] transaction","detail":"{read_only:false; response_revision:7512; number_of_response:1; }","duration":"108.470258ms","start":"2026-02-26T06:31:56.066042Z","end":"2026-02-26T06:31:56.174512Z","steps":["trace[152153362] 'process raft request' (duration: 108.252164ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:56.174715Z","caller":"traceutil/trace.go:171","msg":"trace[899629389] transaction","detail":"{read_only:false; response_revision:7513; number_of_response:1; }","duration":"107.916667ms","start":"2026-02-26T06:31:56.066789Z","end":"2026-02-26T06:31:56.174705Z","steps":["trace[899629389] 'process raft request' (duration: 107.681543ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:31:56.174717Z","caller":"traceutil/trace.go:171","msg":"trace[84098641] transaction","detail":"{read_only:false; response_revision:7514; number_of_response:1; }","duration":"106.60661ms","start":"2026-02-26T06:31:56.0681Z","end":"2026-02-26T06:31:56.174706Z","steps":["trace[84098641] 'process raft request' (duration: 106.551019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.978182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.475817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:31:56.978295Z","caller":"traceutil/trace.go:171","msg":"trace[1447146288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7514; }","duration":"443.65913ms","start":"2026-02-26T06:31:56.534615Z","end":"2026-02-26T06:31:56.978274Z","steps":["trace[1447146288] 'range keys from in-memory index tree' (duration: 443.311714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:31:56.978336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:31:56.534583Z","time spent":"443.744012ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:32:09.218776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.888736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:32:09.219333Z","caller":"traceutil/trace.go:171","msg":"trace[256634413] linearizableReadLoop","detail":"{readStateIndex:7894; appliedIndex:7892; }","duration":"235.269756ms","start":"2026-02-26T06:32:08.984033Z","end":"2026-02-26T06:32:09.219303Z","steps":["trace[256634413] 'read index received' (duration: 79.670167ms)","trace[256634413] 'applied index is now lower than readState.Index' (duration: 155.597769ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:09.21939Z","caller":"traceutil/trace.go:171","msg":"trace[382895968] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7613; }","duration":"238.616014ms","start":"2026-02-26T06:32:08.980728Z","end":"2026-02-26T06:32:09.219344Z","steps":["trace[382895968] 'process raft request' (duration: 238.241667ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:09.219413Z","caller":"traceutil/trace.go:171","msg":"trace[2066394598] transaction","detail":"{read_only:false; response_revision:7612; number_of_response:1; }","duration":"239.225997ms","start":"2026-02-26T06:32:08.980146Z","end":"2026-02-26T06:32:09.219372Z","steps":["trace[2066394598] 'process raft request' (duration: 83.541695ms)","trace[2066394598] 'compare' (duration: 154.593719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:32:09.21955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.511171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:1097"} {"level":"info","ts":"2026-02-26T06:32:09.219612Z","caller":"traceutil/trace.go:171","msg":"trace[46636009] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:7613; }","duration":"235.599693ms","start":"2026-02-26T06:32:08.983982Z","end":"2026-02-26T06:32:09.219581Z","steps":["trace[46636009] 'agreement among raft nodes before linearized reading' (duration: 235.405489ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:09.595741Z","caller":"traceutil/trace.go:171","msg":"trace[36575415] transaction","detail":"{read_only:false; response_revision:7616; number_of_response:1; }","duration":"274.063829ms","start":"2026-02-26T06:32:09.321645Z","end":"2026-02-26T06:32:09.595709Z","steps":["trace[36575415] 'process raft request' (duration: 210.19806ms)","trace[36575415] 'compare' (duration: 63.562493ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:09.904971Z","caller":"traceutil/trace.go:171","msg":"trace[2063978548] transaction","detail":"{read_only:false; response_revision:7618; number_of_response:1; }","duration":"147.021786ms","start":"2026-02-26T06:32:09.757921Z","end":"2026-02-26T06:32:09.904942Z","steps":["trace[2063978548] 'process raft request' (duration: 134.248498ms)","trace[2063978548] 'compare' (duration: 12.640964ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:32:10.559018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.534714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-26T06:32:10.559045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.245874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:32:10.559101Z","caller":"traceutil/trace.go:171","msg":"trace[367422916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7622; }","duration":"307.679768ms","start":"2026-02-26T06:32:10.251403Z","end":"2026-02-26T06:32:10.559083Z","steps":["trace[367422916] 'range keys from in-memory index tree' (duration: 307.402303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:10.559145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.251392Z","time spent":"307.743279ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:32:10.559317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.807156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T06:32:10.559476Z","caller":"traceutil/trace.go:171","msg":"trace[1472885921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7622; }","duration":"286.974869ms","start":"2026-02-26T06:32:10.272481Z","end":"2026-02-26T06:32:10.559456Z","steps":["trace[1472885921] 'range keys from in-memory index tree' (duration: 286.571522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:10.559582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.154098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T06:32:10.559642Z","caller":"traceutil/trace.go:171","msg":"trace[1929093175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7622; }","duration":"376.23352ms","start":"2026-02-26T06:32:10.183395Z","end":"2026-02-26T06:32:10.559629Z","steps":["trace[1929093175] 'range keys from in-memory index tree' (duration: 376.057486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:10.559691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.183385Z","time spent":"376.295492ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T06:32:10.559139Z","caller":"traceutil/trace.go:171","msg":"trace[1367941246] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7622; }","duration":"397.438518ms","start":"2026-02-26T06:32:10.161674Z","end":"2026-02-26T06:32:10.559112Z","steps":["trace[1367941246] 'count revisions from in-memory index tree' (duration: 397.163663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:10.559844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.161653Z","time spent":"398.133492ms","remote":"127.0.0.1:37390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:32:10.55987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.414162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dw469\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T06:32:10.560012Z","caller":"traceutil/trace.go:171","msg":"trace[890338954] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dw469; range_end:; response_count:1; response_revision:7622; }","duration":"368.597385ms","start":"2026-02-26T06:32:10.191382Z","end":"2026-02-26T06:32:10.559979Z","steps":["trace[890338954] 'range keys from in-memory index tree' (duration: 368.200798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:10.56002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.117102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T06:32:10.560151Z","caller":"traceutil/trace.go:171","msg":"trace[1012480454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7622; }","duration":"287.301556ms","start":"2026-02-26T06:32:10.272822Z","end":"2026-02-26T06:32:10.560123Z","steps":["trace[1012480454] 'range keys from in-memory index tree' (duration: 286.872897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:10.560074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.191372Z","time spent":"368.689948ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-dw469\" "} {"level":"info","ts":"2026-02-26T06:32:11.179857Z","caller":"traceutil/trace.go:171","msg":"trace[1734757837] transaction","detail":"{read_only:false; response_revision:7623; number_of_response:1; }","duration":"615.077699ms","start":"2026-02-26T06:32:10.564757Z","end":"2026-02-26T06:32:11.179835Z","steps":["trace[1734757837] 'process raft request' (duration: 614.893946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:11.179993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.564732Z","time spent":"615.190152ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:32:11.180132Z","caller":"traceutil/trace.go:171","msg":"trace[558973421] linearizableReadLoop","detail":"{readStateIndex:7907; appliedIndex:7904; }","duration":"615.019928ms","start":"2026-02-26T06:32:10.565089Z","end":"2026-02-26T06:32:11.180109Z","steps":["trace[558973421] 'read index received' (duration: 68.838179ms)","trace[558973421] 'applied index is now lower than readState.Index' (duration: 546.180579ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:11.180173Z","caller":"traceutil/trace.go:171","msg":"trace[2015332603] transaction","detail":"{read_only:false; response_revision:7624; number_of_response:1; }","duration":"615.213492ms","start":"2026-02-26T06:32:10.564896Z","end":"2026-02-26T06:32:11.18011Z","steps":["trace[2015332603] 'process raft request' (duration: 614.901616ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:11.18026Z","caller":"traceutil/trace.go:171","msg":"trace[1733399276] transaction","detail":"{read_only:false; response_revision:7625; number_of_response:1; }","duration":"615.231603ms","start":"2026-02-26T06:32:10.564915Z","end":"2026-02-26T06:32:11.180146Z","steps":["trace[1733399276] 'process raft request' (duration: 615.067119ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:11.180216Z","caller":"traceutil/trace.go:171","msg":"trace[2128605763] transaction","detail":"{read_only:false; response_revision:7626; number_of_response:1; }","duration":"614.667702ms","start":"2026-02-26T06:32:10.565537Z","end":"2026-02-26T06:32:11.180205Z","steps":["trace[2128605763] 'process raft request' (duration: 614.528159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:11.180477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.564889Z","time spent":"615.393405ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:32:11.180578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.565515Z","time spent":"614.908096ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:32:11.180721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.564875Z","time spent":"615.57245ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:32:11.180836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.736633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:211649"} {"level":"info","ts":"2026-02-26T06:32:11.180907Z","caller":"traceutil/trace.go:171","msg":"trace[1287253010] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7626; }","duration":"615.831584ms","start":"2026-02-26T06:32:10.565057Z","end":"2026-02-26T06:32:11.180889Z","steps":["trace[1287253010] 'agreement among raft nodes before linearized reading' (duration: 615.179471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:11.180958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.565047Z","time spent":"615.900345ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":211673,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-02-26T06:32:11.190316Z","caller":"traceutil/trace.go:171","msg":"trace[591562693] transaction","detail":"{read_only:false; response_revision:7627; number_of_response:1; }","duration":"555.050347ms","start":"2026-02-26T06:32:10.635225Z","end":"2026-02-26T06:32:11.190276Z","steps":["trace[591562693] 'process raft request' (duration: 554.877784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:11.190402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.003138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:32:11.190443Z","caller":"traceutil/trace.go:171","msg":"trace[1277182318] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:7627; }","duration":"446.10022ms","start":"2026-02-26T06:32:10.744331Z","end":"2026-02-26T06:32:11.190432Z","steps":["trace[1277182318] 'agreement among raft nodes before linearized reading' (duration: 445.992807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:11.190469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.744303Z","time spent":"446.159901ms","remote":"127.0.0.1:54546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:32:11.190509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:10.635199Z","time spent":"555.208901ms","remote":"127.0.0.1:55004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:32:11.41415Z","caller":"traceutil/trace.go:171","msg":"trace[638379421] transaction","detail":"{read_only:false; response_revision:7628; number_of_response:1; }","duration":"182.681345ms","start":"2026-02-26T06:32:11.231423Z","end":"2026-02-26T06:32:11.414104Z","steps":["trace[638379421] 'process raft request' (duration: 182.4356ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:12.447442Z","caller":"traceutil/trace.go:171","msg":"trace[1454150561] transaction","detail":"{read_only:false; response_revision:7635; number_of_response:1; }","duration":"175.297586ms","start":"2026-02-26T06:32:12.272122Z","end":"2026-02-26T06:32:12.44742Z","steps":["trace[1454150561] 'process raft request' (duration: 175.068952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:13.040536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.06774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:32:13.040601Z","caller":"traceutil/trace.go:171","msg":"trace[826539156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7635; }","duration":"507.204252ms","start":"2026-02-26T06:32:12.533382Z","end":"2026-02-26T06:32:13.040586Z","steps":["trace[826539156] 'range keys from in-memory index tree' (duration: 506.972048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:13.040632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:32:12.533352Z","time spent":"507.270104ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:32:13.8258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.915732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:32:13.826437Z","caller":"traceutil/trace.go:171","msg":"trace[1990642939] transaction","detail":"{read_only:false; response_revision:7643; number_of_response:1; }","duration":"268.382414ms","start":"2026-02-26T06:32:13.558022Z","end":"2026-02-26T06:32:13.826405Z","steps":["trace[1990642939] 'process raft request' (duration: 11.745327ms)","trace[1990642939] 'compare' (duration: 255.7723ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:13.826642Z","caller":"traceutil/trace.go:171","msg":"trace[572021318] transaction","detail":"{read_only:false; response_revision:7644; number_of_response:1; }","duration":"176.03268ms","start":"2026-02-26T06:32:13.650595Z","end":"2026-02-26T06:32:13.826628Z","steps":["trace[572021318] 'process raft request' (duration: 175.697064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:32:13.826941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.568589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-02-26T06:32:13.826988Z","caller":"traceutil/trace.go:171","msg":"trace[1750478235] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7644; }","duration":"162.655871ms","start":"2026-02-26T06:32:13.664317Z","end":"2026-02-26T06:32:13.826973Z","steps":["trace[1750478235] 'agreement among raft nodes before linearized reading' (duration: 162.511358ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:13.826523Z","caller":"traceutil/trace.go:171","msg":"trace[134842291] linearizableReadLoop","detail":"{readStateIndex:7926; appliedIndex:7924; }","duration":"162.13166ms","start":"2026-02-26T06:32:13.664375Z","end":"2026-02-26T06:32:13.826507Z","steps":["trace[134842291] 'read index received' (duration: 102.478657ms)","trace[134842291] 'applied index is now lower than readState.Index' (duration: 59.651883ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:32:14.257193Z","caller":"traceutil/trace.go:171","msg":"trace[31967374] transaction","detail":"{read_only:false; response_revision:7647; number_of_response:1; }","duration":"107.711752ms","start":"2026-02-26T06:32:14.149456Z","end":"2026-02-26T06:32:14.257168Z","steps":["trace[31967374] 'process raft request' (duration: 107.170812ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:14.272104Z","caller":"traceutil/trace.go:171","msg":"trace[544995252] transaction","detail":"{read_only:false; response_revision:7648; number_of_response:1; }","duration":"121.767416ms","start":"2026-02-26T06:32:14.150307Z","end":"2026-02-26T06:32:14.272074Z","steps":["trace[544995252] 'process raft request' (duration: 121.606593ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:32:27.462367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5634} {"level":"info","ts":"2026-02-26T06:32:27.555436Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5634,"took":"90.824651ms","hash":2059555282,"current-db-size-bytes":43479040,"current-db-size":"44 MB","current-db-size-in-use-bytes":23130112,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T06:32:27.555534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2059555282,"revision":5634,"compact-revision":2632} {"level":"info","ts":"2026-02-26T06:33:02.42865Z","caller":"traceutil/trace.go:171","msg":"trace[1881008237] transaction","detail":"{read_only:false; response_revision:8143; number_of_response:1; }","duration":"113.339411ms","start":"2026-02-26T06:33:02.315283Z","end":"2026-02-26T06:33:02.428622Z","steps":["trace[1881008237] 'process raft request' (duration: 113.122737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:33:12.866665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.150694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:33:12.866767Z","caller":"traceutil/trace.go:171","msg":"trace[1262386267] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:8196; }","duration":"285.308148ms","start":"2026-02-26T06:33:12.581439Z","end":"2026-02-26T06:33:12.866748Z","steps":["trace[1262386267] 'count revisions from in-memory index tree' (duration: 285.011672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:33:12.866886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.285303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T06:33:12.86698Z","caller":"traceutil/trace.go:171","msg":"trace[1517956934] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8196; }","duration":"249.448146ms","start":"2026-02-26T06:33:12.617508Z","end":"2026-02-26T06:33:12.866956Z","steps":["trace[1517956934] 'range keys from in-memory index tree' (duration: 248.982627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:33:12.867102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.026313ms","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-02-26T06:33:12.867193Z","caller":"traceutil/trace.go:171","msg":"trace[1926704031] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8196; }","duration":"262.405321ms","start":"2026-02-26T06:33:12.604768Z","end":"2026-02-26T06:33:12.867173Z","steps":["trace[1926704031] 'range keys from in-memory index tree' (duration: 261.87251ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:33:13.060571Z","caller":"traceutil/trace.go:171","msg":"trace[931832566] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"189.092745ms","start":"2026-02-26T06:33:12.871453Z","end":"2026-02-26T06:33:13.060545Z","steps":["trace[931832566] 'process raft request' (duration: 188.916051ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:33:13.166487Z","caller":"traceutil/trace.go:171","msg":"trace[103602064] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"294.932573ms","start":"2026-02-26T06:33:12.871517Z","end":"2026-02-26T06:33:13.16645Z","steps":["trace[103602064] 'process raft request' (duration: 227.007379ms)","trace[103602064] 'compare' (duration: 67.756401ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:35:32.418488Z","caller":"traceutil/trace.go:171","msg":"trace[663046655] transaction","detail":"{read_only:false; response_revision:9281; number_of_response:1; }","duration":"154.359039ms","start":"2026-02-26T06:35:32.264106Z","end":"2026-02-26T06:35:32.418465Z","steps":["trace[663046655] 'process raft request' (duration: 144.810705ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:35:32.418577Z","caller":"traceutil/trace.go:171","msg":"trace[1112987991] transaction","detail":"{read_only:false; response_revision:9282; number_of_response:1; }","duration":"153.743246ms","start":"2026-02-26T06:35:32.264767Z","end":"2026-02-26T06:35:32.418511Z","steps":["trace[1112987991] 'process raft request' (duration: 153.628264ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:36:18.645913Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T06:36:18.653869Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T06:36:18.653996Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-02-26T06:36:35.539474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.102364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:36:35.539708Z","caller":"traceutil/trace.go:171","msg":"trace[192648126] transaction","detail":"{read_only:false; response_revision:9926; number_of_response:1; }","duration":"240.277353ms","start":"2026-02-26T06:36:35.299393Z","end":"2026-02-26T06:36:35.53967Z","steps":["trace[192648126] 'process raft request' (duration: 101.898283ms)","trace[192648126] 'compare' (duration: 137.950852ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:36:35.540172Z","caller":"traceutil/trace.go:171","msg":"trace[1091286291] linearizableReadLoop","detail":"{readStateIndex:10276; appliedIndex:10275; }","duration":"181.844336ms","start":"2026-02-26T06:36:35.358307Z","end":"2026-02-26T06:36:35.540151Z","steps":["trace[1091286291] 'read index received' (duration: 42.884174ms)","trace[1091286291] 'applied index is now lower than readState.Index' (duration: 138.957812ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:36:35.540322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.753418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T06:36:35.540337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.020949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:36:35.540418Z","caller":"traceutil/trace.go:171","msg":"trace[1720649715] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:9926; }","duration":"182.165322ms","start":"2026-02-26T06:36:35.358238Z","end":"2026-02-26T06:36:35.540404Z","steps":["trace[1720649715] 'agreement among raft nodes before linearized reading' (duration: 181.995848ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:36:35.540367Z","caller":"traceutil/trace.go:171","msg":"trace[401011858] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:9926; }","duration":"149.86084ms","start":"2026-02-26T06:36:35.390495Z","end":"2026-02-26T06:36:35.540356Z","steps":["trace[401011858] 'agreement among raft nodes before linearized reading' (duration: 149.768618ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:36:48.446539Z","caller":"traceutil/trace.go:171","msg":"trace[91101944] linearizableReadLoop","detail":"{readStateIndex:10343; appliedIndex:10342; }","duration":"195.671905ms","start":"2026-02-26T06:36:48.250848Z","end":"2026-02-26T06:36:48.44652Z","steps":["trace[91101944] 'read index received' (duration: 195.495331ms)","trace[91101944] 'applied index is now lower than readState.Index' (duration: 175.704µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:36:48.446656Z","caller":"traceutil/trace.go:171","msg":"trace[526693069] transaction","detail":"{read_only:false; response_revision:9989; number_of_response:1; }","duration":"222.934753ms","start":"2026-02-26T06:36:48.2237Z","end":"2026-02-26T06:36:48.446634Z","steps":["trace[526693069] 'process raft request' (duration: 222.693078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:36:48.446896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.989361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T06:36:48.446989Z","caller":"traceutil/trace.go:171","msg":"trace[558059492] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9989; }","duration":"196.151433ms","start":"2026-02-26T06:36:48.250811Z","end":"2026-02-26T06:36:48.446963Z","steps":["trace[558059492] 'agreement among raft nodes before linearized reading' (duration: 195.853117ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:37:27.473167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7843} {"level":"info","ts":"2026-02-26T06:37:27.540307Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7843,"took":"64.706374ms","hash":3844154172,"current-db-size-bytes":43479040,"current-db-size":"44 MB","current-db-size-in-use-bytes":27824128,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T06:37:27.540369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3844154172,"revision":7843,"compact-revision":5634} {"level":"info","ts":"2026-02-26T06:42:27.486024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10263} {"level":"info","ts":"2026-02-26T06:42:27.569043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10263,"took":"80.482659ms","hash":822189361,"current-db-size-bytes":43479040,"current-db-size":"44 MB","current-db-size-in-use-bytes":24559616,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-02-26T06:42:27.569099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":822189361,"revision":10263,"compact-revision":7843} {"level":"info","ts":"2026-02-26T06:42:56.273108Z","caller":"traceutil/trace.go:171","msg":"trace[1672813666] transaction","detail":"{read_only:false; response_revision:12296; number_of_response:1; }","duration":"127.435285ms","start":"2026-02-26T06:42:56.145635Z","end":"2026-02-26T06:42:56.27307Z","steps":["trace[1672813666] 'process raft request' (duration: 127.18045ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:42:56.805944Z","caller":"traceutil/trace.go:171","msg":"trace[2146319099] linearizableReadLoop","detail":"{readStateIndex:12739; appliedIndex:12738; }","duration":"440.576585ms","start":"2026-02-26T06:42:56.365346Z","end":"2026-02-26T06:42:56.805923Z","steps":["trace[2146319099] 'read index received' (duration: 440.206577ms)","trace[2146319099] 'applied index is now lower than readState.Index' (duration: 369.328µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:42:56.806259Z","caller":"traceutil/trace.go:171","msg":"trace[625201522] transaction","detail":"{read_only:false; response_revision:12297; number_of_response:1; }","duration":"528.879962ms","start":"2026-02-26T06:42:56.277353Z","end":"2026-02-26T06:42:56.806233Z","steps":["trace[625201522] 'process raft request' (duration: 528.442033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:42:56.80656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.554517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-02-26T06:42:56.806622Z","caller":"traceutil/trace.go:171","msg":"trace[587850643] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:12297; }","duration":"381.653429ms","start":"2026-02-26T06:42:56.424954Z","end":"2026-02-26T06:42:56.806607Z","steps":["trace[587850643] 'agreement among raft nodes before linearized reading' (duration: 381.520377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:42:56.806647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:42:56.42494Z","time spent":"381.7033ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-02-26T06:42:56.806645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.887223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-02-26T06:42:56.806662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.675894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T06:42:56.80671Z","caller":"traceutil/trace.go:171","msg":"trace[897561511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12297; }","duration":"207.980675ms","start":"2026-02-26T06:42:56.598712Z","end":"2026-02-26T06:42:56.806692Z","steps":["trace[897561511] 'agreement among raft nodes before linearized reading' (duration: 207.796081ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:42:56.806725Z","caller":"traceutil/trace.go:171","msg":"trace[1403976207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12297; }","duration":"246.776745ms","start":"2026-02-26T06:42:56.559931Z","end":"2026-02-26T06:42:56.806708Z","steps":["trace[1403976207] 'agreement among raft nodes before linearized reading' (duration: 246.583191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:42:56.806805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.486213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:42:56.80687Z","caller":"traceutil/trace.go:171","msg":"trace[1337637538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12297; }","duration":"272.586195ms","start":"2026-02-26T06:42:56.534267Z","end":"2026-02-26T06:42:56.806853Z","steps":["trace[1337637538] 'agreement among raft nodes before linearized reading' (duration: 272.487783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:42:56.80691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.563574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T06:42:56.806935Z","caller":"traceutil/trace.go:171","msg":"trace[864603893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12297; }","duration":"441.610056ms","start":"2026-02-26T06:42:56.365319Z","end":"2026-02-26T06:42:56.806929Z","steps":["trace[864603893] 'agreement among raft nodes before linearized reading' (duration: 441.546314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:42:56.806954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:42:56.365303Z","time spent":"441.647836ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T06:42:56.80695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.165502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-26T06:42:56.806982Z","caller":"traceutil/trace.go:171","msg":"trace[2069871174] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12297; }","duration":"239.214373ms","start":"2026-02-26T06:42:56.567758Z","end":"2026-02-26T06:42:56.806973Z","steps":["trace[2069871174] 'agreement among raft nodes before linearized reading' (duration: 239.123741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:42:56.807164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.987235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T06:42:56.807187Z","caller":"traceutil/trace.go:171","msg":"trace[1783214000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12297; }","duration":"208.008876ms","start":"2026-02-26T06:42:56.599171Z","end":"2026-02-26T06:42:56.80718Z","steps":["trace[1783214000] 'agreement among raft nodes before linearized reading' (duration: 207.960705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:42:56.806373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:42:56.277334Z","time spent":"528.953393ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:43:03.033447Z","caller":"traceutil/trace.go:171","msg":"trace[1058175882] transaction","detail":"{read_only:false; response_revision:12326; number_of_response:1; }","duration":"194.048797ms","start":"2026-02-26T06:43:02.839371Z","end":"2026-02-26T06:43:03.03342Z","steps":["trace[1058175882] 'process raft request' (duration: 193.836493ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:43:03.237253Z","caller":"traceutil/trace.go:171","msg":"trace[1042209166] transaction","detail":"{read_only:false; response_revision:12327; number_of_response:1; }","duration":"382.166493ms","start":"2026-02-26T06:43:02.855057Z","end":"2026-02-26T06:43:03.237224Z","steps":["trace[1042209166] 'process raft request' (duration: 381.977899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:03.237412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:43:02.85502Z","time spent":"382.310325ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:43:03.237465Z","caller":"traceutil/trace.go:171","msg":"trace[1830435193] transaction","detail":"{read_only:false; response_revision:12328; number_of_response:1; }","duration":"382.368316ms","start":"2026-02-26T06:43:02.855082Z","end":"2026-02-26T06:43:03.237451Z","steps":["trace[1830435193] 'process raft request' (duration: 382.102961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:03.237516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:43:02.855066Z","time spent":"382.424867ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:43:03.23763Z","caller":"traceutil/trace.go:171","msg":"trace[939829596] transaction","detail":"{read_only:false; response_revision:12329; number_of_response:1; }","duration":"382.463858ms","start":"2026-02-26T06:43:02.855156Z","end":"2026-02-26T06:43:03.23762Z","steps":["trace[939829596] 'process raft request' (duration: 382.222604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:03.237703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:43:02.855135Z","time spent":"382.533249ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:43:03.237779Z","caller":"traceutil/trace.go:171","msg":"trace[629312763] transaction","detail":"{read_only:false; response_revision:12330; number_of_response:1; }","duration":"380.746515ms","start":"2026-02-26T06:43:02.857015Z","end":"2026-02-26T06:43:03.237762Z","steps":["trace[629312763] 'process raft request' (duration: 380.532841ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:43:03.237832Z","caller":"traceutil/trace.go:171","msg":"trace[1147067679] transaction","detail":"{read_only:false; response_revision:12331; number_of_response:1; }","duration":"247.895599ms","start":"2026-02-26T06:43:02.98993Z","end":"2026-02-26T06:43:03.237825Z","steps":["trace[1147067679] 'process raft request' (duration: 247.798207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:03.237882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:43:02.856996Z","time spent":"380.830497ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:43:10.743198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.791291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-26T06:43:10.743221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.48118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:43:10.743275Z","caller":"traceutil/trace.go:171","msg":"trace[1380841163] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:12376; }","duration":"168.901344ms","start":"2026-02-26T06:43:10.574357Z","end":"2026-02-26T06:43:10.743259Z","steps":["trace[1380841163] 'range keys from in-memory index tree' (duration: 168.592098ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:43:10.74329Z","caller":"traceutil/trace.go:171","msg":"trace[763489594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12376; }","duration":"190.607901ms","start":"2026-02-26T06:43:10.552666Z","end":"2026-02-26T06:43:10.743274Z","steps":["trace[763489594] 'range keys from in-memory index tree' (duration: 190.403168ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:43:14.480477Z","caller":"traceutil/trace.go:171","msg":"trace[1304875436] transaction","detail":"{read_only:false; response_revision:12403; number_of_response:1; }","duration":"132.671363ms","start":"2026-02-26T06:43:14.347781Z","end":"2026-02-26T06:43:14.480452Z","steps":["trace[1304875436] 'process raft request' (duration: 132.5011ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:43:30.897606Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000003310.wal"} {"level":"warn","ts":"2026-02-26T06:43:32.381075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.737202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf10630b-9a23-45ae-95b5-953d81542244\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-02-26T06:43:32.381168Z","caller":"traceutil/trace.go:171","msg":"trace[1753652987] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf10630b-9a23-45ae-95b5-953d81542244; range_end:; response_count:1; response_revision:12673; }","duration":"333.885995ms","start":"2026-02-26T06:43:32.047264Z","end":"2026-02-26T06:43:32.38115Z","steps":["trace[1753652987] 'range keys from in-memory index tree' (duration: 333.557889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:32.381213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:43:32.04724Z","time spent":"333.963946ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3660,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf10630b-9a23-45ae-95b5-953d81542244\" "} {"level":"warn","ts":"2026-02-26T06:43:32.381435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.652281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dw469\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-02-26T06:43:32.381526Z","caller":"traceutil/trace.go:171","msg":"trace[1972496685] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dw469; range_end:; response_count:1; response_revision:12673; }","duration":"282.791864ms","start":"2026-02-26T06:43:32.098718Z","end":"2026-02-26T06:43:32.38151Z","steps":["trace[1972496685] 'range keys from in-memory index tree' (duration: 282.409437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:43:32.381558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.664012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf10630b-9a23-45ae-95b5-953d81542244\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-02-26T06:43:32.381616Z","caller":"traceutil/trace.go:171","msg":"trace[1530731428] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf10630b-9a23-45ae-95b5-953d81542244; range_end:; response_count:1; response_revision:12673; }","duration":"174.769934ms","start":"2026-02-26T06:43:32.206831Z","end":"2026-02-26T06:43:32.381601Z","steps":["trace[1530731428] 'range keys from in-memory index tree' (duration: 174.50677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:21.783813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.49191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:44:21.784052Z","caller":"traceutil/trace.go:171","msg":"trace[616570868] transaction","detail":"{read_only:false; response_revision:13265; number_of_response:1; }","duration":"145.769691ms","start":"2026-02-26T06:44:21.638251Z","end":"2026-02-26T06:44:21.78402Z","steps":["trace[616570868] 'process raft request' (duration: 30.004004ms)","trace[616570868] 'compare' (duration: 115.305157ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:44:56.105193Z","caller":"traceutil/trace.go:171","msg":"trace[2137932294] linearizableReadLoop","detail":"{readStateIndex:13910; appliedIndex:13909; }","duration":"172.829887ms","start":"2026-02-26T06:44:55.932338Z","end":"2026-02-26T06:44:56.105167Z","steps":["trace[2137932294] 'read index received' (duration: 138.422906ms)","trace[2137932294] 'applied index is now lower than readState.Index' (duration: 34.405791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:44:56.105406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.346095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-02-26T06:44:56.105406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.053622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T06:44:56.105432Z","caller":"traceutil/trace.go:171","msg":"trace[1334685898] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13428; }","duration":"162.408097ms","start":"2026-02-26T06:44:55.943014Z","end":"2026-02-26T06:44:56.105422Z","steps":["trace[1334685898] 'agreement among raft nodes before linearized reading' (duration: 162.288355ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:44:56.105462Z","caller":"traceutil/trace.go:171","msg":"trace[774443009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13428; }","duration":"173.145483ms","start":"2026-02-26T06:44:55.9323Z","end":"2026-02-26T06:44:56.105446Z","steps":["trace[774443009] 'agreement among raft nodes before linearized reading' (duration: 172.959329ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:44:56.326668Z","caller":"traceutil/trace.go:171","msg":"trace[957509638] transaction","detail":"{read_only:false; response_revision:13429; number_of_response:1; }","duration":"217.573287ms","start":"2026-02-26T06:44:56.109075Z","end":"2026-02-26T06:44:56.326648Z","steps":["trace[957509638] 'process raft request' (duration: 217.403734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:56.613714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.597104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:44:56.613815Z","caller":"traceutil/trace.go:171","msg":"trace[255127991] linearizableReadLoop","detail":"{readStateIndex:13912; appliedIndex:13910; }","duration":"364.38491ms","start":"2026-02-26T06:44:56.249413Z","end":"2026-02-26T06:44:56.613798Z","steps":["trace[255127991] 'read index received' (duration: 77.116751ms)","trace[255127991] 'applied index is now lower than readState.Index' (duration: 287.267089ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:44:56.6139Z","caller":"traceutil/trace.go:171","msg":"trace[494217024] transaction","detail":"{read_only:false; response_revision:13430; number_of_response:1; }","duration":"504.791105ms","start":"2026-02-26T06:44:56.109097Z","end":"2026-02-26T06:44:56.613888Z","steps":["trace[494217024] 'process raft request' (duration: 326.938216ms)","trace[494217024] 'compare' (duration: 177.485762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:44:56.613965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:44:56.109076Z","time spent":"504.854187ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:44:56.614091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.694527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-02-26T06:44:56.614199Z","caller":"traceutil/trace.go:171","msg":"trace[1755956568] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:13430; }","duration":"280.83621ms","start":"2026-02-26T06:44:56.333347Z","end":"2026-02-26T06:44:56.614184Z","steps":["trace[1755956568] 'agreement among raft nodes before linearized reading' (duration: 280.570885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:56.614339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.904941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mrfgl\" ","response":"range_response_count:1 size:9795"} {"level":"info","ts":"2026-02-26T06:44:56.614444Z","caller":"traceutil/trace.go:171","msg":"trace[317054612] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mrfgl; range_end:; response_count:1; response_revision:13430; }","duration":"365.061163ms","start":"2026-02-26T06:44:56.249358Z","end":"2026-02-26T06:44:56.614419Z","steps":["trace[317054612] 'agreement among raft nodes before linearized reading' (duration: 364.693736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:56.614495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:44:56.249336Z","time spent":"365.150446ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9819,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-mrfgl\" "} {"level":"info","ts":"2026-02-26T06:44:57.820042Z","caller":"traceutil/trace.go:171","msg":"trace[804679981] linearizableReadLoop","detail":"{readStateIndex:13915; appliedIndex:13914; }","duration":"136.089487ms","start":"2026-02-26T06:44:57.683925Z","end":"2026-02-26T06:44:57.820014Z","steps":["trace[804679981] 'read index received' (duration: 135.887333ms)","trace[804679981] 'applied index is now lower than readState.Index' (duration: 201.274µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:44:57.820739Z","caller":"traceutil/trace.go:171","msg":"trace[1271857866] transaction","detail":"{read_only:false; response_revision:13433; number_of_response:1; }","duration":"142.251792ms","start":"2026-02-26T06:44:57.678448Z","end":"2026-02-26T06:44:57.8207Z","steps":["trace[1271857866] 'process raft request' (duration: 141.415254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:57.820875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.900904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:44:57.820975Z","caller":"traceutil/trace.go:171","msg":"trace[1787971295] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:13433; }","duration":"137.057557ms","start":"2026-02-26T06:44:57.683902Z","end":"2026-02-26T06:44:57.82096Z","steps":["trace[1787971295] 'agreement among raft nodes before linearized reading' (duration: 136.868743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:57.821236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.988446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:44:57.821281Z","caller":"traceutil/trace.go:171","msg":"trace[2025678377] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:13433; }","duration":"137.078248ms","start":"2026-02-26T06:44:57.68419Z","end":"2026-02-26T06:44:57.821268Z","steps":["trace[2025678377] 'agreement among raft nodes before linearized reading' (duration: 136.995867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:57.821435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.17004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:44:57.821531Z","caller":"traceutil/trace.go:171","msg":"trace[123495577] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13433; }","duration":"137.311502ms","start":"2026-02-26T06:44:57.684196Z","end":"2026-02-26T06:44:57.821508Z","steps":["trace[123495577] 'agreement among raft nodes before linearized reading' (duration: 137.157209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:44:57.82159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.306111ms","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-02-26T06:44:57.821641Z","caller":"traceutil/trace.go:171","msg":"trace[166155090] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13433; }","duration":"135.356572ms","start":"2026-02-26T06:44:57.686271Z","end":"2026-02-26T06:44:57.821628Z","steps":["trace[166155090] 'agreement among raft nodes before linearized reading' (duration: 134.929924ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:47:27.493311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12119} {"level":"info","ts":"2026-02-26T06:47:27.569625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12119,"took":"73.692211ms","hash":1761859482,"current-db-size-bytes":43479040,"current-db-size":"44 MB","current-db-size-in-use-bytes":27967488,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T06:47:27.569691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1761859482,"revision":12119,"compact-revision":10263} {"level":"warn","ts":"2026-02-26T06:50:13.92695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.848868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:50:13.92706Z","caller":"traceutil/trace.go:171","msg":"trace[1139931544] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:15698; }","duration":"121.031712ms","start":"2026-02-26T06:50:13.806008Z","end":"2026-02-26T06:50:13.92704Z","steps":["trace[1139931544] 'count revisions from in-memory index tree' (duration: 120.692036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:14.866544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.317486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:50:14.866615Z","caller":"traceutil/trace.go:171","msg":"trace[1953032955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15700; }","duration":"332.403998ms","start":"2026-02-26T06:50:14.534196Z","end":"2026-02-26T06:50:14.8666Z","steps":["trace[1953032955] 'range keys from in-memory index tree' (duration: 332.242735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:14.866647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:50:14.534122Z","time spent":"332.518389ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:50:14.866791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.943335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g5dwk\" ","response":"range_response_count:1 size:22127"} {"level":"warn","ts":"2026-02-26T06:50:14.866841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.821406ms","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-02-26T06:50:14.866871Z","caller":"traceutil/trace.go:171","msg":"trace[144694513] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g5dwk; range_end:; response_count:1; response_revision:15700; }","duration":"185.123119ms","start":"2026-02-26T06:50:14.68173Z","end":"2026-02-26T06:50:14.866853Z","steps":["trace[144694513] 'range keys from in-memory index tree' (duration: 184.760062ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:50:14.866894Z","caller":"traceutil/trace.go:171","msg":"trace[1493580647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15700; }","duration":"341.898858ms","start":"2026-02-26T06:50:14.524979Z","end":"2026-02-26T06:50:14.866878Z","steps":["trace[1493580647] 'range keys from in-memory index tree' (duration: 341.704344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:14.866928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:50:14.524963Z","time spent":"341.955699ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T06:50:14.866996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.723565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T06:50:14.86706Z","caller":"traceutil/trace.go:171","msg":"trace[682016454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15700; }","duration":"694.813296ms","start":"2026-02-26T06:50:14.172235Z","end":"2026-02-26T06:50:14.867048Z","steps":["trace[682016454] 'range keys from in-memory index tree' (duration: 694.563462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:14.867086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:50:14.17222Z","time spent":"694.859338ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T06:50:15.075775Z","caller":"traceutil/trace.go:171","msg":"trace[258116063] linearizableReadLoop","detail":"{readStateIndex:16261; appliedIndex:16260; }","duration":"207.917407ms","start":"2026-02-26T06:50:14.867837Z","end":"2026-02-26T06:50:15.075755Z","steps":["trace[258116063] 'read index received' (duration: 207.787255ms)","trace[258116063] 'applied index is now lower than readState.Index' (duration: 129.302µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:50:15.075951Z","caller":"traceutil/trace.go:171","msg":"trace[118090283] transaction","detail":"{read_only:false; response_revision:15701; number_of_response:1; }","duration":"264.246089ms","start":"2026-02-26T06:50:14.811695Z","end":"2026-02-26T06:50:15.075942Z","steps":["trace[118090283] 'process raft request' (duration: 263.943283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:15.076202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.354306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:50:15.07623Z","caller":"traceutil/trace.go:171","msg":"trace[1810227223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15701; }","duration":"208.432838ms","start":"2026-02-26T06:50:14.867789Z","end":"2026-02-26T06:50:15.076222Z","steps":["trace[1810227223] 'agreement among raft nodes before linearized reading' (duration: 208.378426ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:50:15.119361Z","caller":"traceutil/trace.go:171","msg":"trace[1638430811] transaction","detail":"{read_only:false; response_revision:15703; number_of_response:1; }","duration":"247.724695ms","start":"2026-02-26T06:50:14.871617Z","end":"2026-02-26T06:50:15.119341Z","steps":["trace[1638430811] 'process raft request' (duration: 247.45374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:15.119717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.481002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-26T06:50:15.11976Z","caller":"traceutil/trace.go:171","msg":"trace[1680464977] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15703; }","duration":"147.562863ms","start":"2026-02-26T06:50:14.972186Z","end":"2026-02-26T06:50:15.119749Z","steps":["trace[1680464977] 'agreement among raft nodes before linearized reading' (duration: 147.41737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:15.119958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.058462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:93 size:928972"} {"level":"info","ts":"2026-02-26T06:50:15.120036Z","caller":"traceutil/trace.go:171","msg":"trace[1302713852] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:93; response_revision:15703; }","duration":"246.177085ms","start":"2026-02-26T06:50:14.873841Z","end":"2026-02-26T06:50:15.120018Z","steps":["trace[1302713852] 'agreement among raft nodes before linearized reading' (duration: 245.288987ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:50:15.12009Z","caller":"traceutil/trace.go:171","msg":"trace[1607251716] transaction","detail":"{read_only:false; response_revision:15702; number_of_response:1; }","duration":"248.520142ms","start":"2026-02-26T06:50:14.871549Z","end":"2026-02-26T06:50:15.120069Z","steps":["trace[1607251716] 'process raft request' (duration: 247.397989ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:50:15.236628Z","caller":"traceutil/trace.go:171","msg":"trace[1690217519] linearizableReadLoop","detail":"{readStateIndex:16264; appliedIndex:16263; }","duration":"110.640842ms","start":"2026-02-26T06:50:15.12596Z","end":"2026-02-26T06:50:15.236601Z","steps":["trace[1690217519] 'read index received' (duration: 42.891911ms)","trace[1690217519] 'applied index is now lower than readState.Index' (duration: 67.745951ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:50:15.236755Z","caller":"traceutil/trace.go:171","msg":"trace[1249142231] transaction","detail":"{read_only:false; response_revision:15704; number_of_response:1; }","duration":"136.082133ms","start":"2026-02-26T06:50:15.100638Z","end":"2026-02-26T06:50:15.23672Z","steps":["trace[1249142231] 'process raft request' (duration: 68.21684ms)","trace[1249142231] 'compare' (duration: 67.613668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:50:15.236892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.915957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-26T06:50:15.236936Z","caller":"traceutil/trace.go:171","msg":"trace[410607948] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15704; }","duration":"111.000149ms","start":"2026-02-26T06:50:15.125925Z","end":"2026-02-26T06:50:15.236925Z","steps":["trace[410607948] 'agreement among raft nodes before linearized reading' (duration: 110.819286ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:50:23.448009Z","caller":"traceutil/trace.go:171","msg":"trace[1726225437] transaction","detail":"{read_only:false; response_revision:15870; number_of_response:1; }","duration":"190.355983ms","start":"2026-02-26T06:50:23.257619Z","end":"2026-02-26T06:50:23.447975Z","steps":["trace[1726225437] 'process raft request' (duration: 190.098908ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:50:23.449575Z","caller":"traceutil/trace.go:171","msg":"trace[464610549] linearizableReadLoop","detail":"{readStateIndex:16438; appliedIndex:16436; }","duration":"190.17908ms","start":"2026-02-26T06:50:23.25937Z","end":"2026-02-26T06:50:23.449549Z","steps":["trace[464610549] 'read index received' (duration: 188.344963ms)","trace[464610549] 'applied index is now lower than readState.Index' (duration: 1.833357ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:50:23.449733Z","caller":"traceutil/trace.go:171","msg":"trace[1172882452] transaction","detail":"{read_only:false; response_revision:15872; number_of_response:1; }","duration":"180.145918ms","start":"2026-02-26T06:50:23.269529Z","end":"2026-02-26T06:50:23.449675Z","steps":["trace[1172882452] 'process raft request' (duration: 179.966155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:23.449806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.680689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-02-26T06:50:23.449846Z","caller":"traceutil/trace.go:171","msg":"trace[261057970] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:15872; }","duration":"180.794562ms","start":"2026-02-26T06:50:23.269042Z","end":"2026-02-26T06:50:23.449836Z","steps":["trace[261057970] 'agreement among raft nodes before linearized reading' (duration: 180.656169ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:50:23.449844Z","caller":"traceutil/trace.go:171","msg":"trace[1629404840] transaction","detail":"{read_only:false; number_of_response:1; response_revision:15871; }","duration":"191.876674ms","start":"2026-02-26T06:50:23.25795Z","end":"2026-02-26T06:50:23.449827Z","steps":["trace[1629404840] 'process raft request' (duration: 191.427936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:23.4503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.927095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:938652"} {"level":"info","ts":"2026-02-26T06:50:23.450328Z","caller":"traceutil/trace.go:171","msg":"trace[446821418] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:15872; }","duration":"190.999246ms","start":"2026-02-26T06:50:23.259323Z","end":"2026-02-26T06:50:23.450322Z","steps":["trace[446821418] 'agreement among raft nodes before linearized reading' (duration: 190.328943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:50:23.450621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.822741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2364555"} {"level":"info","ts":"2026-02-26T06:50:23.45065Z","caller":"traceutil/trace.go:171","msg":"trace[1660327976] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:15872; }","duration":"170.867851ms","start":"2026-02-26T06:50:23.279774Z","end":"2026-02-26T06:50:23.450642Z","steps":["trace[1660327976] 'agreement among raft nodes before linearized reading' (duration: 170.254789ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:52:27.501223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14476} {"level":"info","ts":"2026-02-26T06:52:27.593322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14476,"took":"89.10719ms","hash":59540878,"current-db-size-bytes":43479040,"current-db-size":"44 MB","current-db-size-in-use-bytes":28581888,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-26T06:52:27.593397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":59540878,"revision":14476,"compact-revision":12119} {"level":"info","ts":"2026-02-26T06:57:27.506593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16745} {"level":"info","ts":"2026-02-26T06:57:27.606449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16745,"took":"97.368277ms","hash":1084119082,"current-db-size-bytes":43479040,"current-db-size":"44 MB","current-db-size-in-use-bytes":23990272,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T06:57:27.606503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1084119082,"revision":16745,"compact-revision":14476}