{"level":"warn","ts":"2026-04-01T23:28:53.144981Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T23:28:53.14512Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-04-01T23:28:53.145193Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T23:28:53.145206Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-01T23:28:53.145233Z","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-04-01T23:28:53.145951Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-04-01T23:28:53.146036Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-04-01T23:28:53.150193Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.918728ms"} {"level":"info","ts":"2026-04-01T23:28:53.156338Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-04-01T23:28:53.156472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T23:28:53.156513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-04-01T23:28:53.156541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T23:28:53.156567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-04-01T23:28:53.156641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-04-01T23:28:53.169543Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T23:28:53.173421Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T23:28:53.174957Z","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-04-01T23:28:53.178637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T23:28:53.178905Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T23:28:53.179022Z","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-04-01T23:28:53.179173Z","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-04-01T23:28:53.179193Z","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-04-01T23:28:53.181195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-04-01T23:28:53.181825Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-01T23:28:53.183481Z","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-04-01T23:28:53.183634Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-01T23:28:53.183667Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-01T23:28:53.184054Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T23:28:53.184095Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T23:28:53.35748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T23:28:53.357556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T23:28:53.357595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-04-01T23:28:53.357618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-04-01T23:28:53.357635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-01T23:28:53.357683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-04-01T23:28:53.357704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-01T23:28:53.358859Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:28:53.359901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T23:28:53.359949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:28:53.360153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:28:53.360418Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T23:28:53.36046Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T23:28:53.363304Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T23:28:53.364808Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-04-01T23:28:53.364952Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:28:53.365067Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:28:53.365118Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:29:09.356883Z","caller":"traceutil/trace.go:171","msg":"trace[742417225] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"237.552784ms","start":"2026-04-01T23:29:09.11927Z","end":"2026-04-01T23:29:09.356823Z","steps":["trace[742417225] 'process raft request' (duration: 237.454742ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:09.357727Z","caller":"traceutil/trace.go:171","msg":"trace[1968901252] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"238.775427ms","start":"2026-04-01T23:29:09.118914Z","end":"2026-04-01T23:29:09.357689Z","steps":["trace[1968901252] 'process raft request' (duration: 141.008434ms)","trace[1968901252] 'compare' (duration: 94.866093ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:30.869416Z","caller":"traceutil/trace.go:171","msg":"trace[2074195429] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"176.736801ms","start":"2026-04-01T23:29:30.692632Z","end":"2026-04-01T23:29:30.869369Z","steps":["trace[2074195429] 'read index received' (duration: 83.798331ms)","trace[2074195429] 'applied index is now lower than readState.Index' (duration: 92.93665ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:30.869449Z","caller":"traceutil/trace.go:171","msg":"trace[1451287429] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"227.374076ms","start":"2026-04-01T23:29:30.642002Z","end":"2026-04-01T23:29:30.869377Z","steps":["trace[1451287429] 'process raft request' (duration: 134.407594ms)","trace[1451287429] 'compare' (duration: 92.612932ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:30.869784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.136832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:30.869947Z","caller":"traceutil/trace.go:171","msg":"trace[161332212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"177.372238ms","start":"2026-04-01T23:29:30.69255Z","end":"2026-04-01T23:29:30.869922Z","steps":["trace[161332212] 'agreement among raft nodes before linearized reading' (duration: 176.966447ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:31.664647Z","caller":"traceutil/trace.go:171","msg":"trace[220370942] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"412.766503ms","start":"2026-04-01T23:29:31.251846Z","end":"2026-04-01T23:29:31.664612Z","steps":["trace[220370942] 'read index received' (duration: 412.724082ms)","trace[220370942] 'applied index is now lower than readState.Index' (duration: 40.931µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:31.664756Z","caller":"traceutil/trace.go:171","msg":"trace[878193514] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"419.701443ms","start":"2026-04-01T23:29:31.245005Z","end":"2026-04-01T23:29:31.664707Z","steps":["trace[878193514] 'process raft request' (duration: 419.378394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:31.664965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.081382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-01T23:29:31.665075Z","caller":"traceutil/trace.go:171","msg":"trace[1439648192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"413.213665ms","start":"2026-04-01T23:29:31.25184Z","end":"2026-04-01T23:29:31.665053Z","steps":["trace[1439648192] 'agreement among raft nodes before linearized reading' (duration: 412.913087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:31.665119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:31.251794Z","time spent":"413.317288ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T23:29:31.665606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:31.244979Z","time spent":"419.870948ms","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:29:31.67964Z","caller":"traceutil/trace.go:171","msg":"trace[1322515525] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"359.200849ms","start":"2026-04-01T23:29:31.320365Z","end":"2026-04-01T23:29:31.679566Z","steps":["trace[1322515525] 'process raft request' (duration: 358.713176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:31.679675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.247795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-01T23:29:31.679763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.979667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-01T23:29:31.679844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:31.32033Z","time spent":"359.406315ms","remote":"127.0.0.1:60724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:29:31.679859Z","caller":"traceutil/trace.go:171","msg":"trace[11678874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"196.135062ms","start":"2026-04-01T23:29:31.483697Z","end":"2026-04-01T23:29:31.679832Z","steps":["trace[11678874] 'agreement among raft nodes before linearized reading' (duration: 195.878045ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:31.679888Z","caller":"traceutil/trace.go:171","msg":"trace[95888655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"211.423099ms","start":"2026-04-01T23:29:31.468323Z","end":"2026-04-01T23:29:31.679746Z","steps":["trace[95888655] 'agreement among raft nodes before linearized reading' (duration: 211.208043ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:48.288488Z","caller":"traceutil/trace.go:171","msg":"trace[2022749868] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"116.867645ms","start":"2026-04-01T23:29:48.171593Z","end":"2026-04-01T23:29:48.28846Z","steps":["trace[2022749868] 'process raft request' (duration: 116.719911ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:48.49394Z","caller":"traceutil/trace.go:171","msg":"trace[1264462838] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"141.853208ms","start":"2026-04-01T23:29:48.352052Z","end":"2026-04-01T23:29:48.493905Z","steps":["trace[1264462838] 'process raft request' (duration: 141.656752ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:16.576348Z","caller":"traceutil/trace.go:171","msg":"trace[701403278] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"152.332664ms","start":"2026-04-01T23:30:16.423983Z","end":"2026-04-01T23:30:16.576316Z","steps":["trace[701403278] 'read index received' (duration: 152.068677ms)","trace[701403278] 'applied index is now lower than readState.Index' (duration: 262.657µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:16.576574Z","caller":"traceutil/trace.go:171","msg":"trace[1782947187] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"233.332137ms","start":"2026-04-01T23:30:16.343193Z","end":"2026-04-01T23:30:16.576525Z","steps":["trace[1782947187] 'process raft request' (duration: 232.881775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:16.577041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.035592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-01T23:30:16.57712Z","caller":"traceutil/trace.go:171","msg":"trace[1471684104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1149; }","duration":"153.197137ms","start":"2026-04-01T23:30:16.423904Z","end":"2026-04-01T23:30:16.577101Z","steps":["trace[1471684104] 'agreement among raft nodes before linearized reading' (duration: 152.591611ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:03.175976Z","caller":"traceutil/trace.go:171","msg":"trace[938383343] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1872; }","duration":"119.902841ms","start":"2026-04-01T23:31:03.05605Z","end":"2026-04-01T23:31:03.175953Z","steps":["trace[938383343] 'read index received' (duration: 119.613193ms)","trace[938383343] 'applied index is now lower than readState.Index' (duration: 288.578µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:03.176066Z","caller":"traceutil/trace.go:171","msg":"trace[2031906027] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"310.166588ms","start":"2026-04-01T23:31:02.865878Z","end":"2026-04-01T23:31:03.176044Z","steps":["trace[2031906027] 'process raft request' (duration: 309.87104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:03.176212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:02.865852Z","time spent":"310.26649ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:03.17623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.152049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-01T23:31:03.176309Z","caller":"traceutil/trace.go:171","msg":"trace[281376041] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1804; }","duration":"120.290722ms","start":"2026-04-01T23:31:03.055999Z","end":"2026-04-01T23:31:03.17629Z","steps":["trace[281376041] 'agreement among raft nodes before linearized reading' (duration: 120.077065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:48.086236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019461230507300,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:31:48.349266Z","caller":"traceutil/trace.go:171","msg":"trace[1203460092] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"923.384699ms","start":"2026-04-01T23:31:47.425857Z","end":"2026-04-01T23:31:48.349242Z","steps":["trace[1203460092] 'process raft request' (duration: 923.255565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:48.349401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:47.425835Z","time spent":"923.495501ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:48.538308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.358298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:31:48.538567Z","caller":"traceutil/trace.go:171","msg":"trace[517859683] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2177; }","duration":"952.815708ms","start":"2026-04-01T23:31:47.585733Z","end":"2026-04-01T23:31:48.538549Z","steps":["trace[517859683] 'read index received' (duration: 763.387036ms)","trace[517859683] 'applied index is now lower than readState.Index' (duration: 189.427752ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:48.538643Z","caller":"traceutil/trace.go:171","msg":"trace[287494972] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"433.545419ms","start":"2026-04-01T23:31:48.105072Z","end":"2026-04-01T23:31:48.538617Z","steps":["trace[287494972] 'process raft request' (duration: 433.354014ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:48.538668Z","caller":"traceutil/trace.go:171","msg":"trace[1037837592] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"575.491584ms","start":"2026-04-01T23:31:47.963131Z","end":"2026-04-01T23:31:48.538623Z","steps":["trace[1037837592] 'process raft request' (duration: 437.56953ms)","trace[1037837592] 'compare' (duration: 137.147263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:48.538753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.016483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-01T23:31:48.538773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:48.105046Z","time spent":"433.660713ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:48.538803Z","caller":"traceutil/trace.go:171","msg":"trace[1213877268] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2101; }","duration":"953.138856ms","start":"2026-04-01T23:31:47.585648Z","end":"2026-04-01T23:31:48.538787Z","steps":["trace[1213877268] 'agreement among raft nodes before linearized reading' (duration: 952.982392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:48.538811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:47.963107Z","time spent":"575.613656ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:48.538843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:47.585626Z","time spent":"953.208087ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-01T23:31:48.573566Z","caller":"traceutil/trace.go:171","msg":"trace[1702488788] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"125.189098ms","start":"2026-04-01T23:31:48.448359Z","end":"2026-04-01T23:31:48.573548Z","steps":["trace[1702488788] 'process raft request' (duration: 125.082546ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:48.611569Z","caller":"traceutil/trace.go:171","msg":"trace[1514285990] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"101.200758ms","start":"2026-04-01T23:31:48.510352Z","end":"2026-04-01T23:31:48.611553Z","steps":["trace[1514285990] 'process raft request' (duration: 101.098315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:48.611822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.201515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-01T23:31:48.611831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.281909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:31:48.611927Z","caller":"traceutil/trace.go:171","msg":"trace[898350738] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2103; }","duration":"315.482535ms","start":"2026-04-01T23:31:48.296422Z","end":"2026-04-01T23:31:48.611904Z","steps":["trace[898350738] 'agreement among raft nodes before linearized reading' (duration: 315.244518ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:48.611907Z","caller":"traceutil/trace.go:171","msg":"trace[1316004919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2103; }","duration":"821.386581ms","start":"2026-04-01T23:31:47.790501Z","end":"2026-04-01T23:31:48.611888Z","steps":["trace[1316004919] 'agreement among raft nodes before linearized reading' (duration: 821.100352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:48.611989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:47.790478Z","time spent":"821.497533ms","remote":"127.0.0.1:60896","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-04-01T23:31:48.611987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:48.2964Z","time spent":"315.566428ms","remote":"127.0.0.1:60938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:31:49.047831Z","caller":"traceutil/trace.go:171","msg":"trace[2093914553] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2183; }","duration":"354.35256ms","start":"2026-04-01T23:31:48.693453Z","end":"2026-04-01T23:31:49.047806Z","steps":["trace[2093914553] 'read index received' (duration: 318.447645ms)","trace[2093914553] 'applied index is now lower than readState.Index' (duration: 35.904025ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:49.047962Z","caller":"traceutil/trace.go:171","msg":"trace[832689502] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"430.59246ms","start":"2026-04-01T23:31:48.617343Z","end":"2026-04-01T23:31:49.047936Z","steps":["trace[832689502] 'process raft request' (duration: 430.279702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:49.048126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:48.617336Z","time spent":"430.709064ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:49.0482Z","caller":"traceutil/trace.go:171","msg":"trace[1611632630] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"430.270251ms","start":"2026-04-01T23:31:48.617854Z","end":"2026-04-01T23:31:49.048124Z","steps":["trace[1611632630] 'process raft request' (duration: 429.84701ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:49.048243Z","caller":"traceutil/trace.go:171","msg":"trace[1823895971] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"430.96514ms","start":"2026-04-01T23:31:48.617196Z","end":"2026-04-01T23:31:49.048161Z","steps":["trace[1823895971] 'process raft request' (duration: 394.679965ms)","trace[1823895971] 'compare' (duration: 35.569646ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:49.048376Z","caller":"traceutil/trace.go:171","msg":"trace[1219249600] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"429.47367ms","start":"2026-04-01T23:31:48.618714Z","end":"2026-04-01T23:31:49.048188Z","steps":["trace[1219249600] 'process raft request' (duration: 429.055219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:49.048447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:48.617847Z","time spent":"430.417505ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:49.04853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:48.617186Z","time spent":"431.205837ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:49.047953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.480183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:31:49.048605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:48.618707Z","time spent":"429.786808ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:49.048685Z","caller":"traceutil/trace.go:171","msg":"trace[371204500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"355.243193ms","start":"2026-04-01T23:31:48.693413Z","end":"2026-04-01T23:31:49.048656Z","steps":["trace[371204500] 'agreement among raft nodes before linearized reading' (duration: 354.495553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:49.048767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:48.693396Z","time spent":"355.341747ms","remote":"127.0.0.1:60622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T23:31:50.963941Z","caller":"traceutil/trace.go:171","msg":"trace[199306534] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"420.242419ms","start":"2026-04-01T23:31:50.543671Z","end":"2026-04-01T23:31:50.963914Z","steps":["trace[199306534] 'process raft request' (duration: 395.170579ms)","trace[199306534] 'compare' (duration: 24.946067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:50.964079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.543646Z","time spent":"420.361012ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:50.998984Z","caller":"traceutil/trace.go:171","msg":"trace[834692980] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2188; }","duration":"444.049135ms","start":"2026-04-01T23:31:50.554906Z","end":"2026-04-01T23:31:50.998955Z","steps":["trace[834692980] 'read index received' (duration: 383.951463ms)","trace[834692980] 'applied index is now lower than readState.Index' (duration: 60.096442ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:50.99908Z","caller":"traceutil/trace.go:171","msg":"trace[408124400] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"381.494308ms","start":"2026-04-01T23:31:50.617562Z","end":"2026-04-01T23:31:50.999057Z","steps":["trace[408124400] 'process raft request' (duration: 381.320613ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:50.999045Z","caller":"traceutil/trace.go:171","msg":"trace[795719071] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"454.419048ms","start":"2026-04-01T23:31:50.544586Z","end":"2026-04-01T23:31:50.999005Z","steps":["trace[795719071] 'process raft request' (duration: 454.13273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:50.999271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.699501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-01T23:31:50.999302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.349978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:31:50.999311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.544577Z","time spent":"454.568181ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:50.999363Z","caller":"traceutil/trace.go:171","msg":"trace[1161222157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2112; }","duration":"364.836125ms","start":"2026-04-01T23:31:50.63451Z","end":"2026-04-01T23:31:50.999346Z","steps":["trace[1161222157] 'agreement among raft nodes before linearized reading' (duration: 364.609309ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:50.999381Z","caller":"traceutil/trace.go:171","msg":"trace[1032912086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2112; }","duration":"306.466771ms","start":"2026-04-01T23:31:50.692892Z","end":"2026-04-01T23:31:50.999359Z","steps":["trace[1032912086] 'agreement among raft nodes before linearized reading' (duration: 306.291066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:50.999412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.63449Z","time spent":"364.909076ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:31:50.99942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.617536Z","time spent":"381.824576ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:50.999446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.692871Z","time spent":"306.562023ms","remote":"127.0.0.1:60606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:31:50.99934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.415276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-01T23:31:50.99954Z","caller":"traceutil/trace.go:171","msg":"trace[2118077054] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2112; }","duration":"444.652051ms","start":"2026-04-01T23:31:50.554866Z","end":"2026-04-01T23:31:50.999518Z","steps":["trace[2118077054] 'agreement among raft nodes before linearized reading' (duration: 444.2294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:50.999623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.55485Z","time spent":"444.759935ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-01T23:31:51.24016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.926849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:31:51.240273Z","caller":"traceutil/trace.go:171","msg":"trace[148943433] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"178.526137ms","start":"2026-04-01T23:31:51.061735Z","end":"2026-04-01T23:31:51.240261Z","steps":["trace[148943433] 'process raft request' (duration: 178.491786ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:51.240347Z","caller":"traceutil/trace.go:171","msg":"trace[770015271] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"187.316236ms","start":"2026-04-01T23:31:51.053007Z","end":"2026-04-01T23:31:51.240323Z","steps":["trace[770015271] 'read index received' (duration: 54.278394ms)","trace[770015271] 'applied index is now lower than readState.Index' (duration: 133.035762ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:51.24039Z","caller":"traceutil/trace.go:171","msg":"trace[1403200903] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"236.717437ms","start":"2026-04-01T23:31:51.003648Z","end":"2026-04-01T23:31:51.240366Z","steps":["trace[1403200903] 'process raft request' (duration: 103.531571ms)","trace[1403200903] 'compare' (duration: 132.795946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:51.240459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.453919ms","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-04-01T23:31:51.24048Z","caller":"traceutil/trace.go:171","msg":"trace[1982565165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2114; }","duration":"187.504391ms","start":"2026-04-01T23:31:51.05297Z","end":"2026-04-01T23:31:51.240474Z","steps":["trace[1982565165] 'agreement among raft nodes before linearized reading' (duration: 187.428959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.240667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.648706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a25f93b16c75f6\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-04-01T23:31:51.240688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.974656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-01T23:31:51.240691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.218336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-01T23:31:51.240708Z","caller":"traceutil/trace.go:171","msg":"trace[1381970744] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a25f93b16c75f6; range_end:; response_count:1; response_revision:2114; }","duration":"162.725238ms","start":"2026-04-01T23:31:51.077972Z","end":"2026-04-01T23:31:51.240697Z","steps":["trace[1381970744] 'agreement among raft nodes before linearized reading' (duration: 162.621965ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:51.240731Z","caller":"traceutil/trace.go:171","msg":"trace[495123997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2114; }","duration":"187.025288ms","start":"2026-04-01T23:31:51.053682Z","end":"2026-04-01T23:31:51.240707Z","steps":["trace[495123997] 'agreement among raft nodes before linearized reading' (duration: 186.952986ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:51.240773Z","caller":"traceutil/trace.go:171","msg":"trace[649931257] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2114; }","duration":"186.291008ms","start":"2026-04-01T23:31:51.054443Z","end":"2026-04-01T23:31:51.240734Z","steps":["trace[649931257] 'agreement among raft nodes before linearized reading' (duration: 186.163065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.240688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.143521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-01T23:31:51.240893Z","caller":"traceutil/trace.go:171","msg":"trace[1331465351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2114; }","duration":"187.395798ms","start":"2026-04-01T23:31:51.053489Z","end":"2026-04-01T23:31:51.240884Z","steps":["trace[1331465351] 'agreement among raft nodes before linearized reading' (duration: 187.071859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.922394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.191136ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:31:51.922527Z","caller":"traceutil/trace.go:171","msg":"trace[627296960] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2114; }","duration":"182.334919ms","start":"2026-04-01T23:31:51.740171Z","end":"2026-04-01T23:31:51.922506Z","steps":["trace[627296960] 'range keys from in-memory index tree' (duration: 182.173185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.922645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.07815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:31:51.922906Z","caller":"traceutil/trace.go:171","msg":"trace[1269820535] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"680.444392ms","start":"2026-04-01T23:31:51.242437Z","end":"2026-04-01T23:31:51.922881Z","steps":["trace[1269820535] 'process raft request' (duration: 157.011632ms)","trace[1269820535] 'compare' (duration: 522.675289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:51.923034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.242419Z","time spent":"680.543515ms","remote":"127.0.0.1:60696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:51.923144Z","caller":"traceutil/trace.go:171","msg":"trace[1356248423] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"678.8425ms","start":"2026-04-01T23:31:51.244282Z","end":"2026-04-01T23:31:51.923124Z","steps":["trace[1356248423] 'process raft request' (duration: 678.697895ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:51.923146Z","caller":"traceutil/trace.go:171","msg":"trace[949029896] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"679.074077ms","start":"2026-04-01T23:31:51.244041Z","end":"2026-04-01T23:31:51.923115Z","steps":["trace[949029896] 'process raft request' (duration: 678.707256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.923229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.244255Z","time spent":"678.928833ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:51.923235Z","caller":"traceutil/trace.go:171","msg":"trace[1025397267] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2193; }","duration":"580.649354ms","start":"2026-04-01T23:31:51.342567Z","end":"2026-04-01T23:31:51.923217Z","steps":["trace[1025397267] 'read index received' (duration: 56.964437ms)","trace[1025397267] 'applied index is now lower than readState.Index' (duration: 523.683967ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:51.923243Z","caller":"traceutil/trace.go:171","msg":"trace[1525979374] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"678.764468ms","start":"2026-04-01T23:31:51.244427Z","end":"2026-04-01T23:31:51.923192Z","steps":["trace[1525979374] 'process raft request' (duration: 678.679816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.923295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.244003Z","time spent":"679.21124ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:51.923285Z","caller":"traceutil/trace.go:171","msg":"trace[2044037952] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"678.936123ms","start":"2026-04-01T23:31:51.244302Z","end":"2026-04-01T23:31:51.923238Z","steps":["trace[2044037952] 'process raft request' (duration: 678.726267ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:51.923301Z","caller":"traceutil/trace.go:171","msg":"trace[507423569] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"479.20713ms","start":"2026-04-01T23:31:51.444068Z","end":"2026-04-01T23:31:51.923275Z","steps":["trace[507423569] 'process raft request' (duration: 479.106467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.923344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.244417Z","time spent":"678.880052ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:51.923412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.84902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12379"} {"level":"info","ts":"2026-04-01T23:31:51.923442Z","caller":"traceutil/trace.go:171","msg":"trace[217707658] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2120; }","duration":"580.906341ms","start":"2026-04-01T23:31:51.342525Z","end":"2026-04-01T23:31:51.923432Z","steps":["trace[217707658] 'agreement among raft nodes before linearized reading' (duration: 580.782168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.923441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.444041Z","time spent":"479.329323ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:51.923469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.342503Z","time spent":"580.960633ms","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12401,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-01T23:31:51.923465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.244282Z","time spent":"679.087737ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:52.855682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.595469ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-01T23:31:52.855775Z","caller":"traceutil/trace.go:171","msg":"trace[973078946] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"932.478375ms","start":"2026-04-01T23:31:51.923281Z","end":"2026-04-01T23:31:52.855759Z","steps":["trace[973078946] 'read index received' (duration: 164.646809ms)","trace[973078946] 'applied index is now lower than readState.Index' (duration: 767.830026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:52.855873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161519392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:31:52.855901Z","caller":"traceutil/trace.go:171","msg":"trace[1271936759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2120; }","duration":"1.161592925s","start":"2026-04-01T23:31:51.6943Z","end":"2026-04-01T23:31:52.855893Z","steps":["trace[1271936759] 'agreement among raft nodes before linearized reading' (duration: 1.161532613s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:52.855926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.694279Z","time spent":"1.161640317s","remote":"127.0.0.1:60622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:31:52.856044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.282171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:31:52.8561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.689084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-01T23:31:52.85615Z","caller":"traceutil/trace.go:171","msg":"trace[34955602] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2120; }","duration":"921.435285ms","start":"2026-04-01T23:31:51.934691Z","end":"2026-04-01T23:31:52.856126Z","steps":["trace[34955602] 'agreement among raft nodes before linearized reading' (duration: 921.22396ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:52.856201Z","caller":"traceutil/trace.go:171","msg":"trace[286183625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2120; }","duration":"398.827338ms","start":"2026-04-01T23:31:52.457355Z","end":"2026-04-01T23:31:52.856182Z","steps":["trace[286183625] 'agreement among raft nodes before linearized reading' (duration: 398.564061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:52.856219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:51.934675Z","time spent":"921.528358ms","remote":"127.0.0.1:32862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-01T23:31:52.856204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.216367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-01T23:31:52.856243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:52.457328Z","time spent":"398.90649ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T23:31:52.85628Z","caller":"traceutil/trace.go:171","msg":"trace[1568264785] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2120; }","duration":"845.35755ms","start":"2026-04-01T23:31:52.010904Z","end":"2026-04-01T23:31:52.856262Z","steps":["trace[1568264785] 'agreement among raft nodes before linearized reading' (duration: 845.114434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:52.85633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:52.010882Z","time spent":"845.436153ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-01T23:31:53.073749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.432612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:31:53.074073Z","caller":"traceutil/trace.go:171","msg":"trace[1481395488] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"131.099608ms","start":"2026-04-01T23:31:52.942946Z","end":"2026-04-01T23:31:53.074046Z","steps":["trace[1481395488] 'process raft request' (duration: 25.217144ms)","trace[1481395488] 'compare' (duration: 105.319969ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:53.074118Z","caller":"traceutil/trace.go:171","msg":"trace[1469781963] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"104.040134ms","start":"2026-04-01T23:31:52.970056Z","end":"2026-04-01T23:31:53.074096Z","steps":["trace[1469781963] 'process raft request' (duration: 103.952872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:59.232397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.682805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-01T23:31:59.232508Z","caller":"traceutil/trace.go:171","msg":"trace[2080873382] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2152; }","duration":"331.842779ms","start":"2026-04-01T23:31:58.900634Z","end":"2026-04-01T23:31:59.232477Z","steps":["trace[2080873382] 'range keys from in-memory index tree' (duration: 331.47623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:59.232569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:58.900612Z","time spent":"331.940902ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-01T23:31:59.232783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.443903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:31:59.232962Z","caller":"traceutil/trace.go:171","msg":"trace[901947961] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"295.935665ms","start":"2026-04-01T23:31:58.936999Z","end":"2026-04-01T23:31:59.232935Z","steps":["trace[901947961] 'process raft request' (duration: 106.224564ms)","trace[901947961] 'compare' (duration: 188.963851ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:59.233043Z","caller":"traceutil/trace.go:171","msg":"trace[1274814350] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"131.721846ms","start":"2026-04-01T23:31:59.101299Z","end":"2026-04-01T23:31:59.233021Z","steps":["trace[1274814350] 'process raft request' (duration: 131.610793ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:59.233039Z","caller":"traceutil/trace.go:171","msg":"trace[894428739] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2233; }","duration":"269.914778ms","start":"2026-04-01T23:31:58.96309Z","end":"2026-04-01T23:31:59.233004Z","steps":["trace[894428739] 'read index received' (duration: 80.024812ms)","trace[894428739] 'applied index is now lower than readState.Index' (duration: 189.886675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:59.233273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.148923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-01T23:31:59.233309Z","caller":"traceutil/trace.go:171","msg":"trace[425377933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2154; }","duration":"270.254276ms","start":"2026-04-01T23:31:58.963044Z","end":"2026-04-01T23:31:59.233298Z","steps":["trace[425377933] 'agreement among raft nodes before linearized reading' (duration: 270.082872ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:59.354459Z","caller":"traceutil/trace.go:171","msg":"trace[738365362] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"116.503583ms","start":"2026-04-01T23:31:59.237931Z","end":"2026-04-01T23:31:59.354434Z","steps":["trace[738365362] 'process raft request' (duration: 116.448031ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:59.354446Z","caller":"traceutil/trace.go:171","msg":"trace[290006530] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"252.236828ms","start":"2026-04-01T23:31:59.102173Z","end":"2026-04-01T23:31:59.35441Z","steps":["trace[290006530] 'process raft request' (duration: 247.87153ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:59.354498Z","caller":"traceutil/trace.go:171","msg":"trace[758508717] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"252.233627ms","start":"2026-04-01T23:31:59.102233Z","end":"2026-04-01T23:31:59.354466Z","steps":["trace[758508717] 'process raft request' (duration: 252.100864ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:59.354633Z","caller":"traceutil/trace.go:171","msg":"trace[1179083815] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2235; }","duration":"121.501828ms","start":"2026-04-01T23:31:59.233095Z","end":"2026-04-01T23:31:59.354597Z","steps":["trace[1179083815] 'read index received' (duration: 116.961125ms)","trace[1179083815] 'applied index is now lower than readState.Index' (duration: 4.534763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:59.355586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.651895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-01T23:31:59.355691Z","caller":"traceutil/trace.go:171","msg":"trace[548844269] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2157; }","duration":"387.797979ms","start":"2026-04-01T23:31:58.96787Z","end":"2026-04-01T23:31:59.355668Z","steps":["trace[548844269] 'agreement among raft nodes before linearized reading' (duration: 386.851812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:59.355752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:58.967859Z","time spent":"387.877461ms","remote":"127.0.0.1:60696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":251,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:32:02.118392Z","caller":"traceutil/trace.go:171","msg":"trace[1654605165] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"138.14581ms","start":"2026-04-01T23:32:01.980209Z","end":"2026-04-01T23:32:02.118355Z","steps":["trace[1654605165] 'process raft request' (duration: 108.845094ms)","trace[1654605165] 'compare' (duration: 29.001808ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:02.118583Z","caller":"traceutil/trace.go:171","msg":"trace[668746045] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2253; }","duration":"130.654818ms","start":"2026-04-01T23:32:01.98791Z","end":"2026-04-01T23:32:02.118565Z","steps":["trace[668746045] 'read index received' (duration: 101.2689ms)","trace[668746045] 'applied index is now lower than readState.Index' (duration: 29.384158ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:02.118724Z","caller":"traceutil/trace.go:171","msg":"trace[869316647] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"127.488051ms","start":"2026-04-01T23:32:01.991204Z","end":"2026-04-01T23:32:02.118692Z","steps":["trace[869316647] 'process raft request' (duration: 127.299876ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:02.118828Z","caller":"traceutil/trace.go:171","msg":"trace[1327479336] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"136.732172ms","start":"2026-04-01T23:32:01.98204Z","end":"2026-04-01T23:32:02.118772Z","steps":["trace[1327479336] 'process raft request' (duration: 136.211548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:02.118997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.163208ms","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-04-01T23:32:02.119046Z","caller":"traceutil/trace.go:171","msg":"trace[812702638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2173; }","duration":"139.280401ms","start":"2026-04-01T23:32:01.979753Z","end":"2026-04-01T23:32:02.119034Z","steps":["trace[812702638] 'agreement among raft nodes before linearized reading' (duration: 138.878911ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:20.876948Z","caller":"traceutil/trace.go:171","msg":"trace[1297122905] transaction","detail":"{read_only:false; response_revision:3932; number_of_response:1; }","duration":"143.032351ms","start":"2026-04-01T23:36:20.733882Z","end":"2026-04-01T23:36:20.876914Z","steps":["trace[1297122905] 'process raft request' (duration: 142.842966ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:37:31.806257Z","caller":"traceutil/trace.go:171","msg":"trace[203975783] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"101.207293ms","start":"2026-04-01T23:37:31.70502Z","end":"2026-04-01T23:37:31.806228Z","steps":["trace[203975783] 'process raft request' (duration: 21.06225ms)","trace[203975783] 'compare' (duration: 79.992459ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:37:39.931103Z","caller":"traceutil/trace.go:171","msg":"trace[796123409] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"157.017337ms","start":"2026-04-01T23:37:39.774038Z","end":"2026-04-01T23:37:39.931056Z","steps":["trace[796123409] 'process raft request' (duration: 156.656096ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:37:39.931799Z","caller":"traceutil/trace.go:171","msg":"trace[1684991717] linearizableReadLoop","detail":"{readStateIndex:5014; appliedIndex:5012; }","duration":"156.739929ms","start":"2026-04-01T23:37:39.775024Z","end":"2026-04-01T23:37:39.931764Z","steps":["trace[1684991717] 'read index received' (duration: 19.721292ms)","trace[1684991717] 'applied index is now lower than readState.Index' (duration: 137.017347ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:37:39.931974Z","caller":"traceutil/trace.go:171","msg":"trace[1758982492] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"150.527019ms","start":"2026-04-01T23:37:39.781434Z","end":"2026-04-01T23:37:39.931961Z","steps":["trace[1758982492] 'process raft request' (duration: 149.440579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:37:39.932128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.639769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:37:39.932183Z","caller":"traceutil/trace.go:171","msg":"trace[104500832] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:4831; }","duration":"147.736752ms","start":"2026-04-01T23:37:39.784428Z","end":"2026-04-01T23:37:39.932164Z","steps":["trace[104500832] 'agreement among raft nodes before linearized reading' (duration: 147.635389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:37:39.932664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.625703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-01T23:37:39.932733Z","caller":"traceutil/trace.go:171","msg":"trace[2013147498] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4831; }","duration":"157.716836ms","start":"2026-04-01T23:37:39.775Z","end":"2026-04-01T23:37:39.932717Z","steps":["trace[2013147498] 'agreement among raft nodes before linearized reading' (duration: 157.50515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:37:39.932515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.542351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-01T23:37:39.93343Z","caller":"traceutil/trace.go:171","msg":"trace[711524553] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4831; }","duration":"142.476576ms","start":"2026-04-01T23:37:39.790936Z","end":"2026-04-01T23:37:39.933412Z","steps":["trace[711524553] 'agreement among raft nodes before linearized reading' (duration: 141.424968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:37:41.336799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.770154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-01T23:37:41.33689Z","caller":"traceutil/trace.go:171","msg":"trace[954291078] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4880; }","duration":"178.875207ms","start":"2026-04-01T23:37:41.157995Z","end":"2026-04-01T23:37:41.33687Z","steps":["trace[954291078] 'range keys from in-memory index tree' (duration: 178.570339ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:37:41.45895Z","caller":"traceutil/trace.go:171","msg":"trace[1179361296] linearizableReadLoop","detail":"{readStateIndex:5066; appliedIndex:5064; }","duration":"114.047546ms","start":"2026-04-01T23:37:41.344877Z","end":"2026-04-01T23:37:41.458924Z","steps":["trace[1179361296] 'read index received' (duration: 38.690294ms)","trace[1179361296] 'applied index is now lower than readState.Index' (duration: 75.356012ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:37:41.459076Z","caller":"traceutil/trace.go:171","msg":"trace[769489856] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"116.847943ms","start":"2026-04-01T23:37:41.342189Z","end":"2026-04-01T23:37:41.459037Z","steps":["trace[769489856] 'process raft request' (duration: 95.153906ms)","trace[769489856] 'compare' (duration: 21.461551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:37:41.459132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.235241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-01T23:37:41.459168Z","caller":"traceutil/trace.go:171","msg":"trace[1583810477] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4882; }","duration":"114.313083ms","start":"2026-04-01T23:37:41.344843Z","end":"2026-04-01T23:37:41.459156Z","steps":["trace[1583810477] 'agreement among raft nodes before linearized reading' (duration: 114.17573ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:37:45.13158Z","caller":"traceutil/trace.go:171","msg":"trace[1906354596] linearizableReadLoop","detail":"{readStateIndex:5170; appliedIndex:5169; }","duration":"108.645648ms","start":"2026-04-01T23:37:45.022914Z","end":"2026-04-01T23:37:45.13156Z","steps":["trace[1906354596] 'read index received' (duration: 108.562726ms)","trace[1906354596] 'applied index is now lower than readState.Index' (duration: 82.192µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:37:45.131665Z","caller":"traceutil/trace.go:171","msg":"trace[1426729004] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"121.906441ms","start":"2026-04-01T23:37:45.009722Z","end":"2026-04-01T23:37:45.131629Z","steps":["trace[1426729004] 'process raft request' (duration: 121.671345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:37:45.131789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.853514ms","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-04-01T23:37:45.13182Z","caller":"traceutil/trace.go:171","msg":"trace[674225294] 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:4982; }","duration":"108.907895ms","start":"2026-04-01T23:37:45.022902Z","end":"2026-04-01T23:37:45.13181Z","steps":["trace[674225294] 'agreement among raft nodes before linearized reading' (duration: 108.767351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:37:45.158271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.443433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-04-01T23:37:45.158326Z","caller":"traceutil/trace.go:171","msg":"trace[805573462] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4983; }","duration":"127.547866ms","start":"2026-04-01T23:37:45.030765Z","end":"2026-04-01T23:37:45.158313Z","steps":["trace[805573462] 'agreement among raft nodes before linearized reading' (duration: 127.407182ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:37:45.294422Z","caller":"traceutil/trace.go:171","msg":"trace[1769258610] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5172; }","duration":"107.713691ms","start":"2026-04-01T23:37:45.18669Z","end":"2026-04-01T23:37:45.294404Z","steps":["trace[1769258610] 'read index received' (duration: 107.549116ms)","trace[1769258610] 'applied index is now lower than readState.Index' (duration: 163.925µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:37:45.29484Z","caller":"traceutil/trace.go:171","msg":"trace[1492782356] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"110.611861ms","start":"2026-04-01T23:37:45.184199Z","end":"2026-04-01T23:37:45.294811Z","steps":["trace[1492782356] 'process raft request' (duration: 110.095667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:37:45.294903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.198825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-01T23:37:45.294935Z","caller":"traceutil/trace.go:171","msg":"trace[1057945007] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4985; }","duration":"108.267776ms","start":"2026-04-01T23:37:45.186658Z","end":"2026-04-01T23:37:45.294926Z","steps":["trace[1057945007] 'agreement among raft nodes before linearized reading' (duration: 108.153023ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:37:49.564726Z","caller":"traceutil/trace.go:171","msg":"trace[631948102] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"145.193528ms","start":"2026-04-01T23:37:49.419506Z","end":"2026-04-01T23:37:49.5647Z","steps":["trace[631948102] 'process raft request' (duration: 145.008183ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:37:49.669087Z","caller":"traceutil/trace.go:171","msg":"trace[608420746] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"100.637358ms","start":"2026-04-01T23:37:49.568405Z","end":"2026-04-01T23:37:49.669043Z","steps":["trace[608420746] 'process raft request' (duration: 70.755109ms)","trace[608420746] 'compare' (duration: 29.631852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:37:51.978521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.352067ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-01T23:37:51.978831Z","caller":"traceutil/trace.go:171","msg":"trace[943967513] linearizableReadLoop","detail":"{readStateIndex:5383; appliedIndex:5382; }","duration":"168.778625ms","start":"2026-04-01T23:37:51.809991Z","end":"2026-04-01T23:37:51.978769Z","steps":["trace[943967513] 'read index received' (duration: 32.110639ms)","trace[943967513] 'applied index is now lower than readState.Index' (duration: 136.664406ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:37:51.978946Z","caller":"traceutil/trace.go:171","msg":"trace[293522125] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"108.870564ms","start":"2026-04-01T23:37:51.870027Z","end":"2026-04-01T23:37:51.978898Z","steps":["trace[293522125] 'process raft request' (duration: 108.618177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:37:52.015535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.461834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7b844f5bd9-wsfj6\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-04-01T23:37:52.015653Z","caller":"traceutil/trace.go:171","msg":"trace[1970217966] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7b844f5bd9-wsfj6; range_end:; response_count:1; response_revision:5190; }","duration":"146.612077ms","start":"2026-04-01T23:37:51.869008Z","end":"2026-04-01T23:37:52.01562Z","steps":["trace[1970217966] 'agreement among raft nodes before linearized reading' (duration: 110.007884ms)","trace[1970217966] 'range keys from in-memory index tree' (duration: 36.380468ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:37:52.015534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.511862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:37:52.015776Z","caller":"traceutil/trace.go:171","msg":"trace[95795424] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:5190; }","duration":"205.80539ms","start":"2026-04-01T23:37:51.809949Z","end":"2026-04-01T23:37:52.015755Z","steps":["trace[95795424] 'agreement among raft nodes before linearized reading' (duration: 168.96869ms)","trace[95795424] 'count revisions from in-memory index tree' (duration: 36.545551ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:37:52.169207Z","caller":"traceutil/trace.go:171","msg":"trace[818755863] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"100.738671ms","start":"2026-04-01T23:37:52.06844Z","end":"2026-04-01T23:37:52.169178Z","steps":["trace[818755863] 'process raft request' (duration: 99.421605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:38:07.598317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.17183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:38:07.598385Z","caller":"traceutil/trace.go:171","msg":"trace[321701624] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5519; }","duration":"137.283013ms","start":"2026-04-01T23:38:07.461088Z","end":"2026-04-01T23:38:07.598371Z","steps":["trace[321701624] 'count revisions from in-memory index tree' (duration: 137.071908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:38:08.134232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.626837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-04-01T23:38:08.134351Z","caller":"traceutil/trace.go:171","msg":"trace[125404742] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5521; }","duration":"139.790731ms","start":"2026-04-01T23:38:07.994537Z","end":"2026-04-01T23:38:08.134328Z","steps":["trace[125404742] 'range keys from in-memory index tree' (duration: 139.427651ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:08.242675Z","caller":"traceutil/trace.go:171","msg":"trace[1519042792] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"104.111974ms","start":"2026-04-01T23:38:08.138537Z","end":"2026-04-01T23:38:08.242649Z","steps":["trace[1519042792] 'process raft request' (duration: 103.96537ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:08.243219Z","caller":"traceutil/trace.go:171","msg":"trace[1530895457] linearizableReadLoop","detail":"{readStateIndex:5726; appliedIndex:5726; }","duration":"103.060535ms","start":"2026-04-01T23:38:08.140123Z","end":"2026-04-01T23:38:08.243183Z","steps":["trace[1530895457] 'read index received' (duration: 103.050385ms)","trace[1530895457] 'applied index is now lower than readState.Index' (duration: 8.3µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:38:08.243564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.419575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18287"} {"level":"info","ts":"2026-04-01T23:38:08.243617Z","caller":"traceutil/trace.go:171","msg":"trace[1762651555] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5522; }","duration":"103.508657ms","start":"2026-04-01T23:38:08.140096Z","end":"2026-04-01T23:38:08.243605Z","steps":["trace[1762651555] 'agreement among raft nodes before linearized reading' (duration: 103.293092ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:08.409128Z","caller":"traceutil/trace.go:171","msg":"trace[66192437] linearizableReadLoop","detail":"{readStateIndex:5727; appliedIndex:5726; }","duration":"165.810424ms","start":"2026-04-01T23:38:08.243299Z","end":"2026-04-01T23:38:08.409109Z","steps":["trace[66192437] 'read index received' (duration: 165.598918ms)","trace[66192437] 'applied index is now lower than readState.Index' (duration: 210.736µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:38:08.409244Z","caller":"traceutil/trace.go:171","msg":"trace[913053230] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"266.471023ms","start":"2026-04-01T23:38:08.142677Z","end":"2026-04-01T23:38:08.409148Z","steps":["trace[913053230] 'process raft request' (duration: 266.275598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:38:08.409306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.07416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-01T23:38:08.409336Z","caller":"traceutil/trace.go:171","msg":"trace[559537558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5523; }","duration":"263.137252ms","start":"2026-04-01T23:38:08.146191Z","end":"2026-04-01T23:38:08.409328Z","steps":["trace[559537558] 'agreement among raft nodes before linearized reading' (duration: 263.019849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:38:08.409616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.98779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-01T23:38:08.409712Z","caller":"traceutil/trace.go:171","msg":"trace[1877114407] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5523; }","duration":"158.125624ms","start":"2026-04-01T23:38:08.251576Z","end":"2026-04-01T23:38:08.409702Z","steps":["trace[1877114407] 'agreement among raft nodes before linearized reading' (duration: 157.904907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:38:08.40973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.842355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:38:08.40976Z","caller":"traceutil/trace.go:171","msg":"trace[1135870815] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5523; }","duration":"112.011069ms","start":"2026-04-01T23:38:08.297742Z","end":"2026-04-01T23:38:08.409753Z","steps":["trace[1135870815] 'agreement among raft nodes before linearized reading' (duration: 111.958508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:38:08.40974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.418129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:38:08.409643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.958248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-01T23:38:08.409837Z","caller":"traceutil/trace.go:171","msg":"trace[1444847856] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5523; }","duration":"109.549243ms","start":"2026-04-01T23:38:08.300265Z","end":"2026-04-01T23:38:08.409814Z","steps":["trace[1444847856] 'agreement among raft nodes before linearized reading' (duration: 109.365397ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:08.409881Z","caller":"traceutil/trace.go:171","msg":"trace[1004844689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5523; }","duration":"266.201985ms","start":"2026-04-01T23:38:08.143653Z","end":"2026-04-01T23:38:08.409855Z","steps":["trace[1004844689] 'agreement among raft nodes before linearized reading' (duration: 265.946917ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:16.276944Z","caller":"traceutil/trace.go:171","msg":"trace[147582303] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"101.264815ms","start":"2026-04-01T23:38:16.175649Z","end":"2026-04-01T23:38:16.276914Z","steps":["trace[147582303] 'process raft request' (duration: 100.368551ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:20.126228Z","caller":"traceutil/trace.go:171","msg":"trace[231384123] transaction","detail":"{read_only:false; response_revision:5683; number_of_response:1; }","duration":"144.10661ms","start":"2026-04-01T23:38:19.982093Z","end":"2026-04-01T23:38:20.1262Z","steps":["trace[231384123] 'process raft request' (duration: 143.937076ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:20.369027Z","caller":"traceutil/trace.go:171","msg":"trace[28623663] transaction","detail":"{read_only:false; response_revision:5686; number_of_response:1; }","duration":"117.317605ms","start":"2026-04-01T23:38:20.251684Z","end":"2026-04-01T23:38:20.369001Z","steps":["trace[28623663] 'process raft request' (duration: 64.784745ms)","trace[28623663] 'compare' (duration: 52.334045ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:38:20.369292Z","caller":"traceutil/trace.go:171","msg":"trace[478532097] transaction","detail":"{read_only:false; response_revision:5687; number_of_response:1; }","duration":"117.288824ms","start":"2026-04-01T23:38:20.251979Z","end":"2026-04-01T23:38:20.369268Z","steps":["trace[478532097] 'process raft request' (duration: 116.961156ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:20.369346Z","caller":"traceutil/trace.go:171","msg":"trace[717805969] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"117.202372ms","start":"2026-04-01T23:38:20.252128Z","end":"2026-04-01T23:38:20.36933Z","steps":["trace[717805969] 'process raft request' (duration: 117.024417ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:20.369497Z","caller":"traceutil/trace.go:171","msg":"trace[657216948] linearizableReadLoop","detail":"{readStateIndex:5893; appliedIndex:5890; }","duration":"117.48694ms","start":"2026-04-01T23:38:20.251995Z","end":"2026-04-01T23:38:20.369482Z","steps":["trace[657216948] 'read index received' (duration: 36.671395ms)","trace[657216948] 'applied index is now lower than readState.Index' (duration: 80.814555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:38:20.369785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.773908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-103987ca-384e-4ec7-ab61-5a52a4ffe92e\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-01T23:38:20.369861Z","caller":"traceutil/trace.go:171","msg":"trace[1062506386] range","detail":"{range_begin:/registry/persistentvolumes/pvc-103987ca-384e-4ec7-ab61-5a52a4ffe92e; range_end:; response_count:1; response_revision:5689; }","duration":"117.913222ms","start":"2026-04-01T23:38:20.251936Z","end":"2026-04-01T23:38:20.369849Z","steps":["trace[1062506386] 'agreement among raft nodes before linearized reading' (duration: 117.640335ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:20.724072Z","caller":"traceutil/trace.go:171","msg":"trace[1272932306] transaction","detail":"{read_only:false; response_revision:5692; number_of_response:1; }","duration":"176.694652ms","start":"2026-04-01T23:38:20.547336Z","end":"2026-04-01T23:38:20.72403Z","steps":["trace[1272932306] 'process raft request' (duration: 152.491739ms)","trace[1272932306] 'compare' (duration: 23.993308ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:38:20.724255Z","caller":"traceutil/trace.go:171","msg":"trace[936510307] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"176.484887ms","start":"2026-04-01T23:38:20.547746Z","end":"2026-04-01T23:38:20.724231Z","steps":["trace[936510307] 'process raft request' (duration: 176.189209ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:38:53.571465Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2958} {"level":"info","ts":"2026-04-01T23:38:53.640943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2958,"took":"67.748239ms","hash":294510382,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":33140736,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-01T23:38:53.641001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":294510382,"revision":2958,"compact-revision":-1} {"level":"info","ts":"2026-04-01T23:43:53.600599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5985} {"level":"info","ts":"2026-04-01T23:43:53.714531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5985,"took":"111.840472ms","hash":530569288,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":26206208,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-01T23:43:53.714594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":530569288,"revision":5985,"compact-revision":2958} {"level":"warn","ts":"2026-04-01T23:44:12.208552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.91481ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-01T23:44:12.208974Z","caller":"traceutil/trace.go:171","msg":"trace[1280464984] linearizableReadLoop","detail":"{readStateIndex:8635; appliedIndex:8634; }","duration":"417.45598ms","start":"2026-04-01T23:44:11.791465Z","end":"2026-04-01T23:44:12.208921Z","steps":["trace[1280464984] 'read index received' (duration: 147.363235ms)","trace[1280464984] 'applied index is now lower than readState.Index' (duration: 270.088795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:44:12.209178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.716318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-01T23:44:12.209236Z","caller":"traceutil/trace.go:171","msg":"trace[1068042421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8345; }","duration":"417.80066ms","start":"2026-04-01T23:44:11.791414Z","end":"2026-04-01T23:44:12.209215Z","steps":["trace[1068042421] 'agreement among raft nodes before linearized reading' (duration: 417.645726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:12.209283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:11.791388Z","time spent":"417.887252ms","remote":"127.0.0.1:60896","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-04-01T23:44:12.226313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.813654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6595ff7785-4v8lf.18a2604029b02800\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-04-01T23:44:12.226391Z","caller":"traceutil/trace.go:171","msg":"trace[1882808841] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6595ff7785-4v8lf.18a2604029b02800; range_end:; response_count:1; response_revision:8348; }","duration":"392.927117ms","start":"2026-04-01T23:44:11.833441Z","end":"2026-04-01T23:44:12.226368Z","steps":["trace[1882808841] 'agreement among raft nodes before linearized reading' (duration: 392.765813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:12.226428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:11.833419Z","time spent":"393.000478ms","remote":"127.0.0.1:60696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1156,"request content":"key:\"/registry/events/openstack/barbican-api-6595ff7785-4v8lf.18a2604029b02800\" "} {"level":"info","ts":"2026-04-01T23:44:12.717966Z","caller":"traceutil/trace.go:171","msg":"trace[1395277934] linearizableReadLoop","detail":"{readStateIndex:8644; appliedIndex:8643; }","duration":"246.714333ms","start":"2026-04-01T23:44:12.471223Z","end":"2026-04-01T23:44:12.717937Z","steps":["trace[1395277934] 'read index received' (duration: 169.225266ms)","trace[1395277934] 'applied index is now lower than readState.Index' (duration: 77.487426ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:44:12.718013Z","caller":"traceutil/trace.go:171","msg":"trace[1012271314] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"337.881235ms","start":"2026-04-01T23:44:12.380108Z","end":"2026-04-01T23:44:12.717989Z","steps":["trace[1012271314] 'process raft request' (duration: 260.397849ms)","trace[1012271314] 'compare' (duration: 77.137117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:44:12.71814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:12.380084Z","time spent":"337.976527ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:44:12.718244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.131031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:44:12.718339Z","caller":"traceutil/trace.go:171","msg":"trace[1234180851] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8353; }","duration":"189.262785ms","start":"2026-04-01T23:44:12.52905Z","end":"2026-04-01T23:44:12.718313Z","steps":["trace[1234180851] 'agreement among raft nodes before linearized reading' (duration: 189.10258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:12.71836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.115403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-01T23:44:12.718481Z","caller":"traceutil/trace.go:171","msg":"trace[1734102384] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8353; }","duration":"247.301289ms","start":"2026-04-01T23:44:12.471154Z","end":"2026-04-01T23:44:12.718455Z","steps":["trace[1734102384] 'agreement among raft nodes before linearized reading' (duration: 246.960569ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:14.474334Z","caller":"traceutil/trace.go:171","msg":"trace[5351225] transaction","detail":"{read_only:false; response_revision:8361; number_of_response:1; }","duration":"115.155061ms","start":"2026-04-01T23:44:14.359155Z","end":"2026-04-01T23:44:14.47431Z","steps":["trace[5351225] 'process raft request' (duration: 114.977906ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:14.918412Z","caller":"traceutil/trace.go:171","msg":"trace[1625037349] transaction","detail":"{read_only:false; response_revision:8365; number_of_response:1; }","duration":"143.301094ms","start":"2026-04-01T23:44:14.775081Z","end":"2026-04-01T23:44:14.918382Z","steps":["trace[1625037349] 'process raft request' (duration: 97.404223ms)","trace[1625037349] 'compare' (duration: 45.713176ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:44:15.415706Z","caller":"traceutil/trace.go:171","msg":"trace[1403528135] transaction","detail":"{read_only:false; response_revision:8366; number_of_response:1; }","duration":"113.172247ms","start":"2026-04-01T23:44:15.302509Z","end":"2026-04-01T23:44:15.415681Z","steps":["trace[1403528135] 'process raft request' (duration: 112.983701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:42.87902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.531008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:44:42.879132Z","caller":"traceutil/trace.go:171","msg":"trace[1454490635] linearizableReadLoop","detail":"{readStateIndex:8864; appliedIndex:8863; }","duration":"422.976481ms","start":"2026-04-01T23:44:42.456137Z","end":"2026-04-01T23:44:42.879114Z","steps":["trace[1454490635] 'read index received' (duration: 286.205756ms)","trace[1454490635] 'applied index is now lower than readState.Index' (duration: 136.769185ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:44:42.879296Z","caller":"traceutil/trace.go:171","msg":"trace[1433130824] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"507.724937ms","start":"2026-04-01T23:44:42.37148Z","end":"2026-04-01T23:44:42.879205Z","steps":["trace[1433130824] 'process raft request' (duration: 370.928012ms)","trace[1433130824] 'compare' (duration: 136.333763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:44:42.879468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:42.371466Z","time spent":"507.904362ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:44:42.879683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.544487ms","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-04-01T23:44:42.879736Z","caller":"traceutil/trace.go:171","msg":"trace[309238119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8565; }","duration":"423.636968ms","start":"2026-04-01T23:44:42.456087Z","end":"2026-04-01T23:44:42.879724Z","steps":["trace[309238119] 'agreement among raft nodes before linearized reading' (duration: 423.290149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:42.879903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.690706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:44:42.87994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:42.45607Z","time spent":"423.799433ms","remote":"127.0.0.1:60896","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-04-01T23:44:42.880027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.907778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:44:42.879984Z","caller":"traceutil/trace.go:171","msg":"trace[881236591] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8565; }","duration":"409.817259ms","start":"2026-04-01T23:44:42.470149Z","end":"2026-04-01T23:44:42.879966Z","steps":["trace[881236591] 'agreement among raft nodes before linearized reading' (duration: 409.670565ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:42.880082Z","caller":"traceutil/trace.go:171","msg":"trace[122685650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8565; }","duration":"187.996291ms","start":"2026-04-01T23:44:42.692073Z","end":"2026-04-01T23:44:42.880069Z","steps":["trace[122685650] 'agreement among raft nodes before linearized reading' (duration: 187.903218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:42.880102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.028752ms","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-04-01T23:44:42.880153Z","caller":"traceutil/trace.go:171","msg":"trace[1223498701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8565; }","duration":"282.109305ms","start":"2026-04-01T23:44:42.598035Z","end":"2026-04-01T23:44:42.880144Z","steps":["trace[1223498701] 'agreement among raft nodes before linearized reading' (duration: 281.973931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:42.880158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:42.470132Z","time spent":"409.993244ms","remote":"127.0.0.1:47406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:44:55.321847Z","caller":"traceutil/trace.go:171","msg":"trace[175404276] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"161.691958ms","start":"2026-04-01T23:44:55.160129Z","end":"2026-04-01T23:44:55.321821Z","steps":["trace[175404276] 'process raft request' (duration: 77.697432ms)","trace[175404276] 'compare' (duration: 83.865802ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:44:55.82602Z","caller":"traceutil/trace.go:171","msg":"trace[939923721] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"127.997933ms","start":"2026-04-01T23:44:55.697995Z","end":"2026-04-01T23:44:55.825993Z","steps":["trace[939923721] 'process raft request' (duration: 127.816578ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:56.831641Z","caller":"traceutil/trace.go:171","msg":"trace[1717062360] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"112.077786ms","start":"2026-04-01T23:44:56.71953Z","end":"2026-04-01T23:44:56.831608Z","steps":["trace[1717062360] 'process raft request' (duration: 111.84257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:57.110791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.257518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-01T23:44:57.110898Z","caller":"traceutil/trace.go:171","msg":"trace[1031140971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8686; }","duration":"149.423502ms","start":"2026-04-01T23:44:56.961449Z","end":"2026-04-01T23:44:57.110873Z","steps":["trace[1031140971] 'range keys from in-memory index tree' (duration: 149.015301ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:57.192754Z","caller":"traceutil/trace.go:171","msg":"trace[1975706757] linearizableReadLoop","detail":"{readStateIndex:8992; appliedIndex:8991; }","duration":"218.183319ms","start":"2026-04-01T23:44:56.974543Z","end":"2026-04-01T23:44:57.192726Z","steps":["trace[1975706757] 'read index received' (duration: 218.029225ms)","trace[1975706757] 'applied index is now lower than readState.Index' (duration: 152.714µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:44:57.192884Z","caller":"traceutil/trace.go:171","msg":"trace[251962768] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"223.67319ms","start":"2026-04-01T23:44:56.969172Z","end":"2026-04-01T23:44:57.192845Z","steps":["trace[251962768] 'process raft request' (duration: 223.347891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:57.192974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.411866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:44:57.193038Z","caller":"traceutil/trace.go:171","msg":"trace[276103043] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:8687; }","duration":"218.491348ms","start":"2026-04-01T23:44:56.974525Z","end":"2026-04-01T23:44:57.193016Z","steps":["trace[276103043] 'agreement among raft nodes before linearized reading' (duration: 218.356015ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:57.313909Z","caller":"traceutil/trace.go:171","msg":"trace[1880554853] linearizableReadLoop","detail":"{readStateIndex:8993; appliedIndex:8992; }","duration":"121.048013ms","start":"2026-04-01T23:44:57.192836Z","end":"2026-04-01T23:44:57.313884Z","steps":["trace[1880554853] 'read index received' (duration: 39.621938ms)","trace[1880554853] 'applied index is now lower than readState.Index' (duration: 81.424365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:44:57.314131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.908642ms","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-04-01T23:44:57.314205Z","caller":"traceutil/trace.go:171","msg":"trace[402694022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8688; }","duration":"308.023655ms","start":"2026-04-01T23:44:57.006173Z","end":"2026-04-01T23:44:57.314197Z","steps":["trace[402694022] 'agreement among raft nodes before linearized reading' (duration: 307.826119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:57.314186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.82343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-01T23:44:57.314204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.077958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-01T23:44:57.314233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:57.006156Z","time spent":"308.070067ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:44:57.314219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.430984ms","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":"warn","ts":"2026-04-01T23:44:57.314282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.024416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sq8ts\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-01T23:44:57.314288Z","caller":"traceutil/trace.go:171","msg":"trace[1096644539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8688; }","duration":"307.976624ms","start":"2026-04-01T23:44:57.006282Z","end":"2026-04-01T23:44:57.314258Z","steps":["trace[1096644539] 'agreement among raft nodes before linearized reading' (duration: 307.701716ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:57.314347Z","caller":"traceutil/trace.go:171","msg":"trace[1691342728] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sq8ts; range_end:; response_count:1; response_revision:8688; }","duration":"151.122118ms","start":"2026-04-01T23:44:57.163211Z","end":"2026-04-01T23:44:57.314333Z","steps":["trace[1691342728] 'agreement among raft nodes before linearized reading' (duration: 150.950873ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:57.313916Z","caller":"traceutil/trace.go:171","msg":"trace[673834943] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"199.219439ms","start":"2026-04-01T23:44:57.114665Z","end":"2026-04-01T23:44:57.313885Z","steps":["trace[673834943] 'process raft request' (duration: 117.852186ms)","trace[673834943] 'compare' (duration: 81.24328ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:44:57.314371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:57.006245Z","time spent":"308.107888ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T23:44:57.31437Z","caller":"traceutil/trace.go:171","msg":"trace[607349234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8688; }","duration":"309.600779ms","start":"2026-04-01T23:44:57.004703Z","end":"2026-04-01T23:44:57.314304Z","steps":["trace[607349234] 'agreement among raft nodes before linearized reading' (duration: 309.378633ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:44:57.314291Z","caller":"traceutil/trace.go:171","msg":"trace[1592194997] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8688; }","duration":"190.205681ms","start":"2026-04-01T23:44:57.124066Z","end":"2026-04-01T23:44:57.314271Z","steps":["trace[1592194997] 'agreement among raft nodes before linearized reading' (duration: 189.846561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:44:57.314502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:44:57.004677Z","time spent":"309.798345ms","remote":"127.0.0.1:60896","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":"info","ts":"2026-04-01T23:45:45.331281Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024e6.wal"} {"level":"info","ts":"2026-04-01T23:45:57.071187Z","caller":"traceutil/trace.go:171","msg":"trace[1796628218] transaction","detail":"{read_only:false; response_revision:9336; number_of_response:1; }","duration":"122.801131ms","start":"2026-04-01T23:45:56.948358Z","end":"2026-04-01T23:45:57.07116Z","steps":["trace[1796628218] 'process raft request' (duration: 122.313528ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:46:43.910314Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-01T23:46:43.916348Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-01T23:46:43.916455Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-01T23:48:53.606816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8119} {"level":"info","ts":"2026-04-01T23:48:53.668892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8119,"took":"59.856656ms","hash":1660667187,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":29532160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-01T23:48:53.668948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1660667187,"revision":8119,"compact-revision":5985} {"level":"info","ts":"2026-04-01T23:53:53.613322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10639} {"level":"info","ts":"2026-04-01T23:53:53.69249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10639,"took":"77.392736ms","hash":4282110619,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":21151744,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-01T23:53:53.692564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4282110619,"revision":10639,"compact-revision":8119}