{"level":"warn","ts":"2026-03-13T09:54:19.572104Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T09:54:19.572202Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.230: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.19.213.230:2380","--initial-cluster=instance=https://199.19.213.230:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.230:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.230: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-03-13T09:54:19.572294Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T09:54:19.572309Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.230:2380"]} {"level":"info","ts":"2026-03-13T09:54:19.572326Z","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-03-13T09:54:19.573069Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.230:2379"]} {"level":"info","ts":"2026-03-13T09:54:19.573187Z","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.19.213.230:2380"],"listen-peer-urls":["https://199.19.213.230:2380"],"advertise-client-urls":["https://199.19.213.230:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.230:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.230: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-03-13T09:54:19.578672Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.172757ms"} {"level":"info","ts":"2026-03-13T09:54:19.592163Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aadce177dd8cd60e","cluster-id":"f249e3094885eab"} {"level":"info","ts":"2026-03-13T09:54:19.592306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T09:54:19.592352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e became follower at term 0"} {"level":"info","ts":"2026-03-13T09:54:19.592378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aadce177dd8cd60e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T09:54:19.592405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e became follower at term 1"} {"level":"info","ts":"2026-03-13T09:54:19.592484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e switched to configuration voters=(12311963386258445838)"} {"level":"warn","ts":"2026-03-13T09:54:19.602034Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T09:54:19.604825Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T09:54:19.606224Z","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-03-13T09:54:19.608447Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aadce177dd8cd60e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T09:54:19.61027Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aadce177dd8cd60e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T09:54:19.610725Z","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-03-13T09:54:19.610787Z","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-03-13T09:54:19.610799Z","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-03-13T09:54:19.611178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e switched to configuration voters=(12311963386258445838)"} {"level":"info","ts":"2026-03-13T09:54:19.61131Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f249e3094885eab","local-member-id":"aadce177dd8cd60e","added-peer-id":"aadce177dd8cd60e","added-peer-peer-urls":["https://199.19.213.230:2380"]} {"level":"info","ts":"2026-03-13T09:54:19.61265Z","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-03-13T09:54:19.612718Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.230:2380"} {"level":"info","ts":"2026-03-13T09:54:19.61275Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.230:2380"} {"level":"info","ts":"2026-03-13T09:54:19.613024Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aadce177dd8cd60e","initial-advertise-peer-urls":["https://199.19.213.230:2380"],"listen-peer-urls":["https://199.19.213.230:2380"],"advertise-client-urls":["https://199.19.213.230:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.230:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T09:54:19.613164Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T09:54:20.193277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T09:54:20.193343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T09:54:20.19338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e received MsgPreVoteResp from aadce177dd8cd60e at term 1"} {"level":"info","ts":"2026-03-13T09:54:20.193398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e became candidate at term 2"} {"level":"info","ts":"2026-03-13T09:54:20.193412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e received MsgVoteResp from aadce177dd8cd60e at term 2"} {"level":"info","ts":"2026-03-13T09:54:20.193439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aadce177dd8cd60e became leader at term 2"} {"level":"info","ts":"2026-03-13T09:54:20.193452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aadce177dd8cd60e elected leader aadce177dd8cd60e at term 2"} {"level":"info","ts":"2026-03-13T09:54:20.194625Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:54:20.195482Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aadce177dd8cd60e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.230:2379]}","request-path":"/0/members/aadce177dd8cd60e/attributes","cluster-id":"f249e3094885eab","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T09:54:20.195686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:54:20.196053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f249e3094885eab","local-member-id":"aadce177dd8cd60e","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:54:20.196169Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:54:20.196215Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:54:20.198788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T09:54:20.199173Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:54:20.20093Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.230:2379"} {"level":"info","ts":"2026-03-13T09:54:20.201383Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T09:54:20.201433Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T09:54:28.923674Z","caller":"traceutil/trace.go:171","msg":"trace[296990999] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"200.348648ms","start":"2026-03-13T09:54:28.723283Z","end":"2026-03-13T09:54:28.923632Z","steps":["trace[296990999] 'process raft request' (duration: 200.126152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:28.923968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.729922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-13T09:54:28.924104Z","caller":"traceutil/trace.go:171","msg":"trace[89177996] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:340; }","duration":"157.971539ms","start":"2026-03-13T09:54:28.766115Z","end":"2026-03-13T09:54:28.924086Z","steps":["trace[89177996] 'agreement among raft nodes before linearized reading' (duration: 157.63581ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:28.923638Z","caller":"traceutil/trace.go:171","msg":"trace[1652649959] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"157.468515ms","start":"2026-03-13T09:54:28.766131Z","end":"2026-03-13T09:54:28.923599Z","steps":["trace[1652649959] 'read index received' (duration: 157.201418ms)","trace[1652649959] 'applied index is now lower than readState.Index' (duration: 265.807µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:28.924446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.948413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-13T09:54:28.924536Z","caller":"traceutil/trace.go:171","msg":"trace[1876900119] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:340; }","duration":"109.051695ms","start":"2026-03-13T09:54:28.815465Z","end":"2026-03-13T09:54:28.924517Z","steps":["trace[1876900119] 'agreement among raft nodes before linearized reading' (duration: 108.86976ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:34.301878Z","caller":"traceutil/trace.go:171","msg":"trace[6044917] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"174.814106ms","start":"2026-03-13T09:54:34.127025Z","end":"2026-03-13T09:54:34.301839Z","steps":["trace[6044917] 'process raft request' (duration: 167.464128ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:50.258219Z","caller":"traceutil/trace.go:171","msg":"trace[2098115397] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"111.91267ms","start":"2026-03-13T09:54:50.14628Z","end":"2026-03-13T09:54:50.258193Z","steps":["trace[2098115397] 'process raft request' (duration: 111.720935ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:50.562344Z","caller":"traceutil/trace.go:171","msg":"trace[1587714845] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"107.787716ms","start":"2026-03-13T09:54:50.454531Z","end":"2026-03-13T09:54:50.562319Z","steps":["trace[1587714845] 'read index received' (duration: 107.479268ms)","trace[1587714845] 'applied index is now lower than readState.Index' (duration: 307.098µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:50.562478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.934121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:50.562471Z","caller":"traceutil/trace.go:171","msg":"trace[1491733242] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"160.909733ms","start":"2026-03-13T09:54:50.40151Z","end":"2026-03-13T09:54:50.56242Z","steps":["trace[1491733242] 'process raft request' (duration: 160.643086ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:50.562522Z","caller":"traceutil/trace.go:171","msg":"trace[1799928925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"108.026183ms","start":"2026-03-13T09:54:50.454481Z","end":"2026-03-13T09:54:50.562507Z","steps":["trace[1799928925] 'agreement among raft nodes before linearized reading' (duration: 107.951061ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:56.143613Z","caller":"traceutil/trace.go:171","msg":"trace[934745883] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"114.466901ms","start":"2026-03-13T09:54:56.029084Z","end":"2026-03-13T09:54:56.143551Z","steps":["trace[934745883] 'process raft request' (duration: 114.124602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:56.338666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.828692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:56.338861Z","caller":"traceutil/trace.go:171","msg":"trace[2000081363] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"178.516279ms","start":"2026-03-13T09:54:56.160301Z","end":"2026-03-13T09:54:56.338817Z","steps":["trace[2000081363] 'process raft request' (duration: 58.885476ms)","trace[2000081363] 'compare' (duration: 118.707127ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:57.049665Z","caller":"traceutil/trace.go:171","msg":"trace[2136430949] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"461.291888ms","start":"2026-03-13T09:54:56.588355Z","end":"2026-03-13T09:54:57.049647Z","steps":["trace[2136430949] 'process raft request' (duration: 461.187925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:57.049778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:56.588334Z","time spent":"461.38493ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:57.385939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.360492ms","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-03-13T09:54:57.386089Z","caller":"traceutil/trace.go:171","msg":"trace[1563792459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"221.554977ms","start":"2026-03-13T09:54:57.164478Z","end":"2026-03-13T09:54:57.386033Z","steps":["trace[1563792459] 'range keys from in-memory index tree' (duration: 221.028352ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:16.557295Z","caller":"traceutil/trace.go:171","msg":"trace[1375638294] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"102.269604ms","start":"2026-03-13T09:55:16.454998Z","end":"2026-03-13T09:55:16.557267Z","steps":["trace[1375638294] 'read index received' (duration: 102.098309ms)","trace[1375638294] 'applied index is now lower than readState.Index' (duration: 170.285µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:16.557438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.420379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:16.557469Z","caller":"traceutil/trace.go:171","msg":"trace[1923091507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"102.527831ms","start":"2026-03-13T09:55:16.454933Z","end":"2026-03-13T09:55:16.55746Z","steps":["trace[1923091507] 'agreement among raft nodes before linearized reading' (duration: 102.448299ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:16.557602Z","caller":"traceutil/trace.go:171","msg":"trace[1885198961] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"202.197463ms","start":"2026-03-13T09:55:16.35538Z","end":"2026-03-13T09:55:16.557577Z","steps":["trace[1885198961] 'process raft request' (duration: 201.72832ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:23.671857Z","caller":"traceutil/trace.go:171","msg":"trace[2084888457] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"101.789641ms","start":"2026-03-13T09:55:23.570035Z","end":"2026-03-13T09:55:23.671824Z","steps":["trace[2084888457] 'process raft request' (duration: 101.614546ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:37.534902Z","caller":"traceutil/trace.go:171","msg":"trace[530363470] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"169.928402ms","start":"2026-03-13T09:55:37.3649Z","end":"2026-03-13T09:55:37.534828Z","steps":["trace[530363470] 'process raft request' (duration: 169.626454ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.578295Z","caller":"traceutil/trace.go:171","msg":"trace[158045539] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"187.067786ms","start":"2026-03-13T09:55:50.391192Z","end":"2026-03-13T09:55:50.57826Z","steps":["trace[158045539] 'process raft request' (duration: 186.876801ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.747685Z","caller":"traceutil/trace.go:171","msg":"trace[60012574] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"130.574055ms","start":"2026-03-13T09:55:50.617084Z","end":"2026-03-13T09:55:50.747658Z","steps":["trace[60012574] 'process raft request' (duration: 130.430591ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.869879Z","caller":"traceutil/trace.go:171","msg":"trace[939311517] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"204.286371ms","start":"2026-03-13T09:55:50.665554Z","end":"2026-03-13T09:55:50.86984Z","steps":["trace[939311517] 'process raft request' (duration: 153.341134ms)","trace[939311517] 'compare' (duration: 50.546556ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:50.870312Z","caller":"traceutil/trace.go:171","msg":"trace[346904453] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"198.145001ms","start":"2026-03-13T09:55:50.672143Z","end":"2026-03-13T09:55:50.870288Z","steps":["trace[346904453] 'process raft request' (duration: 197.966536ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.870429Z","caller":"traceutil/trace.go:171","msg":"trace[1767955001] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"197.829373ms","start":"2026-03-13T09:55:50.672583Z","end":"2026-03-13T09:55:50.870413Z","steps":["trace[1767955001] 'process raft request' (duration: 197.618717ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.870708Z","caller":"traceutil/trace.go:171","msg":"trace[2013187222] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"197.377161ms","start":"2026-03-13T09:55:50.67332Z","end":"2026-03-13T09:55:50.870697Z","steps":["trace[2013187222] 'process raft request' (duration: 197.240727ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.870739Z","caller":"traceutil/trace.go:171","msg":"trace[742646916] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"197.0163ms","start":"2026-03-13T09:55:50.673711Z","end":"2026-03-13T09:55:50.870728Z","steps":["trace[742646916] 'process raft request' (duration: 196.916518ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.870766Z","caller":"traceutil/trace.go:171","msg":"trace[1184824609] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"197.353459ms","start":"2026-03-13T09:55:50.67337Z","end":"2026-03-13T09:55:50.870724Z","steps":["trace[1184824609] 'process raft request' (duration: 197.228516ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.870861Z","caller":"traceutil/trace.go:171","msg":"trace[720529295] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"195.664133ms","start":"2026-03-13T09:55:50.675187Z","end":"2026-03-13T09:55:50.870851Z","steps":["trace[720529295] 'process raft request' (duration: 195.470348ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.871019Z","caller":"traceutil/trace.go:171","msg":"trace[882866376] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"195.711164ms","start":"2026-03-13T09:55:50.675295Z","end":"2026-03-13T09:55:50.871006Z","steps":["trace[882866376] 'process raft request' (duration: 195.388515ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.871124Z","caller":"traceutil/trace.go:171","msg":"trace[127781332] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"195.715734ms","start":"2026-03-13T09:55:50.6754Z","end":"2026-03-13T09:55:50.871116Z","steps":["trace[127781332] 'process raft request' (duration: 195.56645ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.871204Z","caller":"traceutil/trace.go:171","msg":"trace[904212957] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"194.308135ms","start":"2026-03-13T09:55:50.676888Z","end":"2026-03-13T09:55:50.871196Z","steps":["trace[904212957] 'process raft request' (duration: 194.201902ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.871198Z","caller":"traceutil/trace.go:171","msg":"trace[801029030] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"194.369557ms","start":"2026-03-13T09:55:50.676818Z","end":"2026-03-13T09:55:50.871188Z","steps":["trace[801029030] 'process raft request' (duration: 194.239824ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.871219Z","caller":"traceutil/trace.go:171","msg":"trace[477593052] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"195.720995ms","start":"2026-03-13T09:55:50.675488Z","end":"2026-03-13T09:55:50.871209Z","steps":["trace[477593052] 'process raft request' (duration: 195.512529ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.871136Z","caller":"traceutil/trace.go:171","msg":"trace[1697201239] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1290; }","duration":"107.905499ms","start":"2026-03-13T09:55:50.763219Z","end":"2026-03-13T09:55:50.871124Z","steps":["trace[1697201239] 'read index received' (duration: 55.535403ms)","trace[1697201239] 'applied index is now lower than readState.Index' (duration: 52.369176ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:50.871489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.255189ms","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-03-13T09:55:50.871532Z","caller":"traceutil/trace.go:171","msg":"trace[110856036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"108.340411ms","start":"2026-03-13T09:55:50.76318Z","end":"2026-03-13T09:55:50.871521Z","steps":["trace[110856036] 'agreement among raft nodes before linearized reading' (duration: 108.175517ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:51.131739Z","caller":"traceutil/trace.go:171","msg":"trace[1282259997] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"234.093002ms","start":"2026-03-13T09:55:50.897609Z","end":"2026-03-13T09:55:51.131702Z","steps":["trace[1282259997] 'process raft request' (duration: 196.959507ms)","trace[1282259997] 'compare' (duration: 36.730994ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:51.131805Z","caller":"traceutil/trace.go:171","msg":"trace[830756261] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"232.140619ms","start":"2026-03-13T09:55:50.89964Z","end":"2026-03-13T09:55:51.13178Z","steps":["trace[830756261] 'read index received' (duration: 46.221385ms)","trace[830756261] 'applied index is now lower than readState.Index' (duration: 185.918134ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:51.131891Z","caller":"traceutil/trace.go:171","msg":"trace[1697325663] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"190.775858ms","start":"2026-03-13T09:55:50.941087Z","end":"2026-03-13T09:55:51.131863Z","steps":["trace[1697325663] 'process raft request' (duration: 190.581303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.132054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.364635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com\" ","response":"range_response_count:1 size:96253"} {"level":"info","ts":"2026-03-13T09:55:51.132135Z","caller":"traceutil/trace.go:171","msg":"trace[1964889416] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com; range_end:; response_count:1; response_revision:1270; }","duration":"232.52469ms","start":"2026-03-13T09:55:50.899594Z","end":"2026-03-13T09:55:51.132118Z","steps":["trace[1964889416] 'agreement among raft nodes before linearized reading' (duration: 232.350035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.13227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.440488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-13T09:55:51.132317Z","caller":"traceutil/trace.go:171","msg":"trace[1022163788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"148.5023ms","start":"2026-03-13T09:55:50.983802Z","end":"2026-03-13T09:55:51.132304Z","steps":["trace[1022163788] 'agreement among raft nodes before linearized reading' (duration: 148.299824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.49426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.743268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:51.494532Z","caller":"traceutil/trace.go:171","msg":"trace[1121330936] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1307; }","duration":"316.615672ms","start":"2026-03-13T09:55:51.177897Z","end":"2026-03-13T09:55:51.494513Z","steps":["trace[1121330936] 'read index received' (duration: 113.498544ms)","trace[1121330936] 'applied index is now lower than readState.Index' (duration: 203.115878ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:51.494573Z","caller":"traceutil/trace.go:171","msg":"trace[423223665] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"353.401998ms","start":"2026-03-13T09:55:51.141123Z","end":"2026-03-13T09:55:51.494525Z","steps":["trace[423223665] 'process raft request' (duration: 150.283829ms)","trace[423223665] 'compare' (duration: 202.508852ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:51.49472Z","caller":"traceutil/trace.go:171","msg":"trace[1762751702] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"323.340368ms","start":"2026-03-13T09:55:51.171348Z","end":"2026-03-13T09:55:51.494689Z","steps":["trace[1762751702] 'process raft request' (duration: 323.102442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.494813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.836238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:1 size:5619"} {"level":"warn","ts":"2026-03-13T09:55:51.494941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.171332Z","time spent":"323.478492ms","remote":"127.0.0.1:55168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5604,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:51.49507Z","caller":"traceutil/trace.go:171","msg":"trace[375984538] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:1; response_revision:1273; }","duration":"317.039474ms","start":"2026-03-13T09:55:51.177864Z","end":"2026-03-13T09:55:51.494904Z","steps":["trace[375984538] 'agreement among raft nodes before linearized reading' (duration: 316.831439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.495077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.141097Z","time spent":"353.668315ms","remote":"127.0.0.1:55168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:51.495204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:51.177848Z","time spent":"317.297341ms","remote":"127.0.0.1:55168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":5642,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" "} {"level":"warn","ts":"2026-03-13T09:55:51.495411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.97171ms","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-03-13T09:55:51.495474Z","caller":"traceutil/trace.go:171","msg":"trace[943124474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"163.078423ms","start":"2026-03-13T09:55:51.332383Z","end":"2026-03-13T09:55:51.495461Z","steps":["trace[943124474] 'agreement among raft nodes before linearized reading' (duration: 162.917678ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:51.775584Z","caller":"traceutil/trace.go:171","msg":"trace[1047680012] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"120.473986ms","start":"2026-03-13T09:55:51.655064Z","end":"2026-03-13T09:55:51.775538Z","steps":["trace[1047680012] 'process raft request' (duration: 120.130207ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:51.94098Z","caller":"traceutil/trace.go:171","msg":"trace[1601303426] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1319; }","duration":"118.493882ms","start":"2026-03-13T09:55:51.822461Z","end":"2026-03-13T09:55:51.940955Z","steps":["trace[1601303426] 'read index received' (duration: 40.389265ms)","trace[1601303426] 'applied index is now lower than readState.Index' (duration: 78.103757ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:51.94109Z","caller":"traceutil/trace.go:171","msg":"trace[481814290] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"124.506058ms","start":"2026-03-13T09:55:51.81654Z","end":"2026-03-13T09:55:51.941046Z","steps":["trace[481814290] 'process raft request' (duration: 123.890231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.94122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.733218ms","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-03-13T09:55:51.941261Z","caller":"traceutil/trace.go:171","msg":"trace[700490156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"118.819051ms","start":"2026-03-13T09:55:51.822431Z","end":"2026-03-13T09:55:51.94125Z","steps":["trace[700490156] 'agreement among raft nodes before linearized reading' (duration: 118.615905ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:52.002323Z","caller":"traceutil/trace.go:171","msg":"trace[1384974251] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"132.93155ms","start":"2026-03-13T09:55:51.869365Z","end":"2026-03-13T09:55:52.002296Z","steps":["trace[1384974251] 'process raft request' (duration: 132.794707ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:52.089174Z","caller":"traceutil/trace.go:171","msg":"trace[944244411] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"143.756989ms","start":"2026-03-13T09:55:51.945379Z","end":"2026-03-13T09:55:52.089136Z","steps":["trace[944244411] 'process raft request' (duration: 112.713302ms)","trace[944244411] 'compare' (duration: 30.812141ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:52.089429Z","caller":"traceutil/trace.go:171","msg":"trace[2090634027] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"141.001203ms","start":"2026-03-13T09:55:51.948414Z","end":"2026-03-13T09:55:52.089415Z","steps":["trace[2090634027] 'process raft request' (duration: 140.643183ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:52.222691Z","caller":"traceutil/trace.go:171","msg":"trace[494521954] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"115.054247ms","start":"2026-03-13T09:55:52.107603Z","end":"2026-03-13T09:55:52.222657Z","steps":["trace[494521954] 'process raft request' (duration: 104.892967ms)","trace[494521954] 'compare' (duration: 10.023486ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:52.274632Z","caller":"traceutil/trace.go:171","msg":"trace[1813443820] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"106.07852ms","start":"2026-03-13T09:55:52.168524Z","end":"2026-03-13T09:55:52.274602Z","steps":["trace[1813443820] 'process raft request' (duration: 105.950836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:52.460683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.355363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:52.460855Z","caller":"traceutil/trace.go:171","msg":"trace[1003704103] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"230.201426ms","start":"2026-03-13T09:55:52.230622Z","end":"2026-03-13T09:55:52.460823Z","steps":["trace[1003704103] 'process raft request' (duration: 109.545225ms)","trace[1003704103] 'compare' (duration: 120.198949ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:52.463986Z","caller":"traceutil/trace.go:171","msg":"trace[357733675] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"181.609404ms","start":"2026-03-13T09:55:52.282324Z","end":"2026-03-13T09:55:52.463934Z","steps":["trace[357733675] 'process raft request' (duration: 181.249114ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:53.644433Z","caller":"traceutil/trace.go:171","msg":"trace[174310437] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"111.841648ms","start":"2026-03-13T09:55:53.532566Z","end":"2026-03-13T09:55:53.644408Z","steps":["trace[174310437] 'process raft request' (duration: 111.591861ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:54.302393Z","caller":"traceutil/trace.go:171","msg":"trace[1870435545] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"118.142412ms","start":"2026-03-13T09:55:54.184211Z","end":"2026-03-13T09:55:54.302353Z","steps":["trace[1870435545] 'process raft request' (duration: 94.156504ms)","trace[1870435545] 'compare' (duration: 23.845175ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:55.771061Z","caller":"traceutil/trace.go:171","msg":"trace[2042670593] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"122.547213ms","start":"2026-03-13T09:55:55.648488Z","end":"2026-03-13T09:55:55.771036Z","steps":["trace[2042670593] 'read index received' (duration: 122.417229ms)","trace[2042670593] 'applied index is now lower than readState.Index' (duration: 128.654µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:55.771156Z","caller":"traceutil/trace.go:171","msg":"trace[145505481] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"153.495162ms","start":"2026-03-13T09:55:55.61763Z","end":"2026-03-13T09:55:55.771125Z","steps":["trace[145505481] 'process raft request' (duration: 153.237045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:55.771527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.019516ms","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-03-13T09:55:55.771611Z","caller":"traceutil/trace.go:171","msg":"trace[646003322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1305; }","duration":"123.180071ms","start":"2026-03-13T09:55:55.648413Z","end":"2026-03-13T09:55:55.771593Z","steps":["trace[646003322] 'agreement among raft nodes before linearized reading' (duration: 122.77275ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:55.967747Z","caller":"traceutil/trace.go:171","msg":"trace[1069399263] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"139.850349ms","start":"2026-03-13T09:55:55.827867Z","end":"2026-03-13T09:55:55.967717Z","steps":["trace[1069399263] 'process raft request' (duration: 139.624372ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:55.979048Z","caller":"traceutil/trace.go:171","msg":"trace[1016553957] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"102.00315ms","start":"2026-03-13T09:55:55.87701Z","end":"2026-03-13T09:55:55.979013Z","steps":["trace[1016553957] 'process raft request' (duration: 101.65736ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.325475Z","caller":"traceutil/trace.go:171","msg":"trace[1166778990] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1348; }","duration":"183.495555ms","start":"2026-03-13T09:55:56.141956Z","end":"2026-03-13T09:55:56.325451Z","steps":["trace[1166778990] 'read index received' (duration: 84.387456ms)","trace[1166778990] 'applied index is now lower than readState.Index' (duration: 99.107099ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:56.325548Z","caller":"traceutil/trace.go:171","msg":"trace[331185416] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"345.733728ms","start":"2026-03-13T09:55:55.979787Z","end":"2026-03-13T09:55:56.32552Z","steps":["trace[331185416] 'process raft request' (duration: 345.622345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.325676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:55.979759Z","time spent":"345.852172ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":996,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T09:55:56.325548Z","caller":"traceutil/trace.go:171","msg":"trace[1727670750] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"345.711847ms","start":"2026-03-13T09:55:55.979784Z","end":"2026-03-13T09:55:56.325495Z","steps":["trace[1727670750] 'process raft request' (duration: 246.537966ms)","trace[1727670750] 'compare' (duration: 98.900114ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:56.325733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.765903ms","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-03-13T09:55:56.325777Z","caller":"traceutil/trace.go:171","msg":"trace[728505919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1313; }","duration":"183.880356ms","start":"2026-03-13T09:55:56.141885Z","end":"2026-03-13T09:55:56.325765Z","steps":["trace[728505919] 'agreement among raft nodes before linearized reading' (duration: 183.66836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.325866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:55.979759Z","time spent":"345.991925ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1028,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T09:55:56.514183Z","caller":"traceutil/trace.go:171","msg":"trace[1038569043] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"182.724345ms","start":"2026-03-13T09:55:56.331412Z","end":"2026-03-13T09:55:56.514136Z","steps":["trace[1038569043] 'process raft request' (duration: 130.837811ms)","trace[1038569043] 'compare' (duration: 51.449182ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:56.514548Z","caller":"traceutil/trace.go:171","msg":"trace[801041849] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"183.060293ms","start":"2026-03-13T09:55:56.331468Z","end":"2026-03-13T09:55:56.514529Z","steps":["trace[801041849] 'process raft request' (duration: 182.456207ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.772374Z","caller":"traceutil/trace.go:171","msg":"trace[737271573] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"252.803958ms","start":"2026-03-13T09:55:56.519538Z","end":"2026-03-13T09:55:56.772342Z","steps":["trace[737271573] 'process raft request' (duration: 229.465387ms)","trace[737271573] 'compare' (duration: 23.011192ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:56.772425Z","caller":"traceutil/trace.go:171","msg":"trace[1272344939] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"251.927785ms","start":"2026-03-13T09:55:56.520476Z","end":"2026-03-13T09:55:56.772404Z","steps":["trace[1272344939] 'process raft request' (duration: 251.76152ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.772642Z","caller":"traceutil/trace.go:171","msg":"trace[1176392490] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"188.489143ms","start":"2026-03-13T09:55:56.584118Z","end":"2026-03-13T09:55:56.772607Z","steps":["trace[1176392490] 'process raft request' (duration: 188.216076ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.909779Z","caller":"traceutil/trace.go:171","msg":"trace[1813135361] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"132.498736ms","start":"2026-03-13T09:55:56.777253Z","end":"2026-03-13T09:55:56.909751Z","steps":["trace[1813135361] 'process raft request' (duration: 132.382813ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.910122Z","caller":"traceutil/trace.go:171","msg":"trace[1746786554] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"133.092743ms","start":"2026-03-13T09:55:56.777014Z","end":"2026-03-13T09:55:56.910106Z","steps":["trace[1746786554] 'process raft request' (duration: 116.824996ms)","trace[1746786554] 'compare' (duration: 15.673731ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:57.059821Z","caller":"traceutil/trace.go:171","msg":"trace[118211156] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"106.74522ms","start":"2026-03-13T09:55:56.953044Z","end":"2026-03-13T09:55:57.059789Z","steps":["trace[118211156] 'process raft request' (duration: 86.314629ms)","trace[118211156] 'compare' (duration: 20.261386ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:57.528597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.819323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:57.528887Z","caller":"traceutil/trace.go:171","msg":"trace[1121440879] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"259.135322ms","start":"2026-03-13T09:55:57.269631Z","end":"2026-03-13T09:55:57.528766Z","steps":["trace[1121440879] 'process raft request' (duration: 125.082313ms)","trace[1121440879] 'compare' (duration: 133.683439ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:57.529019Z","caller":"traceutil/trace.go:171","msg":"trace[1126132361] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1375; }","duration":"247.565804ms","start":"2026-03-13T09:55:57.281431Z","end":"2026-03-13T09:55:57.528997Z","steps":["trace[1126132361] 'read index received' (duration: 113.381201ms)","trace[1126132361] 'applied index is now lower than readState.Index' (duration: 134.183553ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:57.52904Z","caller":"traceutil/trace.go:171","msg":"trace[844219096] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"258.849504ms","start":"2026-03-13T09:55:57.270171Z","end":"2026-03-13T09:55:57.52902Z","steps":["trace[844219096] 'process raft request' (duration: 258.531225ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:57.529131Z","caller":"traceutil/trace.go:171","msg":"trace[219185199] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"253.715443ms","start":"2026-03-13T09:55:57.275399Z","end":"2026-03-13T09:55:57.529114Z","steps":["trace[219185199] 'process raft request' (duration: 253.507768ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:57.529446Z","caller":"traceutil/trace.go:171","msg":"trace[818562536] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"259.228075ms","start":"2026-03-13T09:55:57.270198Z","end":"2026-03-13T09:55:57.529426Z","steps":["trace[818562536] 'process raft request' (duration: 258.565177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:57.529475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.288364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:4862"} {"level":"info","ts":"2026-03-13T09:55:57.529528Z","caller":"traceutil/trace.go:171","msg":"trace[1411622527] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:1343; }","duration":"256.369426ms","start":"2026-03-13T09:55:57.273144Z","end":"2026-03-13T09:55:57.529513Z","steps":["trace[1411622527] 'agreement among raft nodes before linearized reading' (duration: 256.215182ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:57.529492Z","caller":"traceutil/trace.go:171","msg":"trace[565901721] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"256.148411ms","start":"2026-03-13T09:55:57.273301Z","end":"2026-03-13T09:55:57.529449Z","steps":["trace[565901721] 'process raft request' (duration: 255.551404ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:57.529573Z","caller":"traceutil/trace.go:171","msg":"trace[438881430] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1343; }","duration":"253.354993ms","start":"2026-03-13T09:55:57.2762Z","end":"2026-03-13T09:55:57.529555Z","steps":["trace[438881430] 'process raft request' (duration: 252.750207ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:57.54111Z","caller":"traceutil/trace.go:171","msg":"trace[1526135644] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"254.161225ms","start":"2026-03-13T09:55:57.286934Z","end":"2026-03-13T09:55:57.541095Z","steps":["trace[1526135644] 'process raft request' (duration: 254.068532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:57.541447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.828416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:5139"} {"level":"info","ts":"2026-03-13T09:55:57.541612Z","caller":"traceutil/trace.go:171","msg":"trace[1243992288] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:1344; }","duration":"254.032221ms","start":"2026-03-13T09:55:57.287557Z","end":"2026-03-13T09:55:57.54159Z","steps":["trace[1243992288] 'agreement among raft nodes before linearized reading' (duration: 253.591139ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:57.680307Z","caller":"traceutil/trace.go:171","msg":"trace[1561461704] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"132.581799ms","start":"2026-03-13T09:55:57.547701Z","end":"2026-03-13T09:55:57.680283Z","steps":["trace[1561461704] 'process raft request' (duration: 132.522687ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:57.680343Z","caller":"traceutil/trace.go:171","msg":"trace[1162607433] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"135.17281ms","start":"2026-03-13T09:55:57.545147Z","end":"2026-03-13T09:55:57.68032Z","steps":["trace[1162607433] 'process raft request' (duration: 127.915411ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:04.061164Z","caller":"traceutil/trace.go:171","msg":"trace[901606225] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"111.318815ms","start":"2026-03-13T09:56:03.949784Z","end":"2026-03-13T09:56:04.061103Z","steps":["trace[901606225] 'process raft request' (duration: 96.941071ms)","trace[901606225] 'compare' (duration: 14.143718ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:56:04.061225Z","caller":"traceutil/trace.go:171","msg":"trace[1336128736] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"109.088394ms","start":"2026-03-13T09:56:03.952104Z","end":"2026-03-13T09:56:04.061193Z","steps":["trace[1336128736] 'process raft request' (duration: 108.913589ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:04.349742Z","caller":"traceutil/trace.go:171","msg":"trace[438084994] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"104.21317ms","start":"2026-03-13T09:56:04.245498Z","end":"2026-03-13T09:56:04.349711Z","steps":["trace[438084994] 'process raft request' (duration: 86.887674ms)","trace[438084994] 'compare' (duration: 17.148021ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:56:04.48138Z","caller":"traceutil/trace.go:171","msg":"trace[686961602] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"122.488552ms","start":"2026-03-13T09:56:04.358864Z","end":"2026-03-13T09:56:04.481353Z","steps":["trace[686961602] 'process raft request' (duration: 112.564079ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:04.708095Z","caller":"traceutil/trace.go:171","msg":"trace[1973337362] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"125.734271ms","start":"2026-03-13T09:56:04.58233Z","end":"2026-03-13T09:56:04.708065Z","steps":["trace[1973337362] 'process raft request' (duration: 125.579677ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.655835Z","caller":"traceutil/trace.go:171","msg":"trace[1070585880] linearizableReadLoop","detail":"{readStateIndex:1573; appliedIndex:1572; }","duration":"100.493008ms","start":"2026-03-13T09:56:10.555317Z","end":"2026-03-13T09:56:10.65581Z","steps":["trace[1070585880] 'read index received' (duration: 58.987179ms)","trace[1070585880] 'applied index is now lower than readState.Index' (duration: 41.505019ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:56:10.655929Z","caller":"traceutil/trace.go:171","msg":"trace[1244491223] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"132.954658ms","start":"2026-03-13T09:56:10.522952Z","end":"2026-03-13T09:56:10.655907Z","steps":["trace[1244491223] 'process raft request' (duration: 91.348697ms)","trace[1244491223] 'compare' (duration: 41.367195ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:56:10.656136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.788526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-03-13T09:56:10.656229Z","caller":"traceutil/trace.go:171","msg":"trace[1683784615] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1529; }","duration":"100.913179ms","start":"2026-03-13T09:56:10.555292Z","end":"2026-03-13T09:56:10.656205Z","steps":["trace[1683784615] 'agreement among raft nodes before linearized reading' (duration: 100.649852ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.673737Z","caller":"traceutil/trace.go:171","msg":"trace[1023265729] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"112.989041ms","start":"2026-03-13T09:56:10.560691Z","end":"2026-03-13T09:56:10.67368Z","steps":["trace[1023265729] 'process raft request' (duration: 112.794166ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.673767Z","caller":"traceutil/trace.go:171","msg":"trace[1526862795] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"117.040252ms","start":"2026-03-13T09:56:10.556696Z","end":"2026-03-13T09:56:10.673736Z","steps":["trace[1526862795] 'process raft request' (duration: 116.662951ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.67377Z","caller":"traceutil/trace.go:171","msg":"trace[344371590] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"111.748527ms","start":"2026-03-13T09:56:10.561998Z","end":"2026-03-13T09:56:10.673746Z","steps":["trace[344371590] 'process raft request' (duration: 111.530691ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.806362Z","caller":"traceutil/trace.go:171","msg":"trace[1458885271] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"126.131322ms","start":"2026-03-13T09:56:10.680204Z","end":"2026-03-13T09:56:10.806335Z","steps":["trace[1458885271] 'process raft request' (duration: 125.33536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:10.806621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.92215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-03-13T09:56:10.806703Z","caller":"traceutil/trace.go:171","msg":"trace[389926456] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"122.209924ms","start":"2026-03-13T09:56:10.684477Z","end":"2026-03-13T09:56:10.806687Z","steps":["trace[389926456] 'process raft request' (duration: 121.779152ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.806705Z","caller":"traceutil/trace.go:171","msg":"trace[1807667785] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1538; }","duration":"101.032402ms","start":"2026-03-13T09:56:10.70565Z","end":"2026-03-13T09:56:10.806682Z","steps":["trace[1807667785] 'agreement among raft nodes before linearized reading' (duration: 100.824097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:10.806629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.794555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-13T09:56:10.806871Z","caller":"traceutil/trace.go:171","msg":"trace[2120249005] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1538; }","duration":"129.075482ms","start":"2026-03-13T09:56:10.677785Z","end":"2026-03-13T09:56:10.80686Z","steps":["trace[2120249005] 'agreement among raft nodes before linearized reading' (duration: 128.783274ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.84728Z","caller":"traceutil/trace.go:171","msg":"trace[1235404391] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"114.206884ms","start":"2026-03-13T09:56:10.733047Z","end":"2026-03-13T09:56:10.847254Z","steps":["trace[1235404391] 'process raft request' (duration: 114.05411ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.84737Z","caller":"traceutil/trace.go:171","msg":"trace[1403026635] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"105.427403ms","start":"2026-03-13T09:56:10.741913Z","end":"2026-03-13T09:56:10.84734Z","steps":["trace[1403026635] 'process raft request' (duration: 105.32605ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.84754Z","caller":"traceutil/trace.go:171","msg":"trace[306262646] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"113.874485ms","start":"2026-03-13T09:56:10.733638Z","end":"2026-03-13T09:56:10.847513Z","steps":["trace[306262646] 'process raft request' (duration: 113.563326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:10.847687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.916611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:7691"} {"level":"warn","ts":"2026-03-13T09:56:10.847687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.870532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-13T09:56:10.847753Z","caller":"traceutil/trace.go:171","msg":"trace[1706996254] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:1541; }","duration":"114.977975ms","start":"2026-03-13T09:56:10.732756Z","end":"2026-03-13T09:56:10.847734Z","steps":["trace[1706996254] 'agreement among raft nodes before linearized reading' (duration: 114.857202ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:10.847734Z","caller":"traceutil/trace.go:171","msg":"trace[537658358] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1541; }","duration":"112.026005ms","start":"2026-03-13T09:56:10.735695Z","end":"2026-03-13T09:56:10.847721Z","steps":["trace[537658358] 'agreement among raft nodes before linearized reading' (duration: 111.804368ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:12.854517Z","caller":"traceutil/trace.go:171","msg":"trace[1017449775] linearizableReadLoop","detail":"{readStateIndex:1638; appliedIndex:1637; }","duration":"229.819347ms","start":"2026-03-13T09:56:12.624668Z","end":"2026-03-13T09:56:12.854488Z","steps":["trace[1017449775] 'read index received' (duration: 229.495178ms)","trace[1017449775] 'applied index is now lower than readState.Index' (duration: 323.039µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:56:12.854653Z","caller":"traceutil/trace.go:171","msg":"trace[744665436] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"292.068936ms","start":"2026-03-13T09:56:12.562545Z","end":"2026-03-13T09:56:12.854614Z","steps":["trace[744665436] 'process raft request' (duration: 291.752477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:12.854719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.007492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:56:12.854754Z","caller":"traceutil/trace.go:171","msg":"trace[2147137335] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1593; }","duration":"230.126936ms","start":"2026-03-13T09:56:12.624619Z","end":"2026-03-13T09:56:12.854745Z","steps":["trace[2147137335] 'agreement among raft nodes before linearized reading' (duration: 230.014823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:12.854855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.129701ms","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-03-13T09:56:12.854943Z","caller":"traceutil/trace.go:171","msg":"trace[478745420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1593; }","duration":"177.270525ms","start":"2026-03-13T09:56:12.677653Z","end":"2026-03-13T09:56:12.854924Z","steps":["trace[478745420] 'agreement among raft nodes before linearized reading' (duration: 177.034889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:12.855287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.879795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-03-13T09:56:12.855366Z","caller":"traceutil/trace.go:171","msg":"trace[59809432] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1593; }","duration":"122.003149ms","start":"2026-03-13T09:56:12.733343Z","end":"2026-03-13T09:56:12.855346Z","steps":["trace[59809432] 'agreement among raft nodes before linearized reading' (duration: 121.404052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:12.85549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.812295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-03-13T09:56:12.855766Z","caller":"traceutil/trace.go:171","msg":"trace[570442549] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1593; }","duration":"168.125934ms","start":"2026-03-13T09:56:12.687571Z","end":"2026-03-13T09:56:12.855697Z","steps":["trace[570442549] 'agreement among raft nodes before linearized reading' (duration: 167.24678ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:13.269724Z","caller":"traceutil/trace.go:171","msg":"trace[1689136263] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"409.632583ms","start":"2026-03-13T09:56:12.860066Z","end":"2026-03-13T09:56:13.269699Z","steps":["trace[1689136263] 'process raft request' (duration: 409.363805ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:13.269776Z","caller":"traceutil/trace.go:171","msg":"trace[157322940] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"409.259682ms","start":"2026-03-13T09:56:12.860476Z","end":"2026-03-13T09:56:13.269736Z","steps":["trace[157322940] 'process raft request' (duration: 409.116038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:13.269887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:12.860046Z","time spent":"409.747715ms","remote":"127.0.0.1:55352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:56:13.26998Z","caller":"traceutil/trace.go:171","msg":"trace[1003569690] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"409.203941ms","start":"2026-03-13T09:56:12.860752Z","end":"2026-03-13T09:56:13.269956Z","steps":["trace[1003569690] 'process raft request' (duration: 408.892362ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:13.269991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:12.860466Z","time spent":"409.391576ms","remote":"127.0.0.1:55352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:56:13.270157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:12.860741Z","time spent":"409.335204ms","remote":"127.0.0.1:55244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:56:13.270364Z","caller":"traceutil/trace.go:171","msg":"trace[1637885696] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"408.580373ms","start":"2026-03-13T09:56:12.861765Z","end":"2026-03-13T09:56:13.270346Z","steps":["trace[1637885696] 'process raft request' (duration: 408.072309ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:13.270413Z","caller":"traceutil/trace.go:171","msg":"trace[1199305682] linearizableReadLoop","detail":"{readStateIndex:1644; appliedIndex:1639; }","duration":"225.373046ms","start":"2026-03-13T09:56:13.04498Z","end":"2026-03-13T09:56:13.270353Z","steps":["trace[1199305682] 'read index received' (duration: 224.396659ms)","trace[1199305682] 'applied index is now lower than readState.Index' (duration: 975.367µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:56:13.270516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:12.861737Z","time spent":"408.715657ms","remote":"127.0.0.1:55244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":961,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:56:13.270564Z","caller":"traceutil/trace.go:171","msg":"trace[1378776467] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"407.38045ms","start":"2026-03-13T09:56:12.863171Z","end":"2026-03-13T09:56:13.270552Z","steps":["trace[1378776467] 'process raft request' (duration: 407.060571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:13.270684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:12.86316Z","time spent":"407.436222ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:56:13.270774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.517731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58fe45d6-f24f-41b6-a9e3-6e77e76b263f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-13T09:56:13.270845Z","caller":"traceutil/trace.go:171","msg":"trace[1034513422] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58fe45d6-f24f-41b6-a9e3-6e77e76b263f; range_end:; response_count:1; response_revision:1599; }","duration":"154.646945ms","start":"2026-03-13T09:56:13.116179Z","end":"2026-03-13T09:56:13.270826Z","steps":["trace[1034513422] 'agreement among raft nodes before linearized reading' (duration: 154.454759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:13.270941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.958921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-03-13T09:56:13.270994Z","caller":"traceutil/trace.go:171","msg":"trace[1772239036] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1599; }","duration":"226.065105ms","start":"2026-03-13T09:56:13.044917Z","end":"2026-03-13T09:56:13.270982Z","steps":["trace[1772239036] 'agreement among raft nodes before linearized reading' (duration: 225.948592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:13.271061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.332716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-13T09:56:13.27119Z","caller":"traceutil/trace.go:171","msg":"trace[276184771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1599; }","duration":"142.48869ms","start":"2026-03-13T09:56:13.128654Z","end":"2026-03-13T09:56:13.271142Z","steps":["trace[276184771] 'agreement among raft nodes before linearized reading' (duration: 142.200052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:56.625558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15424438288102676629,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T09:56:56.727444Z","caller":"traceutil/trace.go:171","msg":"trace[847117942] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"609.065605ms","start":"2026-03-13T09:56:56.118352Z","end":"2026-03-13T09:56:56.727418Z","steps":["trace[847117942] 'process raft request' (duration: 608.90461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:56.727613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.118335Z","time spent":"609.194259ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:56:56.91237Z","caller":"traceutil/trace.go:171","msg":"trace[1235795021] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1983; }","duration":"786.850934ms","start":"2026-03-13T09:56:56.125503Z","end":"2026-03-13T09:56:56.912354Z","steps":["trace[1235795021] 'read index received' (duration: 601.828436ms)","trace[1235795021] 'applied index is now lower than readState.Index' (duration: 185.021758ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:56:56.912582Z","caller":"traceutil/trace.go:171","msg":"trace[435043557] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"766.995409ms","start":"2026-03-13T09:56:56.145577Z","end":"2026-03-13T09:56:56.912573Z","steps":["trace[435043557] 'process raft request' (duration: 764.248734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:56.912696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.145564Z","time spent":"767.04666ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:56:56.912951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.116938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-03-13T09:56:56.913035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.854069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:56:56.913079Z","caller":"traceutil/trace.go:171","msg":"trace[1006826471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1921; }","duration":"456.939121ms","start":"2026-03-13T09:56:56.456128Z","end":"2026-03-13T09:56:56.913067Z","steps":["trace[1006826471] 'agreement among raft nodes before linearized reading' (duration: 456.871919ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:56.913069Z","caller":"traceutil/trace.go:171","msg":"trace[767232722] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1921; }","duration":"757.281353ms","start":"2026-03-13T09:56:56.155757Z","end":"2026-03-13T09:56:56.913038Z","steps":["trace[767232722] 'agreement among raft nodes before linearized reading' (duration: 756.926973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:56.913105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.456103Z","time spent":"456.996912ms","remote":"127.0.0.1:55132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T09:56:56.913138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.155744Z","time spent":"757.382585ms","remote":"127.0.0.1:39742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-13T09:56:56.913371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.751459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-13T09:56:56.913466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.40973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-13T09:56:56.913498Z","caller":"traceutil/trace.go:171","msg":"trace[1867191225] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1921; }","duration":"110.484902ms","start":"2026-03-13T09:56:56.803004Z","end":"2026-03-13T09:56:56.913489Z","steps":["trace[1867191225] 'agreement among raft nodes before linearized reading' (duration: 109.895286ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:56.913497Z","caller":"traceutil/trace.go:171","msg":"trace[1940616679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1921; }","duration":"788.000146ms","start":"2026-03-13T09:56:56.125468Z","end":"2026-03-13T09:56:56.913468Z","steps":["trace[1940616679] 'agreement among raft nodes before linearized reading' (duration: 787.540913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:56.913557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.125417Z","time spent":"788.127839ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T09:56:56.913816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.225671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189c5e138f6f6e08\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-13T09:56:56.914571Z","caller":"traceutil/trace.go:171","msg":"trace[1305501602] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.189c5e138f6f6e08; range_end:; response_count:1; response_revision:1921; }","duration":"534.968611ms","start":"2026-03-13T09:56:56.379505Z","end":"2026-03-13T09:56:56.914474Z","steps":["trace[1305501602] 'agreement among raft nodes before linearized reading' (duration: 533.653065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:56.914751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.379419Z","time spent":"535.295711ms","remote":"127.0.0.1:55186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189c5e138f6f6e08\" "} {"level":"info","ts":"2026-03-13T09:56:57.291187Z","caller":"traceutil/trace.go:171","msg":"trace[1486720122] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"372.431951ms","start":"2026-03-13T09:56:56.91873Z","end":"2026-03-13T09:56:57.291162Z","steps":["trace[1486720122] 'process raft request' (duration: 372.270447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:57.291321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.918721Z","time spent":"372.532265ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:56:57.291501Z","caller":"traceutil/trace.go:171","msg":"trace[1201299657] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"372.314758ms","start":"2026-03-13T09:56:56.919143Z","end":"2026-03-13T09:56:57.291458Z","steps":["trace[1201299657] 'process raft request' (duration: 371.980519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:57.291724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.780778ms","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-03-13T09:56:57.291716Z","caller":"traceutil/trace.go:171","msg":"trace[940971490] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"334.248184ms","start":"2026-03-13T09:56:56.957451Z","end":"2026-03-13T09:56:57.291699Z","steps":["trace[940971490] 'process raft request' (duration: 333.878404ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:57.291541Z","caller":"traceutil/trace.go:171","msg":"trace[1530281363] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1986; }","duration":"189.572453ms","start":"2026-03-13T09:56:57.101947Z","end":"2026-03-13T09:56:57.291519Z","steps":["trace[1530281363] 'read index received' (duration: 189.076039ms)","trace[1530281363] 'applied index is now lower than readState.Index' (duration: 494.754µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:56:57.291876Z","caller":"traceutil/trace.go:171","msg":"trace[1487587474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1925; }","duration":"189.889692ms","start":"2026-03-13T09:56:57.101879Z","end":"2026-03-13T09:56:57.291768Z","steps":["trace[1487587474] 'agreement among raft nodes before linearized reading' (duration: 189.701387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:57.291945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.919123Z","time spent":"372.496284ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:56:57.292009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:56:56.957426Z","time spent":"334.363097ms","remote":"127.0.0.1:55562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16383,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:57:39.393664Z","caller":"traceutil/trace.go:171","msg":"trace[1491628473] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"246.85198ms","start":"2026-03-13T09:57:39.146774Z","end":"2026-03-13T09:57:39.393626Z","steps":["trace[1491628473] 'process raft request' (duration: 246.614034ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:58:09.352208Z","caller":"traceutil/trace.go:171","msg":"trace[1999339510] linearizableReadLoop","detail":"{readStateIndex:2525; appliedIndex:2524; }","duration":"438.400039ms","start":"2026-03-13T09:58:08.913782Z","end":"2026-03-13T09:58:09.352182Z","steps":["trace[1999339510] 'read index received' (duration: 438.251815ms)","trace[1999339510] 'applied index is now lower than readState.Index' (duration: 146.744µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:58:09.352277Z","caller":"traceutil/trace.go:171","msg":"trace[754556729] transaction","detail":"{read_only:false; response_revision:2429; number_of_response:1; }","duration":"444.552418ms","start":"2026-03-13T09:58:08.907694Z","end":"2026-03-13T09:58:09.352246Z","steps":["trace[754556729] 'process raft request' (duration: 444.353792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:58:09.352445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.663297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-03-13T09:58:09.352441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:58:08.90767Z","time spent":"444.669031ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:58:09.352487Z","caller":"traceutil/trace.go:171","msg":"trace[1586696797] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2429; }","duration":"438.731628ms","start":"2026-03-13T09:58:08.913743Z","end":"2026-03-13T09:58:09.352475Z","steps":["trace[1586696797] 'agreement among raft nodes before linearized reading' (duration: 438.582484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:58:09.35252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:58:08.913731Z","time spent":"438.78137ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-03-13T09:58:09.352582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.416359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-03-13T09:58:09.352685Z","caller":"traceutil/trace.go:171","msg":"trace[1213772967] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2429; }","duration":"384.588104ms","start":"2026-03-13T09:58:08.968075Z","end":"2026-03-13T09:58:09.352663Z","steps":["trace[1213772967] 'agreement among raft nodes before linearized reading' (duration: 384.298726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:58:09.352737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:58:08.968044Z","time spent":"384.684147ms","remote":"127.0.0.1:55260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11523,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-13T09:58:09.352597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.841315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-13T09:58:09.352806Z","caller":"traceutil/trace.go:171","msg":"trace[913727096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2429; }","duration":"252.117232ms","start":"2026-03-13T09:58:09.100668Z","end":"2026-03-13T09:58:09.352785Z","steps":["trace[913727096] 'agreement among raft nodes before linearized reading' (duration: 251.765103ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:58:14.88022Z","caller":"traceutil/trace.go:171","msg":"trace[795173456] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"284.067807ms","start":"2026-03-13T09:58:14.59611Z","end":"2026-03-13T09:58:14.880178Z","steps":["trace[795173456] 'process raft request' (duration: 252.093161ms)","trace[795173456] 'compare' (duration: 31.687319ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:59:22.310147Z","caller":"traceutil/trace.go:171","msg":"trace[482377204] transaction","detail":"{read_only:false; response_revision:3055; number_of_response:1; }","duration":"714.447587ms","start":"2026-03-13T09:59:21.595669Z","end":"2026-03-13T09:59:22.310116Z","steps":["trace[482377204] 'process raft request' (duration: 714.204001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:59:22.310379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:59:21.595635Z","time spent":"714.640012ms","remote":"127.0.0.1:55562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:59:22.389252Z","caller":"traceutil/trace.go:171","msg":"trace[152603594] linearizableReadLoop","detail":"{readStateIndex:3184; appliedIndex:3182; }","duration":"564.050974ms","start":"2026-03-13T09:59:21.825169Z","end":"2026-03-13T09:59:22.38922Z","steps":["trace[152603594] 'read index received' (duration: 484.662297ms)","trace[152603594] 'applied index is now lower than readState.Index' (duration: 79.387227ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:59:22.389323Z","caller":"traceutil/trace.go:171","msg":"trace[110042217] transaction","detail":"{read_only:false; response_revision:3056; number_of_response:1; }","duration":"786.459531ms","start":"2026-03-13T09:59:21.602836Z","end":"2026-03-13T09:59:22.389296Z","steps":["trace[110042217] 'process raft request' (duration: 749.609181ms)","trace[110042217] 'compare' (duration: 36.640504ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:59:22.389447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:59:21.602814Z","time spent":"786.559314ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:59:22.389599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.397073ms","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-03-13T09:59:22.389706Z","caller":"traceutil/trace.go:171","msg":"trace[757227022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3056; }","duration":"564.553728ms","start":"2026-03-13T09:59:21.825127Z","end":"2026-03-13T09:59:22.389681Z","steps":["trace[757227022] 'agreement among raft nodes before linearized reading' (duration: 564.231619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:59:22.389764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:59:21.82511Z","time spent":"564.64192ms","remote":"127.0.0.1:55346","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":"warn","ts":"2026-03-13T09:59:22.416203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.094095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T09:59:22.416249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.735434ms","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-03-13T09:59:22.416284Z","caller":"traceutil/trace.go:171","msg":"trace[274850151] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:3056; }","duration":"309.231839ms","start":"2026-03-13T09:59:22.107032Z","end":"2026-03-13T09:59:22.416264Z","steps":["trace[274850151] 'agreement among raft nodes before linearized reading' (duration: 309.050484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:59:22.416307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.463352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-03-13T09:59:22.416314Z","caller":"traceutil/trace.go:171","msg":"trace[943768988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3056; }","duration":"343.858886ms","start":"2026-03-13T09:59:22.072436Z","end":"2026-03-13T09:59:22.416295Z","steps":["trace[943768988] 'agreement among raft nodes before linearized reading' (duration: 343.60979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:59:22.416328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:59:22.107015Z","time spent":"309.30292ms","remote":"127.0.0.1:34396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":16,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-03-13T09:59:22.416347Z","caller":"traceutil/trace.go:171","msg":"trace[1682773296] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3056; }","duration":"190.541594ms","start":"2026-03-13T09:59:22.225783Z","end":"2026-03-13T09:59:22.416325Z","steps":["trace[1682773296] 'agreement among raft nodes before linearized reading' (duration: 190.471322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:59:22.416362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:59:22.072414Z","time spent":"343.936529ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T09:59:22.416231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.76265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-03-13T09:59:22.416507Z","caller":"traceutil/trace.go:171","msg":"trace[592352066] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3056; }","duration":"191.016467ms","start":"2026-03-13T09:59:22.225422Z","end":"2026-03-13T09:59:22.416438Z","steps":["trace[592352066] 'agreement among raft nodes before linearized reading' (duration: 190.629917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:59:22.416519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.187204ms","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-03-13T09:59:22.416596Z","caller":"traceutil/trace.go:171","msg":"trace[17677753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3056; }","duration":"341.361078ms","start":"2026-03-13T09:59:22.075218Z","end":"2026-03-13T09:59:22.416579Z","steps":["trace[17677753] 'agreement among raft nodes before linearized reading' (duration: 341.06684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:59:22.416603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.733296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-03-13T09:59:22.416697Z","caller":"traceutil/trace.go:171","msg":"trace[685264625] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret; range_end:; response_count:1; response_revision:3056; }","duration":"207.843819ms","start":"2026-03-13T09:59:22.208825Z","end":"2026-03-13T09:59:22.416669Z","steps":["trace[685264625] 'agreement among raft nodes before linearized reading' (duration: 207.5223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:59:22.416629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:59:22.075195Z","time spent":"341.42471ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T10:00:32.059725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.514678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:00:32.060092Z","caller":"traceutil/trace.go:171","msg":"trace[1914644053] transaction","detail":"{read_only:false; response_revision:4032; number_of_response:1; }","duration":"200.197527ms","start":"2026-03-13T10:00:31.859867Z","end":"2026-03-13T10:00:32.060065Z","steps":["trace[1914644053] 'process raft request' (duration: 38.267119ms)","trace[1914644053] 'compare' (duration: 161.338672ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:00:32.060366Z","caller":"traceutil/trace.go:171","msg":"trace[1766481307] transaction","detail":"{read_only:false; response_revision:4034; number_of_response:1; }","duration":"189.650472ms","start":"2026-03-13T10:00:31.870688Z","end":"2026-03-13T10:00:32.060338Z","steps":["trace[1766481307] 'process raft request' (duration: 189.254841ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:00:32.060434Z","caller":"traceutil/trace.go:171","msg":"trace[1458303531] transaction","detail":"{read_only:false; response_revision:4033; number_of_response:1; }","duration":"200.088645ms","start":"2026-03-13T10:00:31.860303Z","end":"2026-03-13T10:00:32.060392Z","steps":["trace[1458303531] 'process raft request' (duration: 199.554891ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:00:35.050643Z","caller":"traceutil/trace.go:171","msg":"trace[35236823] transaction","detail":"{read_only:false; response_revision:4138; number_of_response:1; }","duration":"120.483032ms","start":"2026-03-13T10:00:34.93013Z","end":"2026-03-13T10:00:35.050613Z","steps":["trace[35236823] 'process raft request' (duration: 94.877537ms)","trace[35236823] 'compare' (duration: 25.340718ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:01:37.39466Z","caller":"traceutil/trace.go:171","msg":"trace[790610183] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"277.114524ms","start":"2026-03-13T10:01:37.117519Z","end":"2026-03-13T10:01:37.394634Z","steps":["trace[790610183] 'process raft request' (duration: 276.9671ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:37.395141Z","caller":"traceutil/trace.go:171","msg":"trace[2050144336] linearizableReadLoop","detail":"{readStateIndex:5015; appliedIndex:5015; }","duration":"270.550479ms","start":"2026-03-13T10:01:37.124577Z","end":"2026-03-13T10:01:37.395128Z","steps":["trace[2050144336] 'read index received' (duration: 270.544739ms)","trace[2050144336] 'applied index is now lower than readState.Index' (duration: 4.83µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:01:37.395278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.693172ms","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-03-13T10:01:37.395303Z","caller":"traceutil/trace.go:171","msg":"trace[1063688388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4803; }","duration":"270.773044ms","start":"2026-03-13T10:01:37.124524Z","end":"2026-03-13T10:01:37.395297Z","steps":["trace[1063688388] 'agreement among raft nodes before linearized reading' (duration: 270.666972ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:37.429436Z","caller":"traceutil/trace.go:171","msg":"trace[1255340389] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"166.831792ms","start":"2026-03-13T10:01:37.262579Z","end":"2026-03-13T10:01:37.42941Z","steps":["trace[1255340389] 'process raft request' (duration: 166.709068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:37.429684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.004331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-13T10:01:37.429731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.103374ms","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-03-13T10:01:37.429759Z","caller":"traceutil/trace.go:171","msg":"trace[1840994453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4804; }","duration":"298.094574ms","start":"2026-03-13T10:01:37.131646Z","end":"2026-03-13T10:01:37.42974Z","steps":["trace[1840994453] 'agreement among raft nodes before linearized reading' (duration: 297.880538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:37.429739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.410803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-13T10:01:37.429772Z","caller":"traceutil/trace.go:171","msg":"trace[835222163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4804; }","duration":"298.192926ms","start":"2026-03-13T10:01:37.131569Z","end":"2026-03-13T10:01:37.429762Z","steps":["trace[835222163] 'agreement among raft nodes before linearized reading' (duration: 297.887458ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:37.429841Z","caller":"traceutil/trace.go:171","msg":"trace[57273036] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4804; }","duration":"150.523706ms","start":"2026-03-13T10:01:37.279288Z","end":"2026-03-13T10:01:37.429812Z","steps":["trace[57273036] 'agreement among raft nodes before linearized reading' (duration: 150.26639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:37.430433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.499293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-03-13T10:01:37.430526Z","caller":"traceutil/trace.go:171","msg":"trace[44016236] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4804; }","duration":"181.660767ms","start":"2026-03-13T10:01:37.248844Z","end":"2026-03-13T10:01:37.430505Z","steps":["trace[44016236] 'agreement among raft nodes before linearized reading' (duration: 180.65063ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:38.020838Z","caller":"traceutil/trace.go:171","msg":"trace[1123313990] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"115.355528ms","start":"2026-03-13T10:01:37.905449Z","end":"2026-03-13T10:01:38.020804Z","steps":["trace[1123313990] 'process raft request' (duration: 114.966127ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:38.754136Z","caller":"traceutil/trace.go:171","msg":"trace[988744980] linearizableReadLoop","detail":"{readStateIndex:5031; appliedIndex:5030; }","duration":"323.604014ms","start":"2026-03-13T10:01:38.430498Z","end":"2026-03-13T10:01:38.754102Z","steps":["trace[988744980] 'read index received' (duration: 323.4683ms)","trace[988744980] 'applied index is now lower than readState.Index' (duration: 134.314µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:01:38.754164Z","caller":"traceutil/trace.go:171","msg":"trace[1220162583] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"325.602017ms","start":"2026-03-13T10:01:38.428538Z","end":"2026-03-13T10:01:38.75414Z","steps":["trace[1220162583] 'process raft request' (duration: 325.292949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:38.754315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:38.428514Z","time spent":"325.71135ms","remote":"127.0.0.1:55186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:01:38.754444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.923272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-03-13T10:01:38.754523Z","caller":"traceutil/trace.go:171","msg":"trace[1999948743] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4819; }","duration":"324.040156ms","start":"2026-03-13T10:01:38.430464Z","end":"2026-03-13T10:01:38.754504Z","steps":["trace[1999948743] 'agreement among raft nodes before linearized reading' (duration: 323.791639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:38.75457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:38.430453Z","time spent":"324.105457ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-03-13T10:01:38.789264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.128605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T10:01:38.7893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.082367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T10:01:38.789356Z","caller":"traceutil/trace.go:171","msg":"trace[1628900858] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4819; }","duration":"328.260828ms","start":"2026-03-13T10:01:38.461072Z","end":"2026-03-13T10:01:38.789333Z","steps":["trace[1628900858] 'agreement among raft nodes before linearized reading' (duration: 328.088313ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:38.789381Z","caller":"traceutil/trace.go:171","msg":"trace[2011399869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4819; }","duration":"333.224901ms","start":"2026-03-13T10:01:38.456138Z","end":"2026-03-13T10:01:38.789363Z","steps":["trace[2011399869] 'agreement among raft nodes before linearized reading' (duration: 333.081487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:38.789399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:38.461058Z","time spent":"328.331231ms","remote":"127.0.0.1:55224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-03-13T10:01:38.789427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:38.456117Z","time spent":"333.302452ms","remote":"127.0.0.1:55132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T10:01:40.494086Z","caller":"traceutil/trace.go:171","msg":"trace[1545742188] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"187.882322ms","start":"2026-03-13T10:01:40.306124Z","end":"2026-03-13T10:01:40.494007Z","steps":["trace[1545742188] 'process raft request' (duration: 132.963797ms)","trace[1545742188] 'compare' (duration: 54.763651ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:01:40.495708Z","caller":"traceutil/trace.go:171","msg":"trace[1589399091] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"187.908373ms","start":"2026-03-13T10:01:40.30777Z","end":"2026-03-13T10:01:40.495678Z","steps":["trace[1589399091] 'process raft request' (duration: 187.688407ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:40.496116Z","caller":"traceutil/trace.go:171","msg":"trace[917211253] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"179.780496ms","start":"2026-03-13T10:01:40.316321Z","end":"2026-03-13T10:01:40.496101Z","steps":["trace[917211253] 'process raft request' (duration: 179.335294ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:40.496354Z","caller":"traceutil/trace.go:171","msg":"trace[274417190] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"186.598938ms","start":"2026-03-13T10:01:40.309429Z","end":"2026-03-13T10:01:40.496028Z","steps":["trace[274417190] 'process raft request' (duration: 186.160777ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:51.205385Z","caller":"traceutil/trace.go:171","msg":"trace[566555417] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"108.460044ms","start":"2026-03-13T10:01:51.096898Z","end":"2026-03-13T10:01:51.205358Z","steps":["trace[566555417] 'process raft request' (duration: 108.272839ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:02:43.454225Z","caller":"traceutil/trace.go:171","msg":"trace[619467138] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"115.528072ms","start":"2026-03-13T10:02:43.338668Z","end":"2026-03-13T10:02:43.454196Z","steps":["trace[619467138] 'process raft request' (duration: 50.367523ms)","trace[619467138] 'compare' (duration: 64.999727ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:03:39.202592Z","caller":"traceutil/trace.go:171","msg":"trace[643628816] linearizableReadLoop","detail":"{readStateIndex:6206; appliedIndex:6205; }","duration":"101.319681ms","start":"2026-03-13T10:03:39.101248Z","end":"2026-03-13T10:03:39.202568Z","steps":["trace[643628816] 'read index received' (duration: 101.096445ms)","trace[643628816] 'applied index is now lower than readState.Index' (duration: 222.236µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:03:39.202686Z","caller":"traceutil/trace.go:171","msg":"trace[490573700] transaction","detail":"{read_only:false; response_revision:5940; number_of_response:1; }","duration":"106.741448ms","start":"2026-03-13T10:03:39.095902Z","end":"2026-03-13T10:03:39.202644Z","steps":["trace[490573700] 'process raft request' (duration: 106.463821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:03:39.202789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.531797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-03-13T10:03:39.202827Z","caller":"traceutil/trace.go:171","msg":"trace[1510648261] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5940; }","duration":"101.622169ms","start":"2026-03-13T10:03:39.101195Z","end":"2026-03-13T10:03:39.202817Z","steps":["trace[1510648261] 'agreement among raft nodes before linearized reading' (duration: 101.480765ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:04:20.22585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3048} {"level":"info","ts":"2026-03-13T10:04:20.297583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3048,"took":"70.200123ms","hash":3369582191,"current-db-size-bytes":43798528,"current-db-size":"44 MB","current-db-size-in-use-bytes":30199808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-13T10:04:20.297648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3369582191,"revision":3048,"compact-revision":-1} {"level":"info","ts":"2026-03-13T10:07:48.548048Z","caller":"traceutil/trace.go:171","msg":"trace[368266776] transaction","detail":"{read_only:false; response_revision:7998; number_of_response:1; }","duration":"363.660545ms","start":"2026-03-13T10:07:48.184361Z","end":"2026-03-13T10:07:48.548022Z","steps":["trace[368266776] 'process raft request' (duration: 363.482701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:07:48.548269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:07:48.184348Z","time spent":"363.787309ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T10:07:48.550395Z","caller":"traceutil/trace.go:171","msg":"trace[1328741169] linearizableReadLoop","detail":"{readStateIndex:8374; appliedIndex:8372; }","duration":"182.277075ms","start":"2026-03-13T10:07:48.368092Z","end":"2026-03-13T10:07:48.550369Z","steps":["trace[1328741169] 'read index received' (duration: 179.830958ms)","trace[1328741169] 'applied index is now lower than readState.Index' (duration: 2.445137ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:07:48.550704Z","caller":"traceutil/trace.go:171","msg":"trace[1164013495] transaction","detail":"{read_only:false; response_revision:7999; number_of_response:1; }","duration":"363.543612ms","start":"2026-03-13T10:07:48.187148Z","end":"2026-03-13T10:07:48.550691Z","steps":["trace[1164013495] 'process raft request' (duration: 363.071439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:07:48.550812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:07:48.187132Z","time spent":"363.603643ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:07:48.551103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.011365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-03-13T10:07:48.551139Z","caller":"traceutil/trace.go:171","msg":"trace[2017046084] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7999; }","duration":"183.071897ms","start":"2026-03-13T10:07:48.368054Z","end":"2026-03-13T10:07:48.551126Z","steps":["trace[2017046084] 'agreement among raft nodes before linearized reading' (duration: 182.951343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:00.364247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.422733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cd62c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T10:08:00.364328Z","caller":"traceutil/trace.go:171","msg":"trace[346010510] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cd62c; range_end:; response_count:1; response_revision:8072; }","duration":"490.560446ms","start":"2026-03-13T10:07:59.873749Z","end":"2026-03-13T10:08:00.36431Z","steps":["trace[346010510] 'range keys from in-memory index tree' (duration: 490.206617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:00.364368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:07:59.873719Z","time spent":"490.641068ms","remote":"127.0.0.1:55260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-cd62c\" "} {"level":"warn","ts":"2026-03-13T10:08:01.147973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.062708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:08:01.148201Z","caller":"traceutil/trace.go:171","msg":"trace[799981213] transaction","detail":"{read_only:false; response_revision:8075; number_of_response:1; }","duration":"472.474778ms","start":"2026-03-13T10:08:00.675594Z","end":"2026-03-13T10:08:01.148069Z","steps":["trace[799981213] 'process raft request' (duration: 298.237045ms)","trace[799981213] 'compare' (duration: 173.956325ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:01.148303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:00.675578Z","time spent":"472.674523ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T10:08:01.168944Z","caller":"traceutil/trace.go:171","msg":"trace[741180466] transaction","detail":"{read_only:false; response_revision:8076; number_of_response:1; }","duration":"491.373447ms","start":"2026-03-13T10:08:00.677542Z","end":"2026-03-13T10:08:01.168915Z","steps":["trace[741180466] 'process raft request' (duration: 491.140461ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:01.168993Z","caller":"traceutil/trace.go:171","msg":"trace[278286667] transaction","detail":"{read_only:false; response_revision:8077; number_of_response:1; }","duration":"239.906529ms","start":"2026-03-13T10:08:00.929052Z","end":"2026-03-13T10:08:01.168959Z","steps":["trace[278286667] 'process raft request' (duration: 239.772356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:01.169088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:00.677531Z","time spent":"491.48452ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T10:08:01.618084Z","caller":"traceutil/trace.go:171","msg":"trace[832740582] transaction","detail":"{read_only:false; response_revision:8084; number_of_response:1; }","duration":"102.814838ms","start":"2026-03-13T10:08:01.515216Z","end":"2026-03-13T10:08:01.618031Z","steps":["trace[832740582] 'process raft request' (duration: 102.763217ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:01.618125Z","caller":"traceutil/trace.go:171","msg":"trace[2060885242] transaction","detail":"{read_only:false; response_revision:8083; number_of_response:1; }","duration":"105.76078ms","start":"2026-03-13T10:08:01.512328Z","end":"2026-03-13T10:08:01.618089Z","steps":["trace[2060885242] 'process raft request' (duration: 105.599325ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:01.618069Z","caller":"traceutil/trace.go:171","msg":"trace[525166947] transaction","detail":"{read_only:false; response_revision:8082; number_of_response:1; }","duration":"105.894084ms","start":"2026-03-13T10:08:01.512122Z","end":"2026-03-13T10:08:01.618016Z","steps":["trace[525166947] 'process raft request' (duration: 105.641517ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:28.371086Z","caller":"traceutil/trace.go:171","msg":"trace[985220869] linearizableReadLoop","detail":"{readStateIndex:8693; appliedIndex:8692; }","duration":"426.71048ms","start":"2026-03-13T10:08:27.944359Z","end":"2026-03-13T10:08:28.371069Z","steps":["trace[985220869] 'read index received' (duration: 426.553956ms)","trace[985220869] 'applied index is now lower than readState.Index' (duration: 155.984µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:28.371142Z","caller":"traceutil/trace.go:171","msg":"trace[1703799344] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"562.950038ms","start":"2026-03-13T10:08:27.808156Z","end":"2026-03-13T10:08:28.371106Z","steps":["trace[1703799344] 'process raft request' (duration: 562.812284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:28.371227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.330731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T10:08:28.37126Z","caller":"traceutil/trace.go:171","msg":"trace[207906523] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8300; }","duration":"337.423323ms","start":"2026-03-13T10:08:28.03383Z","end":"2026-03-13T10:08:28.371253Z","steps":["trace[207906523] 'agreement among raft nodes before linearized reading' (duration: 337.328181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:28.371282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:28.033788Z","time spent":"337.488495ms","remote":"127.0.0.1:34404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-13T10:08:28.371304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:27.808133Z","time spent":"563.082121ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:08:28.371464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.07047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-13T10:08:28.371587Z","caller":"traceutil/trace.go:171","msg":"trace[2119185207] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8300; }","duration":"427.282116ms","start":"2026-03-13T10:08:27.944279Z","end":"2026-03-13T10:08:28.371561Z","steps":["trace[2119185207] 'agreement among raft nodes before linearized reading' (duration: 426.886445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:28.371649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:27.944253Z","time spent":"427.386339ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-03-13T10:08:28.688806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.229296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:08:28.688928Z","caller":"traceutil/trace.go:171","msg":"trace[1100136437] linearizableReadLoop","detail":"{readStateIndex:8694; appliedIndex:8693; }","duration":"154.83959ms","start":"2026-03-13T10:08:28.534068Z","end":"2026-03-13T10:08:28.688907Z","steps":["trace[1100136437] 'read index received' (duration: 13.458131ms)","trace[1100136437] 'applied index is now lower than readState.Index' (duration: 141.380149ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:28.689109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.072986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cd62c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T10:08:28.689142Z","caller":"traceutil/trace.go:171","msg":"trace[891099833] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cd62c; range_end:; response_count:1; response_revision:8301; }","duration":"155.160698ms","start":"2026-03-13T10:08:28.533971Z","end":"2026-03-13T10:08:28.689132Z","steps":["trace[891099833] 'agreement among raft nodes before linearized reading' (duration: 154.987394ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:28.689235Z","caller":"traceutil/trace.go:171","msg":"trace[670835365] transaction","detail":"{read_only:false; response_revision:8301; number_of_response:1; }","duration":"313.105904ms","start":"2026-03-13T10:08:28.376076Z","end":"2026-03-13T10:08:28.689182Z","steps":["trace[670835365] 'process raft request' (duration: 171.404685ms)","trace[670835365] 'compare' (duration: 141.121583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:28.689432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:28.376062Z","time spent":"313.268879ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T10:08:41.037562Z","caller":"traceutil/trace.go:171","msg":"trace[1571485260] linearizableReadLoop","detail":"{readStateIndex:8814; appliedIndex:8813; }","duration":"149.415821ms","start":"2026-03-13T10:08:40.888116Z","end":"2026-03-13T10:08:41.037532Z","steps":["trace[1571485260] 'read index received' (duration: 69.603975ms)","trace[1571485260] 'applied index is now lower than readState.Index' (duration: 79.810655ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:41.037716Z","caller":"traceutil/trace.go:171","msg":"trace[981020990] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"149.76102ms","start":"2026-03-13T10:08:40.887841Z","end":"2026-03-13T10:08:41.037602Z","steps":["trace[981020990] 'process raft request' (duration: 69.77447ms)","trace[981020990] 'compare' (duration: 79.785895ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:41.037868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.719689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-13T10:08:41.037966Z","caller":"traceutil/trace.go:171","msg":"trace[1570531872] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8413; }","duration":"149.841462ms","start":"2026-03-13T10:08:40.888101Z","end":"2026-03-13T10:08:41.037943Z","steps":["trace[1570531872] 'agreement among raft nodes before linearized reading' (duration: 149.524213ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:41.268144Z","caller":"traceutil/trace.go:171","msg":"trace[1982735007] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"199.900189ms","start":"2026-03-13T10:08:41.068215Z","end":"2026-03-13T10:08:41.268115Z","steps":["trace[1982735007] 'process raft request' (duration: 199.740355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:42.187884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15424438288102694286,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T10:08:42.350632Z","caller":"traceutil/trace.go:171","msg":"trace[1522599093] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"703.204447ms","start":"2026-03-13T10:08:41.647402Z","end":"2026-03-13T10:08:42.350607Z","steps":["trace[1522599093] 'process raft request' (duration: 703.069723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:42.350774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.647382Z","time spent":"703.317729ms","remote":"127.0.0.1:55562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T10:08:42.383667Z","caller":"traceutil/trace.go:171","msg":"trace[315962356] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"735.178496ms","start":"2026-03-13T10:08:41.648451Z","end":"2026-03-13T10:08:42.38363Z","steps":["trace[315962356] 'process raft request' (duration: 734.873028ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:42.383749Z","caller":"traceutil/trace.go:171","msg":"trace[615303628] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"601.236691ms","start":"2026-03-13T10:08:41.782478Z","end":"2026-03-13T10:08:42.383714Z","steps":["trace[615303628] 'process raft request' (duration: 601.063517ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:42.383807Z","caller":"traceutil/trace.go:171","msg":"trace[290218537] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"734.328813ms","start":"2026-03-13T10:08:41.649405Z","end":"2026-03-13T10:08:42.383734Z","steps":["trace[290218537] 'process raft request' (duration: 734.092326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:42.383973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.782453Z","time spent":"601.401895ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:08:42.384095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.6494Z","time spent":"734.545059ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:08:42.384041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.648435Z","time spent":"735.309649ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T10:08:42.384068Z","caller":"traceutil/trace.go:171","msg":"trace[420109252] linearizableReadLoop","detail":"{readStateIndex:8825; appliedIndex:8820; }","duration":"696.309557ms","start":"2026-03-13T10:08:41.687569Z","end":"2026-03-13T10:08:42.383878Z","steps":["trace[420109252] 'read index received' (duration: 662.96581ms)","trace[420109252] 'applied index is now lower than readState.Index' (duration: 33.340777ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:42.384324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.734838ms","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-03-13T10:08:42.384368Z","caller":"traceutil/trace.go:171","msg":"trace[269543515] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"504.917321ms","start":"2026-03-13T10:08:41.879436Z","end":"2026-03-13T10:08:42.384353Z","steps":["trace[269543515] 'process raft request' (duration: 504.16051ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:42.384389Z","caller":"traceutil/trace.go:171","msg":"trace[523108026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8424; }","duration":"696.865992ms","start":"2026-03-13T10:08:41.687506Z","end":"2026-03-13T10:08:42.384372Z","steps":["trace[523108026] 'agreement among raft nodes before linearized reading' (duration: 696.633196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:42.384433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.687486Z","time spent":"696.936564ms","remote":"127.0.0.1:55346","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":"warn","ts":"2026-03-13T10:08:42.384434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.879419Z","time spent":"504.978972ms","remote":"127.0.0.1:55332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2999,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:08:42.454461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.695062ms","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-03-13T10:08:42.454515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.519843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T10:08:42.454568Z","caller":"traceutil/trace.go:171","msg":"trace[862287352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8424; }","duration":"626.841255ms","start":"2026-03-13T10:08:41.827705Z","end":"2026-03-13T10:08:42.454546Z","steps":["trace[862287352] 'agreement among raft nodes before linearized reading' (duration: 626.591239ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:42.454595Z","caller":"traceutil/trace.go:171","msg":"trace[1956213007] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8424; }","duration":"567.648977ms","start":"2026-03-13T10:08:41.886926Z","end":"2026-03-13T10:08:42.454575Z","steps":["trace[1956213007] 'agreement among raft nodes before linearized reading' (duration: 567.468902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:42.454622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.827694Z","time spent":"626.910508ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T10:08:42.454642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.886914Z","time spent":"567.717769ms","remote":"127.0.0.1:55544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-13T10:08:42.454616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.134377ms","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-03-13T10:08:42.454745Z","caller":"traceutil/trace.go:171","msg":"trace[331878477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8424; }","duration":"626.322581ms","start":"2026-03-13T10:08:41.828395Z","end":"2026-03-13T10:08:42.454718Z","steps":["trace[331878477] 'agreement among raft nodes before linearized reading' (duration: 625.91426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:42.454816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.828371Z","time spent":"626.432864ms","remote":"127.0.0.1:55346","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-03-13T10:08:42.454775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.700086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-13T10:08:42.454794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.493054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-13T10:08:42.454892Z","caller":"traceutil/trace.go:171","msg":"trace[1877938936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8424; }","duration":"765.84091ms","start":"2026-03-13T10:08:41.689023Z","end":"2026-03-13T10:08:42.454864Z","steps":["trace[1877938936] 'agreement among raft nodes before linearized reading' (duration: 765.48465ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:42.454934Z","caller":"traceutil/trace.go:171","msg":"trace[1976835694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8424; }","duration":"631.668919ms","start":"2026-03-13T10:08:41.823214Z","end":"2026-03-13T10:08:42.454883Z","steps":["trace[1976835694] 'agreement among raft nodes before linearized reading' (duration: 631.263007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:42.454976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.689017Z","time spent":"765.943993ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T10:08:42.455014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:41.823187Z","time spent":"631.814503ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T10:08:43.718554Z","caller":"traceutil/trace.go:171","msg":"trace[958381244] linearizableReadLoop","detail":"{readStateIndex:8837; appliedIndex:8836; }","duration":"342.074511ms","start":"2026-03-13T10:08:43.376434Z","end":"2026-03-13T10:08:43.718508Z","steps":["trace[958381244] 'read index received' (duration: 341.839015ms)","trace[958381244] 'applied index is now lower than readState.Index' (duration: 234.066µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:43.718558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:43.283179Z","time spent":"435.372858ms","remote":"127.0.0.1:55156","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-13T10:08:43.718751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.308227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-13T10:08:43.718792Z","caller":"traceutil/trace.go:171","msg":"trace[430660672] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8434; }","duration":"342.39578ms","start":"2026-03-13T10:08:43.376383Z","end":"2026-03-13T10:08:43.718779Z","steps":["trace[430660672] 'agreement among raft nodes before linearized reading' (duration: 342.235675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:43.718818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:43.376363Z","time spent":"342.449642ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-13T10:08:43.7188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.835373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T10:08:43.718902Z","caller":"traceutil/trace.go:171","msg":"trace[553228685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8434; }","duration":"265.985217ms","start":"2026-03-13T10:08:43.452892Z","end":"2026-03-13T10:08:43.718877Z","steps":["trace[553228685] 'agreement among raft nodes before linearized reading' (duration: 265.811002ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:43.848038Z","caller":"traceutil/trace.go:171","msg":"trace[757778980] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"127.545999ms","start":"2026-03-13T10:08:43.720455Z","end":"2026-03-13T10:08:43.848001Z","steps":["trace[757778980] 'process raft request' (duration: 84.490354ms)","trace[757778980] 'compare' (duration: 42.89027ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:43.869116Z","caller":"traceutil/trace.go:171","msg":"trace[1744425206] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"146.14274ms","start":"2026-03-13T10:08:43.722954Z","end":"2026-03-13T10:08:43.869096Z","steps":["trace[1744425206] 'process raft request' (duration: 145.806251ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:44.666994Z","caller":"traceutil/trace.go:171","msg":"trace[2023013528] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"154.930152ms","start":"2026-03-13T10:08:44.512042Z","end":"2026-03-13T10:08:44.666972Z","steps":["trace[2023013528] 'process raft request' (duration: 154.876271ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:44.66706Z","caller":"traceutil/trace.go:171","msg":"trace[1456585542] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"157.639117ms","start":"2026-03-13T10:08:44.509408Z","end":"2026-03-13T10:08:44.667047Z","steps":["trace[1456585542] 'process raft request' (duration: 157.38928ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:44.667184Z","caller":"traceutil/trace.go:171","msg":"trace[575148010] transaction","detail":"{read_only:false; response_revision:8441; number_of_response:1; }","duration":"158.10406ms","start":"2026-03-13T10:08:44.509039Z","end":"2026-03-13T10:08:44.667144Z","steps":["trace[575148010] 'process raft request' (duration: 114.041628ms)","trace[575148010] 'compare' (duration: 43.598169ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:44.667352Z","caller":"traceutil/trace.go:171","msg":"trace[937829448] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"157.055261ms","start":"2026-03-13T10:08:44.510244Z","end":"2026-03-13T10:08:44.667299Z","steps":["trace[937829448] 'process raft request' (duration: 156.622009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:44.891992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.362011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-13T10:08:44.892053Z","caller":"traceutil/trace.go:171","msg":"trace[1369090183] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8446; }","duration":"104.484744ms","start":"2026-03-13T10:08:44.787553Z","end":"2026-03-13T10:08:44.892038Z","steps":["trace[1369090183] 'range keys from in-memory index tree' (duration: 104.173386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:44.891992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.223912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cd62c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T10:08:44.892412Z","caller":"traceutil/trace.go:171","msg":"trace[1689642099] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cd62c; range_end:; response_count:1; response_revision:8446; }","duration":"106.691225ms","start":"2026-03-13T10:08:44.785683Z","end":"2026-03-13T10:08:44.892374Z","steps":["trace[1689642099] 'range keys from in-memory index tree' (duration: 106.039897ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:09:20.233496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6184} {"level":"info","ts":"2026-03-13T10:09:20.336992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6184,"took":"100.654844ms","hash":1628784371,"current-db-size-bytes":47251456,"current-db-size":"47 MB","current-db-size-in-use-bytes":22499328,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-13T10:09:20.337076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1628784371,"revision":6184,"compact-revision":3048} {"level":"info","ts":"2026-03-13T10:09:41.075577Z","caller":"traceutil/trace.go:171","msg":"trace[1078980968] transaction","detail":"{read_only:false; response_revision:9058; number_of_response:1; }","duration":"104.189681ms","start":"2026-03-13T10:09:40.971351Z","end":"2026-03-13T10:09:41.07554Z","steps":["trace[1078980968] 'process raft request' (duration: 104.017356ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:09:51.467393Z","caller":"traceutil/trace.go:171","msg":"trace[1831006803] linearizableReadLoop","detail":"{readStateIndex:9563; appliedIndex:9562; }","duration":"121.204149ms","start":"2026-03-13T10:09:51.346153Z","end":"2026-03-13T10:09:51.467357Z","steps":["trace[1831006803] 'read index received' (duration: 120.975992ms)","trace[1831006803] 'applied index is now lower than readState.Index' (duration: 227.227µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:09:51.467497Z","caller":"traceutil/trace.go:171","msg":"trace[1641297024] transaction","detail":"{read_only:false; response_revision:9127; number_of_response:1; }","duration":"126.204716ms","start":"2026-03-13T10:09:51.341281Z","end":"2026-03-13T10:09:51.467486Z","steps":["trace[1641297024] 'process raft request' (duration: 125.941029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:09:51.467798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.62894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-03-13T10:09:51.467846Z","caller":"traceutil/trace.go:171","msg":"trace[1741665408] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9127; }","duration":"121.689002ms","start":"2026-03-13T10:09:51.346144Z","end":"2026-03-13T10:09:51.467833Z","steps":["trace[1741665408] 'agreement among raft nodes before linearized reading' (duration: 121.514707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:09:51.746202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.224889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:09:51.746344Z","caller":"traceutil/trace.go:171","msg":"trace[1471534024] linearizableReadLoop","detail":"{readStateIndex:9564; appliedIndex:9563; }","duration":"278.857778ms","start":"2026-03-13T10:09:51.467464Z","end":"2026-03-13T10:09:51.746322Z","steps":["trace[1471534024] 'read index received' (duration: 90.31836ms)","trace[1471534024] 'applied index is now lower than readState.Index' (duration: 188.537778ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:09:51.746355Z","caller":"traceutil/trace.go:171","msg":"trace[542792148] transaction","detail":"{read_only:false; response_revision:9128; number_of_response:1; }","duration":"366.052633ms","start":"2026-03-13T10:09:51.380277Z","end":"2026-03-13T10:09:51.746329Z","steps":["trace[542792148] 'process raft request' (duration: 177.587357ms)","trace[542792148] 'compare' (duration: 188.004274ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:09:51.746671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:09:51.380222Z","time spent":"366.392812ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:09:51.746805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.129907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-03-13T10:09:51.74689Z","caller":"traceutil/trace.go:171","msg":"trace[1969549907] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9128; }","duration":"275.347792ms","start":"2026-03-13T10:09:51.471523Z","end":"2026-03-13T10:09:51.74687Z","steps":["trace[1969549907] 'agreement among raft nodes before linearized reading' (duration: 275.139146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:09:51.74683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.099437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T10:09:51.747105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.763019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6fb866f895-7wv79.189c5eaf941c1af0\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-03-13T10:09:51.747306Z","caller":"traceutil/trace.go:171","msg":"trace[1972381393] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6fb866f895-7wv79.189c5eaf941c1af0; range_end:; response_count:1; response_revision:9128; }","duration":"269.997615ms","start":"2026-03-13T10:09:51.477286Z","end":"2026-03-13T10:09:51.747284Z","steps":["trace[1972381393] 'agreement among raft nodes before linearized reading' (duration: 269.708318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:09:51.747158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.372968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-03-13T10:09:51.747262Z","caller":"traceutil/trace.go:171","msg":"trace[1116436916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9128; }","duration":"290.56268ms","start":"2026-03-13T10:09:51.456578Z","end":"2026-03-13T10:09:51.74714Z","steps":["trace[1116436916] 'agreement among raft nodes before linearized reading' (duration: 290.015484ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:09:51.747505Z","caller":"traceutil/trace.go:171","msg":"trace[90817254] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9128; }","duration":"395.737389ms","start":"2026-03-13T10:09:51.3517Z","end":"2026-03-13T10:09:51.747437Z","steps":["trace[90817254] 'agreement among raft nodes before linearized reading' (duration: 395.221604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:09:51.747728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:09:51.351694Z","time spent":"396.014346ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-03-13T10:10:42.471558Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aadce177dd8cd60e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-13T10:10:42.479953Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-13T10:10:42.480059Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-13T10:10:43.074351Z","caller":"traceutil/trace.go:171","msg":"trace[1620103407] transaction","detail":"{read_only:false; response_revision:9549; number_of_response:1; }","duration":"142.051317ms","start":"2026-03-13T10:10:42.932268Z","end":"2026-03-13T10:10:43.074319Z","steps":["trace[1620103407] 'process raft request' (duration: 61.991662ms)","trace[1620103407] 'compare' (duration: 79.924992ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:12:07.709222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.195846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:12:07.709337Z","caller":"traceutil/trace.go:171","msg":"trace[646045686] linearizableReadLoop","detail":"{readStateIndex:10834; appliedIndex:10833; }","duration":"106.666396ms","start":"2026-03-13T10:12:07.602652Z","end":"2026-03-13T10:12:07.709318Z","steps":["trace[646045686] 'read index received' (duration: 62.732µs)","trace[646045686] 'applied index is now lower than readState.Index' (duration: 106.602554ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:12:07.709422Z","caller":"traceutil/trace.go:171","msg":"trace[71569751] transaction","detail":"{read_only:false; response_revision:10340; number_of_response:1; }","duration":"220.949249ms","start":"2026-03-13T10:12:07.488436Z","end":"2026-03-13T10:12:07.709385Z","steps":["trace[71569751] 'process raft request' (duration: 97.512266ms)","trace[71569751] 'compare' (duration: 123.071583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:12:07.709517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.874841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-03-13T10:12:07.709551Z","caller":"traceutil/trace.go:171","msg":"trace[607083997] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10340; }","duration":"106.941063ms","start":"2026-03-13T10:12:07.602599Z","end":"2026-03-13T10:12:07.70954Z","steps":["trace[607083997] 'agreement among raft nodes before linearized reading' (duration: 106.807129ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:14:20.240986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8819} {"level":"info","ts":"2026-03-13T10:14:20.339317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8819,"took":"96.161685ms","hash":3797326728,"current-db-size-bytes":47251456,"current-db-size":"47 MB","current-db-size-in-use-bytes":22224896,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-13T10:14:20.339434Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3797326728,"revision":8819,"compact-revision":6184}