{"level":"warn","ts":"2026-04-20T04:52:48.002063Z","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-20T04:52:48.002206Z","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-20T04:52:48.002302Z","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-20T04:52:48.002314Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-20T04:52:48.002343Z","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-20T04:52:48.00321Z","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-20T04:52:48.003322Z","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-20T04:52:48.008634Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.028575ms"} {"level":"info","ts":"2026-04-20T04:52:48.022131Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-04-20T04:52:48.022239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T04:52:48.022292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-04-20T04:52:48.022317Z","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-20T04:52:48.022354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-04-20T04:52:48.022401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-04-20T04:52:48.028247Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T04:52:48.029335Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T04:52:48.030329Z","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-20T04:52:48.032156Z","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-20T04:52:48.032284Z","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-20T04:52:48.032506Z","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-20T04:52:48.032658Z","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-20T04:52:48.032713Z","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-20T04:52:48.035099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-04-20T04:52:48.035399Z","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-20T04:52:48.037311Z","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-20T04:52:48.037438Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-20T04:52:48.037561Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-20T04:52:48.037777Z","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-20T04:52:48.037838Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T04:52:48.823709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T04:52:48.823781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T04:52:48.823828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-04-20T04:52:48.823849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-04-20T04:52:48.823865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-20T04:52:48.823893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-04-20T04:52:48.823911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-20T04:52:48.826207Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:52:48.826471Z","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-20T04:52:48.826501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:52:48.82648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:52:48.826896Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T04:52:48.826966Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T04:52:48.82727Z","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-20T04:52:48.827384Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:52:48.827426Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:52:48.830227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T04:52:48.831085Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-04-20T04:52:50.225039Z","caller":"traceutil/trace.go:171","msg":"trace[660509531] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"155.788974ms","start":"2026-04-20T04:52:50.069218Z","end":"2026-04-20T04:52:50.225007Z","steps":["trace[660509531] 'process raft request' (duration: 155.55086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:50.225554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.502876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T04:52:50.225606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.132118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T04:52:50.225635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.22195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T04:52:50.225646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.310681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T04:52:50.225707Z","caller":"traceutil/trace.go:171","msg":"trace[2072737428] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:9; }","duration":"160.269921ms","start":"2026-04-20T04:52:50.065421Z","end":"2026-04-20T04:52:50.225691Z","steps":["trace[2072737428] 'agreement among raft nodes before linearized reading' (duration: 160.118568ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:50.225696Z","caller":"traceutil/trace.go:171","msg":"trace[712628765] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"103.671759ms","start":"2026-04-20T04:52:50.122004Z","end":"2026-04-20T04:52:50.225676Z","steps":["trace[712628765] 'agreement among raft nodes before linearized reading' (duration: 103.437745ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:50.225736Z","caller":"traceutil/trace.go:171","msg":"trace[328626639] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"104.334952ms","start":"2026-04-20T04:52:50.121372Z","end":"2026-04-20T04:52:50.225707Z","steps":["trace[328626639] 'agreement among raft nodes before linearized reading' (duration: 104.190459ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:50.225733Z","caller":"traceutil/trace.go:171","msg":"trace[1405577051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9; }","duration":"160.417373ms","start":"2026-04-20T04:52:50.0653Z","end":"2026-04-20T04:52:50.225717Z","steps":["trace[1405577051] 'agreement among raft nodes before linearized reading' (duration: 160.281901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:50.225759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.603046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T04:52:50.225843Z","caller":"traceutil/trace.go:171","msg":"trace[1667880015] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:9; }","duration":"160.711549ms","start":"2026-04-20T04:52:50.065109Z","end":"2026-04-20T04:52:50.22582Z","steps":["trace[1667880015] 'agreement among raft nodes before linearized reading' (duration: 160.576646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:50.226142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.607975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T04:52:50.226332Z","caller":"traceutil/trace.go:171","msg":"trace[1594096058] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"107.87507ms","start":"2026-04-20T04:52:50.118428Z","end":"2026-04-20T04:52:50.226303Z","steps":["trace[1594096058] 'agreement among raft nodes before linearized reading' (duration: 107.518213ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:02.352805Z","caller":"traceutil/trace.go:171","msg":"trace[703050179] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"114.385594ms","start":"2026-04-20T04:53:02.238385Z","end":"2026-04-20T04:53:02.35277Z","steps":["trace[703050179] 'process raft request' (duration: 114.204611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:04.958899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.559786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:04.959051Z","caller":"traceutil/trace.go:171","msg":"trace[1829379611] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"356.534637ms","start":"2026-04-20T04:53:04.602498Z","end":"2026-04-20T04:53:04.959033Z","steps":["trace[1829379611] 'read index received' (duration: 51.150226ms)","trace[1829379611] 'applied index is now lower than readState.Index' (duration: 305.382851ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:04.959127Z","caller":"traceutil/trace.go:171","msg":"trace[327492310] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"422.453656ms","start":"2026-04-20T04:53:04.536622Z","end":"2026-04-20T04:53:04.959076Z","steps":["trace[327492310] 'process raft request' (duration: 117.075584ms)","trace[327492310] 'compare' (duration: 304.24401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:04.95919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.689811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T04:53:04.959224Z","caller":"traceutil/trace.go:171","msg":"trace[970965923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"356.772992ms","start":"2026-04-20T04:53:04.60244Z","end":"2026-04-20T04:53:04.959213Z","steps":["trace[970965923] 'agreement among raft nodes before linearized reading' (duration: 356.68813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:04.959269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:04.602414Z","time spent":"356.846654ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T04:53:04.959332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:04.536592Z","time spent":"422.62833ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:53:04.959299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.368495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-20T04:53:04.959431Z","caller":"traceutil/trace.go:171","msg":"trace[65132501] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:413; }","duration":"311.543799ms","start":"2026-04-20T04:53:04.64784Z","end":"2026-04-20T04:53:04.959383Z","steps":["trace[65132501] 'agreement among raft nodes before linearized reading' (duration: 311.345035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:04.959501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:04.647812Z","time spent":"311.676652ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4078,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-04-20T04:53:05.106775Z","caller":"traceutil/trace.go:171","msg":"trace[1242979992] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"132.229454ms","start":"2026-04-20T04:53:04.974511Z","end":"2026-04-20T04:53:05.10674Z","steps":["trace[1242979992] 'process raft request' (duration: 127.780919ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:16.118803Z","caller":"traceutil/trace.go:171","msg":"trace[108011302] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"308.062854ms","start":"2026-04-20T04:53:15.810714Z","end":"2026-04-20T04:53:16.118777Z","steps":["trace[108011302] 'process raft request' (duration: 307.939581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:16.118959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:15.810694Z","time spent":"308.190036ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:19.967202Z","caller":"traceutil/trace.go:171","msg":"trace[222039270] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"233.936207ms","start":"2026-04-20T04:53:19.733239Z","end":"2026-04-20T04:53:19.967175Z","steps":["trace[222039270] 'process raft request' (duration: 233.801745ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.986618Z","caller":"traceutil/trace.go:171","msg":"trace[724282670] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"247.71982ms","start":"2026-04-20T04:53:19.738878Z","end":"2026-04-20T04:53:19.986598Z","steps":["trace[724282670] 'process raft request' (duration: 247.457614ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.986788Z","caller":"traceutil/trace.go:171","msg":"trace[124199468] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"247.829912ms","start":"2026-04-20T04:53:19.738919Z","end":"2026-04-20T04:53:19.986749Z","steps":["trace[124199468] 'process raft request' (duration: 247.533245ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.98724Z","caller":"traceutil/trace.go:171","msg":"trace[1663317687] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"240.649605ms","start":"2026-04-20T04:53:19.746576Z","end":"2026-04-20T04:53:19.987225Z","steps":["trace[1663317687] 'process raft request' (duration: 240.005282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.98732Z","caller":"traceutil/trace.go:171","msg":"trace[1638611568] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"233.00387ms","start":"2026-04-20T04:53:19.754295Z","end":"2026-04-20T04:53:19.987299Z","steps":["trace[1638611568] 'process raft request' (duration: 232.836977ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.987411Z","caller":"traceutil/trace.go:171","msg":"trace[848945300] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"229.067066ms","start":"2026-04-20T04:53:19.758312Z","end":"2026-04-20T04:53:19.987379Z","steps":["trace[848945300] 'process raft request' (duration: 228.883553ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.988962Z","caller":"traceutil/trace.go:171","msg":"trace[1395866456] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"226.916854ms","start":"2026-04-20T04:53:19.762016Z","end":"2026-04-20T04:53:19.988933Z","steps":["trace[1395866456] 'process raft request' (duration: 225.337575ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.989216Z","caller":"traceutil/trace.go:171","msg":"trace[1064616367] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"227.126328ms","start":"2026-04-20T04:53:19.762076Z","end":"2026-04-20T04:53:19.989203Z","steps":["trace[1064616367] 'process raft request' (duration: 226.165521ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.989338Z","caller":"traceutil/trace.go:171","msg":"trace[610141452] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"208.604927ms","start":"2026-04-20T04:53:19.780716Z","end":"2026-04-20T04:53:19.98932Z","steps":["trace[610141452] 'process raft request' (duration: 208.383933ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.989319Z","caller":"traceutil/trace.go:171","msg":"trace[1935951493] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"220.521223ms","start":"2026-04-20T04:53:19.768753Z","end":"2026-04-20T04:53:19.989274Z","steps":["trace[1935951493] 'process raft request' (duration: 220.250408ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:19.989477Z","caller":"traceutil/trace.go:171","msg":"trace[1523082842] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"193.364949ms","start":"2026-04-20T04:53:19.796097Z","end":"2026-04-20T04:53:19.989462Z","steps":["trace[1523082842] 'process raft request' (duration: 193.068343ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:26.178974Z","caller":"traceutil/trace.go:171","msg":"trace[1333281258] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"244.911486ms","start":"2026-04-20T04:53:25.934027Z","end":"2026-04-20T04:53:26.178939Z","steps":["trace[1333281258] 'process raft request' (duration: 243.294396ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:26.373175Z","caller":"traceutil/trace.go:171","msg":"trace[1513710996] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"116.629152ms","start":"2026-04-20T04:53:26.25651Z","end":"2026-04-20T04:53:26.373139Z","steps":["trace[1513710996] 'read index received' (duration: 74.728117ms)","trace[1513710996] 'applied index is now lower than readState.Index' (duration: 41.899945ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:26.373417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.879307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T04:53:26.373463Z","caller":"traceutil/trace.go:171","msg":"trace[821766036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"116.983528ms","start":"2026-04-20T04:53:26.256465Z","end":"2026-04-20T04:53:26.373448Z","steps":["trace[821766036] 'agreement among raft nodes before linearized reading' (duration: 116.798395ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:26.373465Z","caller":"traceutil/trace.go:171","msg":"trace[1497213695] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"139.003147ms","start":"2026-04-20T04:53:26.234419Z","end":"2026-04-20T04:53:26.373422Z","steps":["trace[1497213695] 'process raft request' (duration: 96.895678ms)","trace[1497213695] 'compare' (duration: 41.63329ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:27.037586Z","caller":"traceutil/trace.go:171","msg":"trace[1210348807] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"253.56598ms","start":"2026-04-20T04:53:26.783983Z","end":"2026-04-20T04:53:27.037548Z","steps":["trace[1210348807] 'process raft request' (duration: 246.750701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:27.037667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.699748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T04:53:27.037782Z","caller":"traceutil/trace.go:171","msg":"trace[1675192581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"452.140588ms","start":"2026-04-20T04:53:26.585614Z","end":"2026-04-20T04:53:27.037754Z","steps":["trace[1675192581] 'range keys from in-memory index tree' (duration: 451.2363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:27.037844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:26.585593Z","time spent":"452.235109ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T04:53:39.29019Z","caller":"traceutil/trace.go:171","msg":"trace[1705515375] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"110.928554ms","start":"2026-04-20T04:53:39.179227Z","end":"2026-04-20T04:53:39.290156Z","steps":["trace[1705515375] 'process raft request' (duration: 78.465999ms)","trace[1705515375] 'compare' (duration: 32.281442ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:41.879721Z","caller":"traceutil/trace.go:171","msg":"trace[532325678] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"137.558119ms","start":"2026-04-20T04:53:41.742135Z","end":"2026-04-20T04:53:41.879693Z","steps":["trace[532325678] 'read index received' (duration: 137.251824ms)","trace[532325678] 'applied index is now lower than readState.Index' (duration: 305.395µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:41.879926Z","caller":"traceutil/trace.go:171","msg":"trace[408366706] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"168.206751ms","start":"2026-04-20T04:53:41.711673Z","end":"2026-04-20T04:53:41.879879Z","steps":["trace[408366706] 'process raft request' (duration: 167.823664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:41.880024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.867645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T04:53:41.880065Z","caller":"traceutil/trace.go:171","msg":"trace[1281461816] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:780; }","duration":"138.009018ms","start":"2026-04-20T04:53:41.742045Z","end":"2026-04-20T04:53:41.880054Z","steps":["trace[1281461816] 'agreement among raft nodes before linearized reading' (duration: 137.759773ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:48.808516Z","caller":"traceutil/trace.go:171","msg":"trace[592152060] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"128.180793ms","start":"2026-04-20T04:54:48.680301Z","end":"2026-04-20T04:54:48.808482Z","steps":["trace[592152060] 'process raft request' (duration: 127.99697ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:57.705896Z","caller":"traceutil/trace.go:171","msg":"trace[922878293] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"123.138505ms","start":"2026-04-20T04:54:57.582731Z","end":"2026-04-20T04:54:57.70587Z","steps":["trace[922878293] 'process raft request' (duration: 122.976972ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:57.82379Z","caller":"traceutil/trace.go:171","msg":"trace[1071474154] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1923; }","duration":"121.771168ms","start":"2026-04-20T04:54:57.701988Z","end":"2026-04-20T04:54:57.823759Z","steps":["trace[1071474154] 'read index received' (duration: 3.708562ms)","trace[1071474154] 'applied index is now lower than readState.Index' (duration: 118.061336ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:57.823909Z","caller":"traceutil/trace.go:171","msg":"trace[1452431203] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"237.643531ms","start":"2026-04-20T04:54:57.586224Z","end":"2026-04-20T04:54:57.823868Z","steps":["trace[1452431203] 'process raft request' (duration: 231.350918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:57.824027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.019733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T04:54:57.824182Z","caller":"traceutil/trace.go:171","msg":"trace[1168683841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1851; }","duration":"122.166756ms","start":"2026-04-20T04:54:57.701946Z","end":"2026-04-20T04:54:57.824113Z","steps":["trace[1168683841] 'agreement among raft nodes before linearized reading' (duration: 121.936241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:58.44637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019864337032378,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:54:58.53607Z","caller":"traceutil/trace.go:171","msg":"trace[719218392] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"648.931438ms","start":"2026-04-20T04:54:57.887106Z","end":"2026-04-20T04:54:58.536037Z","steps":["trace[719218392] 'process raft request' (duration: 648.744045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:58.536262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:57.887082Z","time spent":"649.075931ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3725,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:58.538607Z","caller":"traceutil/trace.go:171","msg":"trace[1007271781] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1926; }","duration":"592.900279ms","start":"2026-04-20T04:54:57.945669Z","end":"2026-04-20T04:54:58.538569Z","steps":["trace[1007271781] 'read index received' (duration: 589.917591ms)","trace[1007271781] 'applied index is now lower than readState.Index' (duration: 2.951477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:58.538818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.461025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:54:58.538786Z","caller":"traceutil/trace.go:171","msg":"trace[1969160010] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"385.831292ms","start":"2026-04-20T04:54:58.152912Z","end":"2026-04-20T04:54:58.538743Z","steps":["trace[1969160010] 'process raft request' (duration: 385.460845ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:58.538856Z","caller":"traceutil/trace.go:171","msg":"trace[10014030] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1854; }","duration":"477.547986ms","start":"2026-04-20T04:54:58.061298Z","end":"2026-04-20T04:54:58.538846Z","steps":["trace[10014030] 'agreement among raft nodes before linearized reading' (duration: 477.452904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:58.538898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:58.061281Z","time spent":"477.605427ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T04:54:58.538964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.9978ms","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-20T04:54:58.538963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.308176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T04:54:58.538958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.256696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a7f7ab8281c47a\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-20T04:54:58.539024Z","caller":"traceutil/trace.go:171","msg":"trace[12115000] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"289.116112ms","start":"2026-04-20T04:54:58.249891Z","end":"2026-04-20T04:54:58.539007Z","steps":["trace[12115000] 'agreement among raft nodes before linearized reading' (duration: 288.913598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:58.539033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:58.152886Z","time spent":"385.994195ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:58.539044Z","caller":"traceutil/trace.go:171","msg":"trace[25005608] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1854; }","duration":"270.443029ms","start":"2026-04-20T04:54:58.26858Z","end":"2026-04-20T04:54:58.539023Z","steps":["trace[25005608] 'agreement among raft nodes before linearized reading' (duration: 270.281576ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:58.539064Z","caller":"traceutil/trace.go:171","msg":"trace[1074221733] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a7f7ab8281c47a; range_end:; response_count:1; response_revision:1854; }","duration":"593.411078ms","start":"2026-04-20T04:54:57.945624Z","end":"2026-04-20T04:54:58.539035Z","steps":["trace[1074221733] 'agreement among raft nodes before linearized reading' (duration: 593.125723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:58.539115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:57.945604Z","time spent":"593.500971ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1004,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a7f7ab8281c47a\" "} {"level":"warn","ts":"2026-04-20T04:54:58.539173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.630304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc3d021d-4fb3-4b6b-a479-aa8739f92da8\" ","response":"range_response_count:1 size:3740"} {"level":"info","ts":"2026-04-20T04:54:58.539381Z","caller":"traceutil/trace.go:171","msg":"trace[1927398265] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cc3d021d-4fb3-4b6b-a479-aa8739f92da8; range_end:; response_count:1; response_revision:1854; }","duration":"467.849918ms","start":"2026-04-20T04:54:58.071515Z","end":"2026-04-20T04:54:58.539365Z","steps":["trace[1927398265] 'agreement among raft nodes before linearized reading' (duration: 467.608593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:58.53942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:58.071506Z","time spent":"467.902759ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3762,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc3d021d-4fb3-4b6b-a479-aa8739f92da8\" "} {"level":"warn","ts":"2026-04-20T04:54:58.539254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.481517ms","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-20T04:54:58.539477Z","caller":"traceutil/trace.go:171","msg":"trace[1096961864] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1854; }","duration":"402.713021ms","start":"2026-04-20T04:54:58.136741Z","end":"2026-04-20T04:54:58.539454Z","steps":["trace[1096961864] 'agreement among raft nodes before linearized reading' (duration: 402.249851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:58.539532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:58.13671Z","time spent":"402.808263ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":245,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-20T04:54:58.53923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.865722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-20T04:54:58.539633Z","caller":"traceutil/trace.go:171","msg":"trace[1130352608] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1854; }","duration":"371.29019ms","start":"2026-04-20T04:54:58.168306Z","end":"2026-04-20T04:54:58.539597Z","steps":["trace[1130352608] 'agreement among raft nodes before linearized reading' (duration: 370.705078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:58.539693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:58.168297Z","time spent":"371.381371ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T04:55:42.882012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.092947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:55:42.882295Z","caller":"traceutil/trace.go:171","msg":"trace[372144283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2142; }","duration":"176.684528ms","start":"2026-04-20T04:55:42.70559Z","end":"2026-04-20T04:55:42.882274Z","steps":["trace[372144283] 'range keys from in-memory index tree' (duration: 175.944195ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:49.249827Z","caller":"traceutil/trace.go:171","msg":"trace[1879234321] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"163.072585ms","start":"2026-04-20T04:55:49.086726Z","end":"2026-04-20T04:55:49.249799Z","steps":["trace[1879234321] 'process raft request' (duration: 162.841361ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:49.647448Z","caller":"traceutil/trace.go:171","msg":"trace[873001241] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2259; }","duration":"440.493464ms","start":"2026-04-20T04:55:49.206922Z","end":"2026-04-20T04:55:49.647416Z","steps":["trace[873001241] 'read index received' (duration: 42.688878ms)","trace[873001241] 'applied index is now lower than readState.Index' (duration: 397.802716ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:49.647613Z","caller":"traceutil/trace.go:171","msg":"trace[306993218] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"478.323211ms","start":"2026-04-20T04:55:49.169267Z","end":"2026-04-20T04:55:49.64759Z","steps":["trace[306993218] 'process raft request' (duration: 394.638598ms)","trace[306993218] 'compare' (duration: 83.069801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:55:49.647757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.169231Z","time spent":"478.421023ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:55:49.647797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.870191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:55:49.647874Z","caller":"traceutil/trace.go:171","msg":"trace[2114066466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2177; }","duration":"441.002313ms","start":"2026-04-20T04:55:49.20685Z","end":"2026-04-20T04:55:49.647853Z","steps":["trace[2114066466] 'agreement among raft nodes before linearized reading' (duration: 440.84875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:49.647925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.20681Z","time spent":"441.107475ms","remote":"127.0.0.1:52360","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-20T04:55:49.663043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.17595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T04:55:49.663114Z","caller":"traceutil/trace.go:171","msg":"trace[545755634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2178; }","duration":"415.291304ms","start":"2026-04-20T04:55:49.247804Z","end":"2026-04-20T04:55:49.663096Z","steps":["trace[545755634] 'agreement among raft nodes before linearized reading' (duration: 415.143141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:49.663152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.247772Z","time spent":"415.370556ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T04:55:49.663193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.454729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a7f7b20a372340\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-20T04:55:49.66332Z","caller":"traceutil/trace.go:171","msg":"trace[1650022657] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a7f7b20a372340; range_end:; response_count:1; response_revision:2178; }","duration":"413.616481ms","start":"2026-04-20T04:55:49.24967Z","end":"2026-04-20T04:55:49.663287Z","steps":["trace[1650022657] 'agreement among raft nodes before linearized reading' (duration: 413.270775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:49.66334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.694801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-20T04:55:49.663379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.249659Z","time spent":"413.709274ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a7f7b20a372340\" "} {"level":"info","ts":"2026-04-20T04:55:49.663426Z","caller":"traceutil/trace.go:171","msg":"trace[776275264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2178; }","duration":"415.829113ms","start":"2026-04-20T04:55:49.247575Z","end":"2026-04-20T04:55:49.663404Z","steps":["trace[776275264] 'agreement among raft nodes before linearized reading' (duration: 415.581419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:49.663486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.247537Z","time spent":"415.936655ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T04:55:50.024622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.808105ms","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-20T04:55:50.024731Z","caller":"traceutil/trace.go:171","msg":"trace[1281375838] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2178; }","duration":"181.938507ms","start":"2026-04-20T04:55:49.842776Z","end":"2026-04-20T04:55:50.024715Z","steps":["trace[1281375838] 'range keys from in-memory index tree' (duration: 181.794054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:50.025918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.488137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:55:50.026073Z","caller":"traceutil/trace.go:171","msg":"trace[1370374200] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"359.795757ms","start":"2026-04-20T04:55:49.666212Z","end":"2026-04-20T04:55:50.026008Z","steps":["trace[1370374200] 'process raft request' (duration: 155.104136ms)","trace[1370374200] 'compare' (duration: 204.354795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:55:50.026196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.666183Z","time spent":"359.966281ms","remote":"127.0.0.1:52444","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-20T04:55:50.043932Z","caller":"traceutil/trace.go:171","msg":"trace[1570293249] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2262; }","duration":"320.982633ms","start":"2026-04-20T04:55:49.722927Z","end":"2026-04-20T04:55:50.04391Z","steps":["trace[1570293249] 'read index received' (duration: 98.454628ms)","trace[1570293249] 'applied index is now lower than readState.Index' (duration: 222.526845ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:50.044012Z","caller":"traceutil/trace.go:171","msg":"trace[1951257797] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"375.673434ms","start":"2026-04-20T04:55:49.668295Z","end":"2026-04-20T04:55:50.043968Z","steps":["trace[1951257797] 'process raft request' (duration: 375.45736ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:50.044017Z","caller":"traceutil/trace.go:171","msg":"trace[680871006] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"375.661333ms","start":"2026-04-20T04:55:49.668326Z","end":"2026-04-20T04:55:50.043987Z","steps":["trace[680871006] 'process raft request' (duration: 375.543611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:50.044108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.167056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:55:50.044147Z","caller":"traceutil/trace.go:171","msg":"trace[713907622] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2181; }","duration":"321.259518ms","start":"2026-04-20T04:55:49.722875Z","end":"2026-04-20T04:55:50.044134Z","steps":["trace[713907622] 'agreement among raft nodes before linearized reading' (duration: 321.156866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:50.04417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.668269Z","time spent":"375.811846ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:55:50.04419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.722857Z","time spent":"321.322409ms","remote":"127.0.0.1:36998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T04:55:50.044255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:49.668302Z","time spent":"375.818157ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:55:50.045615Z","caller":"traceutil/trace.go:171","msg":"trace[687497477] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"124.200668ms","start":"2026-04-20T04:55:49.921397Z","end":"2026-04-20T04:55:50.045598Z","steps":["trace[687497477] 'process raft request' (duration: 123.782841ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:57:13.524549Z","caller":"traceutil/trace.go:171","msg":"trace[1600480790] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"125.665776ms","start":"2026-04-20T04:57:13.398853Z","end":"2026-04-20T04:57:13.524519Z","steps":["trace[1600480790] 'process raft request' (duration: 125.494472ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:12.989372Z","caller":"traceutil/trace.go:171","msg":"trace[1568895527] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"100.583361ms","start":"2026-04-20T05:01:12.888743Z","end":"2026-04-20T05:01:12.989327Z","steps":["trace[1568895527] 'process raft request' (duration: 100.315285ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:13.10434Z","caller":"traceutil/trace.go:171","msg":"trace[1698624027] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"103.857442ms","start":"2026-04-20T05:01:13.000453Z","end":"2026-04-20T05:01:13.104311Z","steps":["trace[1698624027] 'process raft request' (duration: 103.679678ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:13.132749Z","caller":"traceutil/trace.go:171","msg":"trace[1834485156] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"115.01847ms","start":"2026-04-20T05:01:13.017511Z","end":"2026-04-20T05:01:13.13253Z","steps":["trace[1834485156] 'process raft request' (duration: 114.612561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:15.208976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.150669ms","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-20T05:01:15.20899Z","caller":"traceutil/trace.go:171","msg":"trace[733756777] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"188.532184ms","start":"2026-04-20T05:01:15.020423Z","end":"2026-04-20T05:01:15.208955Z","steps":["trace[733756777] 'process raft request' (duration: 175.0069ms)","trace[733756777] 'compare' (duration: 13.34794ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:15.209068Z","caller":"traceutil/trace.go:171","msg":"trace[1835079694] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4903; }","duration":"201.294422ms","start":"2026-04-20T05:01:15.007753Z","end":"2026-04-20T05:01:15.209047Z","steps":["trace[1835079694] 'range keys from in-memory index tree' (duration: 200.929566ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:15.219664Z","caller":"traceutil/trace.go:171","msg":"trace[850737234] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"199.163462ms","start":"2026-04-20T05:01:15.020476Z","end":"2026-04-20T05:01:15.21964Z","steps":["trace[850737234] 'process raft request' (duration: 199.004349ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:17.420787Z","caller":"traceutil/trace.go:171","msg":"trace[947915189] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"206.946068ms","start":"2026-04-20T05:01:17.213811Z","end":"2026-04-20T05:01:17.420757Z","steps":["trace[947915189] 'process raft request' (duration: 206.767683ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:17.627698Z","caller":"traceutil/trace.go:171","msg":"trace[363591008] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"402.184366ms","start":"2026-04-20T05:01:17.225478Z","end":"2026-04-20T05:01:17.627662Z","steps":["trace[363591008] 'process raft request' (duration: 401.888081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:17.627942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:17.225441Z","time spent":"402.376619ms","remote":"127.0.0.1:52630","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-20T05:01:17.628591Z","caller":"traceutil/trace.go:171","msg":"trace[1245663508] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5229; }","duration":"375.867865ms","start":"2026-04-20T05:01:17.252708Z","end":"2026-04-20T05:01:17.628576Z","steps":["trace[1245663508] 'read index received' (duration: 169.417096ms)","trace[1245663508] 'applied index is now lower than readState.Index' (duration: 206.449379ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:17.628689Z","caller":"traceutil/trace.go:171","msg":"trace[1474965667] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"225.24585ms","start":"2026-04-20T05:01:17.403434Z","end":"2026-04-20T05:01:17.62868Z","steps":["trace[1474965667] 'process raft request' (duration: 224.159209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:17.628932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.223411ms","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-20T05:01:17.628973Z","caller":"traceutil/trace.go:171","msg":"trace[1035189517] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5035; }","duration":"376.302683ms","start":"2026-04-20T05:01:17.25266Z","end":"2026-04-20T05:01:17.628962Z","steps":["trace[1035189517] 'agreement among raft nodes before linearized reading' (duration: 376.13705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:17.629006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:17.252641Z","time spent":"376.356953ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T05:01:17.629043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.908531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:01:17.629128Z","caller":"traceutil/trace.go:171","msg":"trace[921649701] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5035; }","duration":"310.081824ms","start":"2026-04-20T05:01:17.319028Z","end":"2026-04-20T05:01:17.629109Z","steps":["trace[921649701] 'agreement among raft nodes before linearized reading' (duration: 309.86179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:17.629172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:17.318999Z","time spent":"310.162426ms","remote":"127.0.0.1:52644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":42,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-20T05:01:36.078547Z","caller":"traceutil/trace.go:171","msg":"trace[461399481] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"120.823288ms","start":"2026-04-20T05:01:35.957685Z","end":"2026-04-20T05:01:36.078509Z","steps":["trace[461399481] 'process raft request' (duration: 119.590945ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:47.771533Z","caller":"traceutil/trace.go:171","msg":"trace[2087207852] linearizableReadLoop","detail":"{readStateIndex:5741; appliedIndex:5740; }","duration":"129.545012ms","start":"2026-04-20T05:01:47.64195Z","end":"2026-04-20T05:01:47.771495Z","steps":["trace[2087207852] 'read index received' (duration: 129.171144ms)","trace[2087207852] 'applied index is now lower than readState.Index' (duration: 372.718µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:01:47.771786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.794216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:01:47.771835Z","caller":"traceutil/trace.go:171","msg":"trace[431277586] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5533; }","duration":"129.939419ms","start":"2026-04-20T05:01:47.641882Z","end":"2026-04-20T05:01:47.771821Z","steps":["trace[431277586] 'agreement among raft nodes before linearized reading' (duration: 129.760985ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:47.771753Z","caller":"traceutil/trace.go:171","msg":"trace[2052914839] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"142.225788ms","start":"2026-04-20T05:01:47.629476Z","end":"2026-04-20T05:01:47.771702Z","steps":["trace[2052914839] 'process raft request' (duration: 141.762769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:55.490604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.226695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-04-20T05:01:55.490722Z","caller":"traceutil/trace.go:171","msg":"trace[1303191745] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5624; }","duration":"117.403809ms","start":"2026-04-20T05:01:55.373295Z","end":"2026-04-20T05:01:55.490698Z","steps":["trace[1303191745] 'range keys from in-memory index tree' (duration: 116.998862ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:55.771483Z","caller":"traceutil/trace.go:171","msg":"trace[1061333270] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"267.43045ms","start":"2026-04-20T05:01:55.504012Z","end":"2026-04-20T05:01:55.771443Z","steps":["trace[1061333270] 'process raft request' (duration: 267.197555ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.310355Z","caller":"traceutil/trace.go:171","msg":"trace[1273785661] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"805.136463ms","start":"2026-04-20T05:01:55.505177Z","end":"2026-04-20T05:01:56.310313Z","steps":["trace[1273785661] 'process raft request' (duration: 713.110938ms)","trace[1273785661] 'compare' (duration: 91.876222ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:01:56.310575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:55.505151Z","time spent":"805.309907ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10666,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:01:56.372177Z","caller":"traceutil/trace.go:171","msg":"trace[1189393498] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"572.249432ms","start":"2026-04-20T05:01:55.799899Z","end":"2026-04-20T05:01:56.372148Z","steps":["trace[1189393498] 'process raft request' (duration: 572.041328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.372436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:55.79987Z","time spent":"572.385844ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:01:56.372487Z","caller":"traceutil/trace.go:171","msg":"trace[1152502841] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"564.109997ms","start":"2026-04-20T05:01:55.808332Z","end":"2026-04-20T05:01:56.372442Z","steps":["trace[1152502841] 'process raft request' (duration: 563.77522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.3727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.053241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-20T05:01:56.372724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:55.808291Z","time spent":"564.304941ms","remote":"127.0.0.1:52630","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-20T05:01:56.372792Z","caller":"traceutil/trace.go:171","msg":"trace[277964503] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5628; }","duration":"504.193154ms","start":"2026-04-20T05:01:55.86858Z","end":"2026-04-20T05:01:56.372773Z","steps":["trace[277964503] 'agreement among raft nodes before linearized reading' (duration: 503.93176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.372847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:55.868565Z","time spent":"504.271656ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T05:01:56.373012Z","caller":"traceutil/trace.go:171","msg":"trace[1180959095] linearizableReadLoop","detail":"{readStateIndex:5840; appliedIndex:5837; }","duration":"503.785956ms","start":"2026-04-20T05:01:55.86862Z","end":"2026-04-20T05:01:56.372406Z","steps":["trace[1180959095] 'read index received' (duration: 349.690487ms)","trace[1180959095] 'applied index is now lower than readState.Index' (duration: 154.092749ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:56.509168Z","caller":"traceutil/trace.go:171","msg":"trace[1779229035] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"190.581465ms","start":"2026-04-20T05:01:56.318565Z","end":"2026-04-20T05:01:56.509147Z","steps":["trace[1779229035] 'process raft request' (duration: 132.66266ms)","trace[1779229035] 'compare' (duration: 57.70082ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:56.509464Z","caller":"traceutil/trace.go:171","msg":"trace[1462658425] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"190.449802ms","start":"2026-04-20T05:01:56.319002Z","end":"2026-04-20T05:01:56.509452Z","steps":["trace[1462658425] 'process raft request' (duration: 190.097726ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.509819Z","caller":"traceutil/trace.go:171","msg":"trace[709712549] transaction","detail":"{read_only:false; response_revision:5633; number_of_response:1; }","duration":"131.916226ms","start":"2026-04-20T05:01:56.377878Z","end":"2026-04-20T05:01:56.509794Z","steps":["trace[709712549] 'process raft request' (duration: 131.835235ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.509889Z","caller":"traceutil/trace.go:171","msg":"trace[605672962] transaction","detail":"{read_only:false; response_revision:5632; number_of_response:1; }","duration":"190.34399ms","start":"2026-04-20T05:01:56.319474Z","end":"2026-04-20T05:01:56.509818Z","steps":["trace[605672962] 'process raft request' (duration: 190.144795ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.509935Z","caller":"traceutil/trace.go:171","msg":"trace[1236817918] linearizableReadLoop","detail":"{readStateIndex:5844; appliedIndex:5840; }","duration":"136.832359ms","start":"2026-04-20T05:01:56.373051Z","end":"2026-04-20T05:01:56.509884Z","steps":["trace[1236817918] 'read index received' (duration: 78.194101ms)","trace[1236817918] 'applied index is now lower than readState.Index' (duration: 58.633898ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:56.509963Z","caller":"traceutil/trace.go:171","msg":"trace[1580282739] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"190.550934ms","start":"2026-04-20T05:01:56.31936Z","end":"2026-04-20T05:01:56.509911Z","steps":["trace[1580282739] 'process raft request' (duration: 190.039713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.510121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.9601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T05:01:56.510262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.466066ms","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-20T05:01:56.510357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.606319ms","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-20T05:01:56.510383Z","caller":"traceutil/trace.go:171","msg":"trace[456189660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5633; }","duration":"562.62752ms","start":"2026-04-20T05:01:55.947723Z","end":"2026-04-20T05:01:56.51035Z","steps":["trace[456189660] 'agreement among raft nodes before linearized reading' (duration: 562.202112ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.510418Z","caller":"traceutil/trace.go:171","msg":"trace[58196894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5633; }","duration":"562.716791ms","start":"2026-04-20T05:01:55.947688Z","end":"2026-04-20T05:01:56.510405Z","steps":["trace[58196894] 'agreement among raft nodes before linearized reading' (duration: 562.471326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.51045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:55.947709Z","time spent":"562.731121ms","remote":"127.0.0.1:52630","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-20T05:01:56.510483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:55.947675Z","time spent":"562.799123ms","remote":"127.0.0.1:52630","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-20T05:01:56.51036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.497003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"warn","ts":"2026-04-20T05:01:56.510519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.814547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T05:01:56.510553Z","caller":"traceutil/trace.go:171","msg":"trace[1520169078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5633; }","duration":"482.084793ms","start":"2026-04-20T05:01:56.028103Z","end":"2026-04-20T05:01:56.510187Z","steps":["trace[1520169078] 'agreement among raft nodes before linearized reading' (duration: 481.793477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.510612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.959146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-20T05:01:56.510649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:56.028069Z","time spent":"482.565552ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T05:01:56.510714Z","caller":"traceutil/trace.go:171","msg":"trace[139885296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5633; }","duration":"584.18772ms","start":"2026-04-20T05:01:55.926501Z","end":"2026-04-20T05:01:56.510689Z","steps":["trace[139885296] 'agreement among raft nodes before linearized reading' (duration: 583.794663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.510708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.915641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-rnf9p\" ","response":"range_response_count:1 size:8063"} {"level":"info","ts":"2026-04-20T05:01:56.510692Z","caller":"traceutil/trace.go:171","msg":"trace[1825476548] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5633; }","duration":"372.788519ms","start":"2026-04-20T05:01:56.137796Z","end":"2026-04-20T05:01:56.510585Z","steps":["trace[1825476548] 'agreement among raft nodes before linearized reading' (duration: 372.203478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.510871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:56.137779Z","time spent":"373.076914ms","remote":"127.0.0.1:59370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3265,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-20T05:01:56.510773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:55.926465Z","time spent":"584.292092ms","remote":"127.0.0.1:52630","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":"info","ts":"2026-04-20T05:01:56.511088Z","caller":"traceutil/trace.go:171","msg":"trace[1331773955] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-rnf9p; range_end:; response_count:1; response_revision:5633; }","duration":"126.340219ms","start":"2026-04-20T05:01:56.384691Z","end":"2026-04-20T05:01:56.511031Z","steps":["trace[1331773955] 'agreement among raft nodes before linearized reading' (duration: 125.576385ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.51064Z","caller":"traceutil/trace.go:171","msg":"trace[1777237051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5633; }","duration":"472.993459ms","start":"2026-04-20T05:01:56.03762Z","end":"2026-04-20T05:01:56.510613Z","steps":["trace[1777237051] 'agreement among raft nodes before linearized reading' (duration: 472.607512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:56.511281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:56.037608Z","time spent":"473.627343ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T05:01:56.721763Z","caller":"traceutil/trace.go:171","msg":"trace[2108561784] transaction","detail":"{read_only:false; response_revision:5636; number_of_response:1; }","duration":"204.732714ms","start":"2026-04-20T05:01:56.517012Z","end":"2026-04-20T05:01:56.721745Z","steps":["trace[2108561784] 'process raft request' (duration: 204.648542ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.721858Z","caller":"traceutil/trace.go:171","msg":"trace[983398662] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"204.797395ms","start":"2026-04-20T05:01:56.517014Z","end":"2026-04-20T05:01:56.721812Z","steps":["trace[983398662] 'process raft request' (duration: 118.336276ms)","trace[983398662] 'compare' (duration: 86.207844ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:56.722217Z","caller":"traceutil/trace.go:171","msg":"trace[1528954871] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"204.685693ms","start":"2026-04-20T05:01:56.517505Z","end":"2026-04-20T05:01:56.72219Z","steps":["trace[1528954871] 'process raft request' (duration: 204.200524ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.722811Z","caller":"traceutil/trace.go:171","msg":"trace[671893139] transaction","detail":"{read_only:false; response_revision:5639; number_of_response:1; }","duration":"201.890231ms","start":"2026-04-20T05:01:56.520844Z","end":"2026-04-20T05:01:56.722734Z","steps":["trace[671893139] 'process raft request' (duration: 201.667417ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:56.722821Z","caller":"traceutil/trace.go:171","msg":"trace[55372371] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"205.186613ms","start":"2026-04-20T05:01:56.517562Z","end":"2026-04-20T05:01:56.722748Z","steps":["trace[55372371] 'process raft request' (duration: 204.387768ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:02:48.857459Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-04-20T05:02:48.946696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"87.567271ms","hash":4115410174,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":31363072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-20T05:02:48.9468Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4115410174,"revision":3020,"compact-revision":-1} {"level":"info","ts":"2026-04-20T05:07:48.899117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6088} {"level":"info","ts":"2026-04-20T05:07:49.001217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6088,"took":"100.134642ms","hash":384827467,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":22638592,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-20T05:07:49.001276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":384827467,"revision":6088,"compact-revision":3020} {"level":"warn","ts":"2026-04-20T05:07:52.582954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.656371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:07:52.583055Z","caller":"traceutil/trace.go:171","msg":"trace[1490039395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8336; }","duration":"377.815374ms","start":"2026-04-20T05:07:52.20522Z","end":"2026-04-20T05:07:52.583035Z","steps":["trace[1490039395] 'range keys from in-memory index tree' (duration: 377.57427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:52.583106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:52.2052Z","time spent":"377.894796ms","remote":"127.0.0.1:52360","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-20T05:07:52.821979Z","caller":"traceutil/trace.go:171","msg":"trace[1548422223] transaction","detail":"{read_only:false; response_revision:8337; number_of_response:1; }","duration":"233.503926ms","start":"2026-04-20T05:07:52.588431Z","end":"2026-04-20T05:07:52.821935Z","steps":["trace[1548422223] 'process raft request' (duration: 233.217791ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:52.822549Z","caller":"traceutil/trace.go:171","msg":"trace[2097747682] linearizableReadLoop","detail":"{readStateIndex:8637; appliedIndex:8635; }","duration":"139.599551ms","start":"2026-04-20T05:07:52.682925Z","end":"2026-04-20T05:07:52.822525Z","steps":["trace[2097747682] 'read index received' (duration: 138.792245ms)","trace[2097747682] 'applied index is now lower than readState.Index' (duration: 805.936µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:52.822746Z","caller":"traceutil/trace.go:171","msg":"trace[1461807027] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"233.850753ms","start":"2026-04-20T05:07:52.588876Z","end":"2026-04-20T05:07:52.822727Z","steps":["trace[1461807027] 'process raft request' (duration: 233.517586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:52.823119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.53748ms","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-20T05:07:52.823241Z","caller":"traceutil/trace.go:171","msg":"trace[302984225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8338; }","duration":"138.692923ms","start":"2026-04-20T05:07:52.684529Z","end":"2026-04-20T05:07:52.823222Z","steps":["trace[302984225] 'agreement among raft nodes before linearized reading' (duration: 138.409018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:52.823191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.172028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T05:07:52.823427Z","caller":"traceutil/trace.go:171","msg":"trace[973081975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8338; }","duration":"135.446273ms","start":"2026-04-20T05:07:52.687955Z","end":"2026-04-20T05:07:52.823401Z","steps":["trace[973081975] 'agreement among raft nodes before linearized reading' (duration: 134.955584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:52.823265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.332034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T05:07:52.823586Z","caller":"traceutil/trace.go:171","msg":"trace[943277089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8338; }","duration":"140.682059ms","start":"2026-04-20T05:07:52.682872Z","end":"2026-04-20T05:07:52.823554Z","steps":["trace[943277089] 'agreement among raft nodes before linearized reading' (duration: 140.292602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:52.824054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.814465ms","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-20T05:07:52.824152Z","caller":"traceutil/trace.go:171","msg":"trace[1385940636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8338; }","duration":"139.986118ms","start":"2026-04-20T05:07:52.684138Z","end":"2026-04-20T05:07:52.824124Z","steps":["trace[1385940636] 'agreement among raft nodes before linearized reading' (duration: 138.622142ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:05.183998Z","caller":"traceutil/trace.go:171","msg":"trace[2141321134] transaction","detail":"{read_only:false; response_revision:8400; number_of_response:1; }","duration":"210.497286ms","start":"2026-04-20T05:08:04.973475Z","end":"2026-04-20T05:08:05.183972Z","steps":["trace[2141321134] 'process raft request' (duration: 210.288042ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:05.184443Z","caller":"traceutil/trace.go:171","msg":"trace[289648778] transaction","detail":"{read_only:false; response_revision:8403; number_of_response:1; }","duration":"210.620919ms","start":"2026-04-20T05:08:04.973781Z","end":"2026-04-20T05:08:05.184402Z","steps":["trace[289648778] 'process raft request' (duration: 210.528087ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:05.184499Z","caller":"traceutil/trace.go:171","msg":"trace[329601233] transaction","detail":"{read_only:false; response_revision:8401; number_of_response:1; }","duration":"210.728291ms","start":"2026-04-20T05:08:04.973742Z","end":"2026-04-20T05:08:05.18447Z","steps":["trace[329601233] 'process raft request' (duration: 210.18429ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:05.184581Z","caller":"traceutil/trace.go:171","msg":"trace[689055556] transaction","detail":"{read_only:false; response_revision:8404; number_of_response:1; }","duration":"209.675251ms","start":"2026-04-20T05:08:04.974858Z","end":"2026-04-20T05:08:05.184533Z","steps":["trace[689055556] 'process raft request' (duration: 209.495258ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:05.184746Z","caller":"traceutil/trace.go:171","msg":"trace[1217799204] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"210.880954ms","start":"2026-04-20T05:08:04.973802Z","end":"2026-04-20T05:08:05.184683Z","steps":["trace[1217799204] 'process raft request' (duration: 210.373253ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:06.167727Z","caller":"traceutil/trace.go:171","msg":"trace[2142907937] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"240.494928ms","start":"2026-04-20T05:08:05.927204Z","end":"2026-04-20T05:08:06.167699Z","steps":["trace[2142907937] 'process raft request' (duration: 240.202513ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:06.168066Z","caller":"traceutil/trace.go:171","msg":"trace[783767528] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"211.25527ms","start":"2026-04-20T05:08:05.956759Z","end":"2026-04-20T05:08:06.168015Z","steps":["trace[783767528] 'process raft request' (duration: 210.838913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:08.480884Z","caller":"traceutil/trace.go:171","msg":"trace[351006207] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"157.086057ms","start":"2026-04-20T05:08:08.323774Z","end":"2026-04-20T05:08:08.48086Z","steps":["trace[351006207] 'process raft request' (duration: 156.908984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:36.89296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.340833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:08:36.893196Z","caller":"traceutil/trace.go:171","msg":"trace[1905034640] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"290.723796ms","start":"2026-04-20T05:08:36.602454Z","end":"2026-04-20T05:08:36.893178Z","steps":["trace[1905034640] 'process raft request' (duration: 290.660355ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:36.893194Z","caller":"traceutil/trace.go:171","msg":"trace[2132233447] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"582.651446ms","start":"2026-04-20T05:08:36.310478Z","end":"2026-04-20T05:08:36.893129Z","steps":["trace[2132233447] 'process raft request' (duration: 372.046738ms)","trace[2132233447] 'compare' (duration: 208.929426ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:36.893245Z","caller":"traceutil/trace.go:171","msg":"trace[1703865995] linearizableReadLoop","detail":"{readStateIndex:8934; appliedIndex:8933; }","duration":"441.491335ms","start":"2026-04-20T05:08:36.451711Z","end":"2026-04-20T05:08:36.893202Z","steps":["trace[1703865995] 'read index received' (duration: 230.779885ms)","trace[1703865995] 'applied index is now lower than readState.Index' (duration: 210.7072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:08:36.893387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.68112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:08:36.893423Z","caller":"traceutil/trace.go:171","msg":"trace[1385577446] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8625; }","duration":"441.768021ms","start":"2026-04-20T05:08:36.451642Z","end":"2026-04-20T05:08:36.89341Z","steps":["trace[1385577446] 'agreement among raft nodes before linearized reading' (duration: 441.71362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:36.893452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:36.451614Z","time spent":"441.830122ms","remote":"127.0.0.1:33202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T05:08:36.893446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:36.310444Z","time spent":"582.868529ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:08:36.893514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.651171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:08:36.893567Z","caller":"traceutil/trace.go:171","msg":"trace[318069919] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8625; }","duration":"439.743523ms","start":"2026-04-20T05:08:36.453808Z","end":"2026-04-20T05:08:36.893552Z","steps":["trace[318069919] 'agreement among raft nodes before linearized reading' (duration: 439.59789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:36.893622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:36.453769Z","time spent":"439.839904ms","remote":"127.0.0.1:52628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-20T05:08:36.893721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.331284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:08:36.893758Z","caller":"traceutil/trace.go:171","msg":"trace[1018817966] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8625; }","duration":"233.427465ms","start":"2026-04-20T05:08:36.660321Z","end":"2026-04-20T05:08:36.893749Z","steps":["trace[1018817966] 'agreement among raft nodes before linearized reading' (duration: 233.359174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:36.893894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.440699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:08:36.893944Z","caller":"traceutil/trace.go:171","msg":"trace[1664292296] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:8625; }","duration":"430.540471ms","start":"2026-04-20T05:08:36.46339Z","end":"2026-04-20T05:08:36.893931Z","steps":["trace[1664292296] 'agreement among raft nodes before linearized reading' (duration: 430.462069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:36.893866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.042108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:08:36.894053Z","caller":"traceutil/trace.go:171","msg":"trace[177176799] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8625; }","duration":"409.273132ms","start":"2026-04-20T05:08:36.484754Z","end":"2026-04-20T05:08:36.894027Z","steps":["trace[177176799] 'agreement among raft nodes before linearized reading' (duration: 408.963377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:36.894179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:36.484742Z","time spent":"409.417475ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T05:08:36.893977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:36.463362Z","time spent":"430.606431ms","remote":"127.0.0.1:33206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-20T05:08:39.339498Z","caller":"traceutil/trace.go:171","msg":"trace[846070187] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"440.332614ms","start":"2026-04-20T05:08:38.899125Z","end":"2026-04-20T05:08:39.339457Z","steps":["trace[846070187] 'process raft request' (duration: 440.141711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:39.339787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:38.899101Z","time spent":"440.492216ms","remote":"127.0.0.1:52630","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-20T05:08:39.368361Z","caller":"traceutil/trace.go:171","msg":"trace[1976802045] linearizableReadLoop","detail":"{readStateIndex:8945; appliedIndex:8943; }","duration":"162.867065ms","start":"2026-04-20T05:08:39.205461Z","end":"2026-04-20T05:08:39.368328Z","steps":["trace[1976802045] 'read index received' (duration: 133.862474ms)","trace[1976802045] 'applied index is now lower than readState.Index' (duration: 29.003411ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:39.368537Z","caller":"traceutil/trace.go:171","msg":"trace[1352368698] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"469.384587ms","start":"2026-04-20T05:08:38.899125Z","end":"2026-04-20T05:08:39.368509Z","steps":["trace[1352368698] 'process raft request' (duration: 469.061091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:39.368626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.138941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:08:39.368694Z","caller":"traceutil/trace.go:171","msg":"trace[341663093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8634; }","duration":"163.285904ms","start":"2026-04-20T05:08:39.205391Z","end":"2026-04-20T05:08:39.368677Z","steps":["trace[341663093] 'agreement among raft nodes before linearized reading' (duration: 163.151901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:39.368721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:38.8991Z","time spent":"469.514339ms","remote":"127.0.0.1:52630","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-20T05:08:39.629844Z","caller":"traceutil/trace.go:171","msg":"trace[1132771451] linearizableReadLoop","detail":"{readStateIndex:8946; appliedIndex:8945; }","duration":"248.473395ms","start":"2026-04-20T05:08:39.381297Z","end":"2026-04-20T05:08:39.62977Z","steps":["trace[1132771451] 'read index received' (duration: 189.393001ms)","trace[1132771451] 'applied index is now lower than readState.Index' (duration: 59.079234ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:39.630009Z","caller":"traceutil/trace.go:171","msg":"trace[846937977] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"256.403675ms","start":"2026-04-20T05:08:39.373385Z","end":"2026-04-20T05:08:39.629788Z","steps":["trace[846937977] 'process raft request' (duration: 197.33221ms)","trace[846937977] 'compare' (duration: 58.655277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:08:39.630165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.831052ms","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-20T05:08:39.630284Z","caller":"traceutil/trace.go:171","msg":"trace[1627164451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8635; }","duration":"248.978915ms","start":"2026-04-20T05:08:39.381279Z","end":"2026-04-20T05:08:39.630258Z","steps":["trace[1627164451] 'agreement among raft nodes before linearized reading' (duration: 248.613329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:39.630432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.181427ms","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-20T05:08:39.630484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.142847ms","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-20T05:08:39.630557Z","caller":"traceutil/trace.go:171","msg":"trace[801401700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8635; }","duration":"167.35224ms","start":"2026-04-20T05:08:39.463175Z","end":"2026-04-20T05:08:39.630527Z","steps":["trace[801401700] 'agreement among raft nodes before linearized reading' (duration: 166.951132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:39.630611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.415396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cqcz2\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-20T05:08:39.630653Z","caller":"traceutil/trace.go:171","msg":"trace[1776420937] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cqcz2; range_end:; response_count:1; response_revision:8635; }","duration":"155.495777ms","start":"2026-04-20T05:08:39.475146Z","end":"2026-04-20T05:08:39.630641Z","steps":["trace[1776420937] 'agreement among raft nodes before linearized reading' (duration: 155.314534ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:39.630637Z","caller":"traceutil/trace.go:171","msg":"trace[783790142] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8635; }","duration":"166.383691ms","start":"2026-04-20T05:08:39.46421Z","end":"2026-04-20T05:08:39.630594Z","steps":["trace[783790142] 'agreement among raft nodes before linearized reading' (duration: 165.859651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:39.630732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.209501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T05:08:39.630775Z","caller":"traceutil/trace.go:171","msg":"trace[1721615509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8635; }","duration":"172.254801ms","start":"2026-04-20T05:08:39.458508Z","end":"2026-04-20T05:08:39.630763Z","steps":["trace[1721615509] 'agreement among raft nodes before linearized reading' (duration: 171.488337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:39.630779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.398037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T05:08:39.630832Z","caller":"traceutil/trace.go:171","msg":"trace[347464178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8635; }","duration":"170.517869ms","start":"2026-04-20T05:08:39.460301Z","end":"2026-04-20T05:08:39.630819Z","steps":["trace[347464178] 'agreement among raft nodes before linearized reading' (duration: 170.318606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:48.972565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.732127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:08:48.972699Z","caller":"traceutil/trace.go:171","msg":"trace[941611263] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"127.680617ms","start":"2026-04-20T05:08:48.844998Z","end":"2026-04-20T05:08:48.972678Z","steps":["trace[941611263] 'compare' (duration: 120.572225ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:49.513111Z","caller":"traceutil/trace.go:171","msg":"trace[1432544135] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"138.571251ms","start":"2026-04-20T05:08:49.374501Z","end":"2026-04-20T05:08:49.513072Z","steps":["trace[1432544135] 'process raft request' (duration: 138.377978ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:49.573814Z","caller":"traceutil/trace.go:171","msg":"trace[153031707] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"168.022972ms","start":"2026-04-20T05:08:49.405757Z","end":"2026-04-20T05:08:49.57378Z","steps":["trace[153031707] 'process raft request' (duration: 167.867479ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:49.87063Z","caller":"traceutil/trace.go:171","msg":"trace[1566547845] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"157.943523ms","start":"2026-04-20T05:08:49.712637Z","end":"2026-04-20T05:08:49.870581Z","steps":["trace[1566547845] 'process raft request' (duration: 157.693299ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:49.957718Z","caller":"traceutil/trace.go:171","msg":"trace[1715354258] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"241.77723ms","start":"2026-04-20T05:08:49.71589Z","end":"2026-04-20T05:08:49.957667Z","steps":["trace[1715354258] 'process raft request' (duration: 235.461953ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:49.95771Z","caller":"traceutil/trace.go:171","msg":"trace[698583891] linearizableReadLoop","detail":"{readStateIndex:9047; appliedIndex:9045; }","duration":"231.657432ms","start":"2026-04-20T05:08:49.726024Z","end":"2026-04-20T05:08:49.957682Z","steps":["trace[698583891] 'read index received' (duration: 144.321059ms)","trace[698583891] 'applied index is now lower than readState.Index' (duration: 87.335003ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:49.958598Z","caller":"traceutil/trace.go:171","msg":"trace[2084899229] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"128.812299ms","start":"2026-04-20T05:08:49.82967Z","end":"2026-04-20T05:08:49.958483Z","steps":["trace[2084899229] 'process raft request' (duration: 127.930122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:49.95887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.810773ms","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-20T05:08:49.958981Z","caller":"traceutil/trace.go:171","msg":"trace[1745577147] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8732; }","duration":"232.947325ms","start":"2026-04-20T05:08:49.726Z","end":"2026-04-20T05:08:49.958947Z","steps":["trace[1745577147] 'agreement among raft nodes before linearized reading' (duration: 232.678511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:49.958965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.047335ms","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-20T05:08:49.959026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.139263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cqcz2\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-20T05:08:49.959068Z","caller":"traceutil/trace.go:171","msg":"trace[89323780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8732; }","duration":"212.184507ms","start":"2026-04-20T05:08:49.746862Z","end":"2026-04-20T05:08:49.959047Z","steps":["trace[89323780] 'agreement among raft nodes before linearized reading' (duration: 211.902602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:49.958874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.663719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T05:08:49.959082Z","caller":"traceutil/trace.go:171","msg":"trace[2139841285] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cqcz2; range_end:; response_count:1; response_revision:8732; }","duration":"200.208044ms","start":"2026-04-20T05:08:49.758855Z","end":"2026-04-20T05:08:49.959063Z","steps":["trace[2139841285] 'agreement among raft nodes before linearized reading' (duration: 200.077212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:49.959109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.149471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"warn","ts":"2026-04-20T05:08:49.959117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.166998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T05:08:49.959165Z","caller":"traceutil/trace.go:171","msg":"trace[1622936658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8732; }","duration":"211.24731ms","start":"2026-04-20T05:08:49.747904Z","end":"2026-04-20T05:08:49.959151Z","steps":["trace[1622936658] 'agreement among raft nodes before linearized reading' (duration: 211.039285ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:49.959153Z","caller":"traceutil/trace.go:171","msg":"trace[865135522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8732; }","duration":"210.954094ms","start":"2026-04-20T05:08:49.748173Z","end":"2026-04-20T05:08:49.959128Z","steps":["trace[865135522] 'agreement among raft nodes before linearized reading' (duration: 210.573987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:49.959274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.343462ms","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-20T05:08:49.959186Z","caller":"traceutil/trace.go:171","msg":"trace[1546839411] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8732; }","duration":"140.246204ms","start":"2026-04-20T05:08:49.8189Z","end":"2026-04-20T05:08:49.959146Z","steps":["trace[1546839411] 'agreement among raft nodes before linearized reading' (duration: 139.936637ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:49.959379Z","caller":"traceutil/trace.go:171","msg":"trace[755015050] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8732; }","duration":"211.489285ms","start":"2026-04-20T05:08:49.747865Z","end":"2026-04-20T05:08:49.959354Z","steps":["trace[755015050] 'agreement among raft nodes before linearized reading' (duration: 211.122447ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:50.73196Z","caller":"traceutil/trace.go:171","msg":"trace[617992675] linearizableReadLoop","detail":"{readStateIndex:9060; appliedIndex:9059; }","duration":"250.254089ms","start":"2026-04-20T05:08:50.481673Z","end":"2026-04-20T05:08:50.731928Z","steps":["trace[617992675] 'read index received' (duration: 250.089096ms)","trace[617992675] 'applied index is now lower than readState.Index' (duration: 163.713µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:08:50.732327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.633117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-20T05:08:50.732429Z","caller":"traceutil/trace.go:171","msg":"trace[1876145696] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8744; }","duration":"250.81884ms","start":"2026-04-20T05:08:50.481597Z","end":"2026-04-20T05:08:50.732416Z","steps":["trace[1876145696] 'agreement among raft nodes before linearized reading' (duration: 250.559775ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:50.73237Z","caller":"traceutil/trace.go:171","msg":"trace[1519617685] transaction","detail":"{read_only:false; response_revision:8744; number_of_response:1; }","duration":"269.966149ms","start":"2026-04-20T05:08:50.462381Z","end":"2026-04-20T05:08:50.732347Z","steps":["trace[1519617685] 'process raft request' (duration: 269.348817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:09:20.242966Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249b.wal"} {"level":"info","ts":"2026-04-20T05:10:29.021887Z","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-20T05:10:29.02794Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T05:10:29.028019Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T05:12:01.067295Z","caller":"traceutil/trace.go:171","msg":"trace[307884418] transaction","detail":"{read_only:false; response_revision:10302; number_of_response:1; }","duration":"139.041341ms","start":"2026-04-20T05:12:00.92821Z","end":"2026-04-20T05:12:01.067251Z","steps":["trace[307884418] 'process raft request' (duration: 67.111391ms)","trace[307884418] 'compare' (duration: 71.632935ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:12:01.067319Z","caller":"traceutil/trace.go:171","msg":"trace[796910636] transaction","detail":"{read_only:false; response_revision:10303; number_of_response:1; }","duration":"138.019272ms","start":"2026-04-20T05:12:00.929251Z","end":"2026-04-20T05:12:01.06727Z","steps":["trace[796910636] 'process raft request' (duration: 137.888539ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:12:48.905982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8221} {"level":"info","ts":"2026-04-20T05:12:48.987398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8221,"took":"78.032525ms","hash":1193514320,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":26009600,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-20T05:12:48.987471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1193514320,"revision":8221,"compact-revision":6088} {"level":"info","ts":"2026-04-20T05:17:48.912465Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10706} {"level":"info","ts":"2026-04-20T05:17:49.006333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10706,"took":"91.50926ms","hash":435115755,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":21254144,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-20T05:17:49.006454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":435115755,"revision":10706,"compact-revision":8221}