{"level":"warn","ts":"2026-05-18T00:40:39.855945Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T00:40:39.856081Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-05-18T00:40:39.856175Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T00:40:39.856199Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-18T00:40:39.856225Z","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-05-18T00:40:39.856942Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-05-18T00:40:39.857036Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-05-18T00:40:39.861021Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.677091ms"} {"level":"info","ts":"2026-05-18T00:40:39.867536Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-05-18T00:40:39.867627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-05-18T00:40:39.867657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-05-18T00:40:39.86767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-18T00:40:39.867685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-05-18T00:40:39.867734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-05-18T00:40:39.873435Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-18T00:40:39.875735Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-18T00:40:39.877063Z","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-05-18T00:40:39.879593Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-18T00:40:39.879815Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-18T00:40:39.880042Z","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-05-18T00:40:39.880147Z","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-05-18T00:40:39.880165Z","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-05-18T00:40:39.882775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-05-18T00:40:39.882983Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-18T00:40:39.88533Z","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-05-18T00:40:39.885578Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-18T00:40:39.885613Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-18T00:40:39.885708Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-18T00:40:39.885724Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-18T00:40:40.268557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-05-18T00:40:40.268665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-18T00:40:40.268697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-05-18T00:40:40.268724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-05-18T00:40:40.268756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-18T00:40:40.268783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-05-18T00:40:40.268811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-18T00:40:40.277323Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:40:40.278791Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:40:40.278842Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:40:40.279201Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-18T00:40:40.279278Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-18T00:40:40.278794Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-05-18T00:40:40.280888Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:40:40.281006Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:40:40.281046Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:40:40.282536Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-18T00:40:40.2927Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-05-18T00:40:46.21433Z","caller":"traceutil/trace.go:171","msg":"trace[495450029] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"152.394694ms","start":"2026-05-18T00:40:46.061915Z","end":"2026-05-18T00:40:46.21431Z","steps":["trace[495450029] 'process raft request' (duration: 152.330372ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:46.278187Z","caller":"traceutil/trace.go:171","msg":"trace[1700131432] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:275; }","duration":"108.94342ms","start":"2026-05-18T00:40:46.169219Z","end":"2026-05-18T00:40:46.278162Z","steps":["trace[1700131432] 'read index received' (duration: 45.104325ms)","trace[1700131432] 'applied index is now lower than readState.Index' (duration: 63.837905ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:40:46.278229Z","caller":"traceutil/trace.go:171","msg":"trace[325944678] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"198.01988ms","start":"2026-05-18T00:40:46.080171Z","end":"2026-05-18T00:40:46.278191Z","steps":["trace[325944678] 'process raft request' (duration: 197.771045ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:46.278298Z","caller":"traceutil/trace.go:171","msg":"trace[1126515425] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"191.789959ms","start":"2026-05-18T00:40:46.086498Z","end":"2026-05-18T00:40:46.278288Z","steps":["trace[1126515425] 'process raft request' (duration: 191.611226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:46.278453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.216905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:40:46.27858Z","caller":"traceutil/trace.go:171","msg":"trace[886503960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:267; }","duration":"109.399198ms","start":"2026-05-18T00:40:46.169159Z","end":"2026-05-18T00:40:46.278558Z","steps":["trace[886503960] 'agreement among raft nodes before linearized reading' (duration: 109.214535ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:53.053428Z","caller":"traceutil/trace.go:171","msg":"trace[712592303] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"172.660053ms","start":"2026-05-18T00:40:52.880742Z","end":"2026-05-18T00:40:53.053402Z","steps":["trace[712592303] 'process raft request' (duration: 172.47384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:15.366853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.048675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:41:15.36697Z","caller":"traceutil/trace.go:171","msg":"trace[203767800] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"230.127006ms","start":"2026-05-18T00:41:15.136824Z","end":"2026-05-18T00:41:15.366951Z","steps":["trace[203767800] 'process raft request' (duration: 74.467358ms)","trace[203767800] 'compare' (duration: 154.922073ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:15.404417Z","caller":"traceutil/trace.go:171","msg":"trace[1674502664] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"202.888192ms","start":"2026-05-18T00:41:15.201511Z","end":"2026-05-18T00:41:15.404399Z","steps":["trace[1674502664] 'read index received' (duration: 9.732566ms)","trace[1674502664] 'applied index is now lower than readState.Index' (duration: 193.153236ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:15.404512Z","caller":"traceutil/trace.go:171","msg":"trace[903405488] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"254.778708ms","start":"2026-05-18T00:41:15.149724Z","end":"2026-05-18T00:41:15.404502Z","steps":["trace[903405488] 'process raft request' (duration: 254.569594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:15.404726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.204068ms","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-05-18T00:41:15.404768Z","caller":"traceutil/trace.go:171","msg":"trace[703348920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"203.277361ms","start":"2026-05-18T00:41:15.201481Z","end":"2026-05-18T00:41:15.404758Z","steps":["trace[703348920] 'agreement among raft nodes before linearized reading' (duration: 203.131797ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:15.886869Z","caller":"traceutil/trace.go:171","msg":"trace[949781461] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"298.992366ms","start":"2026-05-18T00:41:15.587771Z","end":"2026-05-18T00:41:15.886764Z","steps":["trace[949781461] 'process raft request' (duration: 212.626399ms)","trace[949781461] 'compare' (duration: 85.392818ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:15.892182Z","caller":"traceutil/trace.go:171","msg":"trace[1172654651] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"102.1942ms","start":"2026-05-18T00:41:15.789963Z","end":"2026-05-18T00:41:15.892157Z","steps":["trace[1172654651] 'process raft request' (duration: 98.118042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:30.660517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.230331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:41:30.660602Z","caller":"traceutil/trace.go:171","msg":"trace[2034047618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"223.369534ms","start":"2026-05-18T00:41:30.437216Z","end":"2026-05-18T00:41:30.660585Z","steps":["trace[2034047618] 'range keys from in-memory index tree' (duration: 223.144169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:30.660594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.407289ms","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-05-18T00:41:30.660693Z","caller":"traceutil/trace.go:171","msg":"trace[878502315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:730; }","duration":"117.578863ms","start":"2026-05-18T00:41:30.543094Z","end":"2026-05-18T00:41:30.660673Z","steps":["trace[878502315] 'range keys from in-memory index tree' (duration: 117.152274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:47.404317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.273382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:42:47.404505Z","caller":"traceutil/trace.go:171","msg":"trace[1846886510] linearizableReadLoop","detail":"{readStateIndex:1860; appliedIndex:1859; }","duration":"250.502619ms","start":"2026-05-18T00:42:47.153972Z","end":"2026-05-18T00:42:47.404475Z","steps":["trace[1846886510] 'read index received' (duration: 117.762µs)","trace[1846886510] 'applied index is now lower than readState.Index' (duration: 250.382697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:47.404804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.834595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-18T00:42:47.404871Z","caller":"traceutil/trace.go:171","msg":"trace[1815460285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1791; }","duration":"250.950048ms","start":"2026-05-18T00:42:47.153904Z","end":"2026-05-18T00:42:47.404854Z","steps":["trace[1815460285] 'agreement among raft nodes before linearized reading' (duration: 250.746863ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:47.405088Z","caller":"traceutil/trace.go:171","msg":"trace[324260589] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"322.86703ms","start":"2026-05-18T00:42:47.082112Z","end":"2026-05-18T00:42:47.404979Z","steps":["trace[324260589] 'process raft request' (duration: 66.550947ms)","trace[324260589] 'compare' (duration: 255.062518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:47.405538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:47.08203Z","time spent":"323.298458ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:42:47.411719Z","caller":"traceutil/trace.go:171","msg":"trace[1456740480] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"111.186433ms","start":"2026-05-18T00:42:47.300513Z","end":"2026-05-18T00:42:47.4117Z","steps":["trace[1456740480] 'process raft request' (duration: 111.122131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:47.412036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.293951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-18T00:42:47.412124Z","caller":"traceutil/trace.go:171","msg":"trace[820983041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1792; }","duration":"176.440454ms","start":"2026-05-18T00:42:47.235664Z","end":"2026-05-18T00:42:47.412104Z","steps":["trace[820983041] 'agreement among raft nodes before linearized reading' (duration: 176.150149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:32.532515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.829917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:43:32.532764Z","caller":"traceutil/trace.go:171","msg":"trace[1842890293] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"279.60932ms","start":"2026-05-18T00:43:32.253098Z","end":"2026-05-18T00:43:32.532707Z","steps":["trace[1842890293] 'process raft request' (duration: 146.460476ms)","trace[1842890293] 'compare' (duration: 132.44543ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:32.665615Z","caller":"traceutil/trace.go:171","msg":"trace[1079406814] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"128.397492ms","start":"2026-05-18T00:43:32.53718Z","end":"2026-05-18T00:43:32.665578Z","steps":["trace[1079406814] 'process raft request' (duration: 97.438213ms)","trace[1079406814] 'compare' (duration: 30.806115ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:43:33.192519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.852486ms","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-05-18T00:43:33.19272Z","caller":"traceutil/trace.go:171","msg":"trace[167690501] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2096; }","duration":"275.07222ms","start":"2026-05-18T00:43:32.917614Z","end":"2026-05-18T00:43:33.192686Z","steps":["trace[167690501] 'range keys from in-memory index tree' (duration: 274.825436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:33.192986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.423077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:43:33.193133Z","caller":"traceutil/trace.go:171","msg":"trace[795828186] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"242.179964ms","start":"2026-05-18T00:43:32.950928Z","end":"2026-05-18T00:43:33.193108Z","steps":["trace[795828186] 'process raft request' (duration: 102.522353ms)","trace[795828186] 'compare' (duration: 139.251243ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:38.484883Z","caller":"traceutil/trace.go:171","msg":"trace[1495137430] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"125.149981ms","start":"2026-05-18T00:43:38.359692Z","end":"2026-05-18T00:43:38.484842Z","steps":["trace[1495137430] 'process raft request' (duration: 124.60759ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:44:26.658529Z","caller":"traceutil/trace.go:171","msg":"trace[1635539283] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"181.100921ms","start":"2026-05-18T00:44:26.477398Z","end":"2026-05-18T00:44:26.658499Z","steps":["trace[1635539283] 'process raft request' (duration: 180.881847ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:44:26.659281Z","caller":"traceutil/trace.go:171","msg":"trace[91559484] transaction","detail":"{read_only:false; response_revision:2561; number_of_response:1; }","duration":"178.801967ms","start":"2026-05-18T00:44:26.480445Z","end":"2026-05-18T00:44:26.659247Z","steps":["trace[91559484] 'process raft request' (duration: 178.732186ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:01.164536Z","caller":"traceutil/trace.go:171","msg":"trace[387905037] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"268.38323ms","start":"2026-05-18T00:45:00.896112Z","end":"2026-05-18T00:45:01.164495Z","steps":["trace[387905037] 'process raft request' (duration: 268.184666ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:07.020127Z","caller":"traceutil/trace.go:171","msg":"trace[1257729574] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"105.192068ms","start":"2026-05-18T00:45:06.914905Z","end":"2026-05-18T00:45:07.020097Z","steps":["trace[1257729574] 'process raft request' (duration: 105.033025ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:47:36.82197Z","caller":"traceutil/trace.go:171","msg":"trace[563532646] linearizableReadLoop","detail":"{readStateIndex:3932; appliedIndex:3931; }","duration":"170.482714ms","start":"2026-05-18T00:47:36.651456Z","end":"2026-05-18T00:47:36.821938Z","steps":["trace[563532646] 'read index received' (duration: 170.289531ms)","trace[563532646] 'applied index is now lower than readState.Index' (duration: 191.703µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:47:36.822042Z","caller":"traceutil/trace.go:171","msg":"trace[437644007] transaction","detail":"{read_only:false; response_revision:3789; number_of_response:1; }","duration":"308.868651ms","start":"2026-05-18T00:47:36.513124Z","end":"2026-05-18T00:47:36.821993Z","steps":["trace[437644007] 'process raft request' (duration: 308.637077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:47:36.822259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.768699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xkvzd\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-18T00:47:36.822311Z","caller":"traceutil/trace.go:171","msg":"trace[908766734] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xkvzd; range_end:; response_count:1; response_revision:3789; }","duration":"170.896242ms","start":"2026-05-18T00:47:36.651401Z","end":"2026-05-18T00:47:36.822297Z","steps":["trace[908766734] 'agreement among raft nodes before linearized reading' (duration: 170.663788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:47:36.822316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:47:36.513075Z","time spent":"309.113766ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:47:37.112777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.178283ms","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-05-18T00:47:37.112885Z","caller":"traceutil/trace.go:171","msg":"trace[753099669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3789; }","duration":"113.353577ms","start":"2026-05-18T00:47:36.999508Z","end":"2026-05-18T00:47:37.112862Z","steps":["trace[753099669] 'range keys from in-memory index tree' (duration: 112.991019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:47:37.113175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.28931ms","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-05-18T00:47:37.113214Z","caller":"traceutil/trace.go:171","msg":"trace[1354110531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3789; }","duration":"107.333421ms","start":"2026-05-18T00:47:37.005869Z","end":"2026-05-18T00:47:37.113203Z","steps":["trace[1354110531] 'range keys from in-memory index tree' (duration: 107.096786ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:23.885186Z","caller":"traceutil/trace.go:171","msg":"trace[254920781] linearizableReadLoop","detail":"{readStateIndex:5570; appliedIndex:5569; }","duration":"140.397121ms","start":"2026-05-18T00:49:23.744762Z","end":"2026-05-18T00:49:23.885159Z","steps":["trace[254920781] 'read index received' (duration: 140.255217ms)","trace[254920781] 'applied index is now lower than readState.Index' (duration: 140.502µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:49:23.88525Z","caller":"traceutil/trace.go:171","msg":"trace[1108536975] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"165.366719ms","start":"2026-05-18T00:49:23.719846Z","end":"2026-05-18T00:49:23.885212Z","steps":["trace[1108536975] 'process raft request' (duration: 165.141724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:49:23.885563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.776177ms","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-05-18T00:49:23.885635Z","caller":"traceutil/trace.go:171","msg":"trace[1493819754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5369; }","duration":"140.91881ms","start":"2026-05-18T00:49:23.744701Z","end":"2026-05-18T00:49:23.88562Z","steps":["trace[1493819754] 'agreement among raft nodes before linearized reading' (duration: 140.613464ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:23.896591Z","caller":"traceutil/trace.go:171","msg":"trace[354233699] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"129.166249ms","start":"2026-05-18T00:49:23.767406Z","end":"2026-05-18T00:49:23.896572Z","steps":["trace[354233699] 'process raft request' (duration: 128.891753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:49:23.896598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.667574ms","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-05-18T00:49:23.896653Z","caller":"traceutil/trace.go:171","msg":"trace[630712720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5370; }","duration":"149.735475ms","start":"2026-05-18T00:49:23.746905Z","end":"2026-05-18T00:49:23.89664Z","steps":["trace[630712720] 'agreement among raft nodes before linearized reading' (duration: 149.570802ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:24.173496Z","caller":"traceutil/trace.go:171","msg":"trace[280510414] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"141.439989ms","start":"2026-05-18T00:49:24.032013Z","end":"2026-05-18T00:49:24.173453Z","steps":["trace[280510414] 'process raft request' (duration: 132.363119ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:24.173524Z","caller":"traceutil/trace.go:171","msg":"trace[1066015163] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"141.47376ms","start":"2026-05-18T00:49:24.03202Z","end":"2026-05-18T00:49:24.173494Z","steps":["trace[1066015163] 'process raft request' (duration: 141.363008ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:24.174163Z","caller":"traceutil/trace.go:171","msg":"trace[120827253] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"141.917398ms","start":"2026-05-18T00:49:24.032219Z","end":"2026-05-18T00:49:24.174136Z","steps":["trace[120827253] 'process raft request' (duration: 141.234694ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:24.174203Z","caller":"traceutil/trace.go:171","msg":"trace[771111679] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"141.885897ms","start":"2026-05-18T00:49:24.032286Z","end":"2026-05-18T00:49:24.174172Z","steps":["trace[771111679] 'process raft request' (duration: 141.534351ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:24.174462Z","caller":"traceutil/trace.go:171","msg":"trace[2023580324] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"142.00693ms","start":"2026-05-18T00:49:24.032415Z","end":"2026-05-18T00:49:24.174422Z","steps":["trace[2023580324] 'process raft request' (duration: 141.719845ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:24.174477Z","caller":"traceutil/trace.go:171","msg":"trace[1180661377] linearizableReadLoop","detail":"{readStateIndex:5580; appliedIndex:5574; }","duration":"141.972678ms","start":"2026-05-18T00:49:24.032483Z","end":"2026-05-18T00:49:24.174456Z","steps":["trace[1180661377] 'read index received' (duration: 27.272132ms)","trace[1180661377] 'applied index is now lower than readState.Index' (duration: 114.698846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:49:24.174722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.212033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-18T00:49:24.174743Z","caller":"traceutil/trace.go:171","msg":"trace[26298153] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"137.923952ms","start":"2026-05-18T00:49:24.036802Z","end":"2026-05-18T00:49:24.174726Z","steps":["trace[26298153] 'process raft request' (duration: 137.528655ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:24.174793Z","caller":"traceutil/trace.go:171","msg":"trace[560911093] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5380; }","duration":"142.346175ms","start":"2026-05-18T00:49:24.03243Z","end":"2026-05-18T00:49:24.174776Z","steps":["trace[560911093] 'agreement among raft nodes before linearized reading' (duration: 142.095921ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:50:40.431004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-05-18T00:50:40.508756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"75.943596ms","hash":2107169419,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":33714176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-18T00:50:40.50884Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2107169419,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-05-18T00:55:19.941347Z","caller":"traceutil/trace.go:171","msg":"trace[268188833] transaction","detail":"{read_only:false; response_revision:8151; number_of_response:1; }","duration":"196.225368ms","start":"2026-05-18T00:55:19.745073Z","end":"2026-05-18T00:55:19.941299Z","steps":["trace[268188833] 'process raft request' (duration: 195.997003ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:55:20.07204Z","caller":"traceutil/trace.go:171","msg":"trace[1247039099] linearizableReadLoop","detail":"{readStateIndex:8440; appliedIndex:8439; }","duration":"130.755658ms","start":"2026-05-18T00:55:19.941249Z","end":"2026-05-18T00:55:20.072004Z","steps":["trace[1247039099] 'read index received' (duration: 101.627481ms)","trace[1247039099] 'applied index is now lower than readState.Index' (duration: 29.126737ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:55:20.072067Z","caller":"traceutil/trace.go:171","msg":"trace[467442701] transaction","detail":"{read_only:false; response_revision:8152; number_of_response:1; }","duration":"187.383149ms","start":"2026-05-18T00:55:19.884633Z","end":"2026-05-18T00:55:20.072016Z","steps":["trace[467442701] 'process raft request' (duration: 158.227342ms)","trace[467442701] 'compare' (duration: 28.933362ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:55:20.072192Z","caller":"traceutil/trace.go:171","msg":"trace[2080781094] transaction","detail":"{read_only:false; response_revision:8153; number_of_response:1; }","duration":"126.855283ms","start":"2026-05-18T00:55:19.94533Z","end":"2026-05-18T00:55:20.072186Z","steps":["trace[2080781094] 'process raft request' (duration: 126.633788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:55:20.072451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.721772ms","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-05-18T00:55:20.072447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.153482ms","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-05-18T00:55:20.072505Z","caller":"traceutil/trace.go:171","msg":"trace[20614832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8153; }","duration":"202.841955ms","start":"2026-05-18T00:55:19.86965Z","end":"2026-05-18T00:55:20.072492Z","steps":["trace[20614832] 'agreement among raft nodes before linearized reading' (duration: 202.615251ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:55:20.072524Z","caller":"traceutil/trace.go:171","msg":"trace[699387553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8153; }","duration":"201.291495ms","start":"2026-05-18T00:55:19.871211Z","end":"2026-05-18T00:55:20.072503Z","steps":["trace[699387553] 'agreement among raft nodes before linearized reading' (duration: 200.916428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:55:32.450809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.165113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:55:32.451191Z","caller":"traceutil/trace.go:171","msg":"trace[892320849] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"292.767789ms","start":"2026-05-18T00:55:32.158399Z","end":"2026-05-18T00:55:32.451167Z","steps":["trace[892320849] 'process raft request' (duration: 83.125157ms)","trace[892320849] 'compare' (duration: 209.003439ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:55:32.488723Z","caller":"traceutil/trace.go:171","msg":"trace[1906377908] transaction","detail":"{read_only:false; response_revision:8221; number_of_response:1; }","duration":"329.554612ms","start":"2026-05-18T00:55:32.159118Z","end":"2026-05-18T00:55:32.488672Z","steps":["trace[1906377908] 'process raft request' (duration: 329.501061ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:55:32.488791Z","caller":"traceutil/trace.go:171","msg":"trace[2013798405] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"330.294945ms","start":"2026-05-18T00:55:32.158455Z","end":"2026-05-18T00:55:32.48875Z","steps":["trace[2013798405] 'process raft request' (duration: 330.00094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:55:32.488913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:55:32.158427Z","time spent":"330.431187ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:55:32.48895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:55:32.159096Z","time spent":"329.728285ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:55:40.442055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6066} {"level":"info","ts":"2026-05-18T00:55:40.543007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6066,"took":"98.791025ms","hash":2501000812,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":23654400,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-18T00:55:40.543098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2501000812,"revision":6066,"compact-revision":2984} {"level":"info","ts":"2026-05-18T00:56:00.978918Z","caller":"traceutil/trace.go:171","msg":"trace[1107640290] linearizableReadLoop","detail":"{readStateIndex:8729; appliedIndex:8728; }","duration":"118.673636ms","start":"2026-05-18T00:56:00.86021Z","end":"2026-05-18T00:56:00.978884Z","steps":["trace[1107640290] 'read index received' (duration: 117.615055ms)","trace[1107640290] 'applied index is now lower than readState.Index' (duration: 1.056801ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:56:00.979083Z","caller":"traceutil/trace.go:171","msg":"trace[1648667243] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"184.265458ms","start":"2026-05-18T00:56:00.794804Z","end":"2026-05-18T00:56:00.97907Z","steps":["trace[1648667243] 'process raft request' (duration: 183.034295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:00.979508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.263186ms","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-05-18T00:56:00.979614Z","caller":"traceutil/trace.go:171","msg":"trace[1086887239] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8429; }","duration":"119.44067ms","start":"2026-05-18T00:56:00.860146Z","end":"2026-05-18T00:56:00.979586Z","steps":["trace[1086887239] 'agreement among raft nodes before linearized reading' (duration: 119.125584ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:13.362223Z","caller":"traceutil/trace.go:171","msg":"trace[441455388] linearizableReadLoop","detail":"{readStateIndex:8840; appliedIndex:8839; }","duration":"118.287388ms","start":"2026-05-18T00:56:13.243906Z","end":"2026-05-18T00:56:13.362194Z","steps":["trace[441455388] 'read index received' (duration: 100.688152ms)","trace[441455388] 'applied index is now lower than readState.Index' (duration: 17.597836ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:56:13.362307Z","caller":"traceutil/trace.go:171","msg":"trace[153471035] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8535; }","duration":"156.087049ms","start":"2026-05-18T00:56:13.206182Z","end":"2026-05-18T00:56:13.362269Z","steps":["trace[153471035] 'process raft request' (duration: 138.436123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:13.362469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.537492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-h6khh\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-05-18T00:56:13.36253Z","caller":"traceutil/trace.go:171","msg":"trace[585111834] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-h6khh; range_end:; response_count:1; response_revision:8535; }","duration":"118.623435ms","start":"2026-05-18T00:56:13.243892Z","end":"2026-05-18T00:56:13.362515Z","steps":["trace[585111834] 'agreement among raft nodes before linearized reading' (duration: 118.41444ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:13.845759Z","caller":"traceutil/trace.go:171","msg":"trace[414129318] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"154.042421ms","start":"2026-05-18T00:56:13.69168Z","end":"2026-05-18T00:56:13.845722Z","steps":["trace[414129318] 'process raft request' (duration: 153.784205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:14.06712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.11155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:56:14.067232Z","caller":"traceutil/trace.go:171","msg":"trace[731881041] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8844; }","duration":"103.114619ms","start":"2026-05-18T00:56:13.964099Z","end":"2026-05-18T00:56:14.067213Z","steps":["trace[731881041] 'read index received' (duration: 71.641µs)","trace[731881041] 'applied index is now lower than readState.Index' (duration: 103.041938ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:56:14.067333Z","caller":"traceutil/trace.go:171","msg":"trace[1394307025] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"205.35536ms","start":"2026-05-18T00:56:13.861949Z","end":"2026-05-18T00:56:14.067305Z","steps":["trace[1394307025] 'process raft request' (duration: 81.951745ms)","trace[1394307025] 'compare' (duration: 122.990188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:56:14.06743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.337833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xkvzd\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-18T00:56:14.06747Z","caller":"traceutil/trace.go:171","msg":"trace[1993759847] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xkvzd; range_end:; response_count:1; response_revision:8539; }","duration":"103.417724ms","start":"2026-05-18T00:56:13.964042Z","end":"2026-05-18T00:56:14.06746Z","steps":["trace[1993759847] 'agreement among raft nodes before linearized reading' (duration: 103.263001ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:30.033451Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002353.wal"} {"level":"info","ts":"2026-05-18T00:58:22.125804Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T00:58:22.133007Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-05-18T00:58:22.133107Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-05-18T01:00:40.44876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8268} {"level":"info","ts":"2026-05-18T01:00:40.530529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8268,"took":"78.92575ms","hash":4169121050,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":26357760,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-18T01:00:40.530624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4169121050,"revision":8268,"compact-revision":6066} {"level":"warn","ts":"2026-05-18T01:01:33.399765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.11195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T01:01:33.399898Z","caller":"traceutil/trace.go:171","msg":"trace[196874400] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:11240; }","duration":"129.329014ms","start":"2026-05-18T01:01:33.270543Z","end":"2026-05-18T01:01:33.399872Z","steps":["trace[196874400] 'count revisions from in-memory index tree' (duration: 128.988019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:01:33.399984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.069748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T01:01:33.400122Z","caller":"traceutil/trace.go:171","msg":"trace[692768810] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:11240; }","duration":"155.328052ms","start":"2026-05-18T01:01:33.244764Z","end":"2026-05-18T01:01:33.400092Z","steps":["trace[692768810] 'count revisions from in-memory index tree' (duration: 154.908204ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:05:40.457036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10909} {"level":"info","ts":"2026-05-18T01:05:40.54284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10909,"took":"82.510258ms","hash":241587506,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":28094464,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-18T01:05:40.542911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":241587506,"revision":10909,"compact-revision":8268} {"level":"info","ts":"2026-05-18T01:07:23.253775Z","caller":"traceutil/trace.go:171","msg":"trace[1731440819] linearizableReadLoop","detail":"{readStateIndex:14133; appliedIndex:14132; }","duration":"137.799085ms","start":"2026-05-18T01:07:23.11594Z","end":"2026-05-18T01:07:23.25374Z","steps":["trace[1731440819] 'read index received' (duration: 88.428155ms)","trace[1731440819] 'applied index is now lower than readState.Index' (duration: 49.36988ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:07:23.253914Z","caller":"traceutil/trace.go:171","msg":"trace[1783769437] transaction","detail":"{read_only:false; response_revision:13668; number_of_response:1; }","duration":"246.29616ms","start":"2026-05-18T01:07:23.007587Z","end":"2026-05-18T01:07:23.253883Z","steps":["trace[1783769437] 'process raft request' (duration: 196.768167ms)","trace[1783769437] 'compare' (duration: 49.269708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:07:23.254052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.08973ms","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-05-18T01:07:23.25413Z","caller":"traceutil/trace.go:171","msg":"trace[1039623728] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13668; }","duration":"138.179282ms","start":"2026-05-18T01:07:23.115932Z","end":"2026-05-18T01:07:23.254112Z","steps":["trace[1039623728] 'agreement among raft nodes before linearized reading' (duration: 137.954408ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:07:39.667818Z","caller":"traceutil/trace.go:171","msg":"trace[1578309019] linearizableReadLoop","detail":"{readStateIndex:14240; appliedIndex:14239; }","duration":"134.046515ms","start":"2026-05-18T01:07:39.533743Z","end":"2026-05-18T01:07:39.66779Z","steps":["trace[1578309019] 'read index received' (duration: 41.93712ms)","trace[1578309019] 'applied index is now lower than readState.Index' (duration: 92.108045ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:07:39.667863Z","caller":"traceutil/trace.go:171","msg":"trace[990522554] transaction","detail":"{read_only:false; response_revision:13770; number_of_response:1; }","duration":"318.860047ms","start":"2026-05-18T01:07:39.348959Z","end":"2026-05-18T01:07:39.667819Z","steps":["trace[990522554] 'process raft request' (duration: 226.839773ms)","trace[990522554] 'compare' (duration: 91.8298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:07:39.668045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.26986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T01:07:39.66817Z","caller":"traceutil/trace.go:171","msg":"trace[2142851708] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:13770; }","duration":"134.419662ms","start":"2026-05-18T01:07:39.533723Z","end":"2026-05-18T01:07:39.668142Z","steps":["trace[2142851708] 'agreement among raft nodes before linearized reading' (duration: 134.201238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:07:39.668176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:07:39.348944Z","time spent":"319.0858ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:09:27.733733Z","caller":"traceutil/trace.go:171","msg":"trace[600305658] transaction","detail":"{read_only:false; response_revision:14889; number_of_response:1; }","duration":"115.410284ms","start":"2026-05-18T01:09:27.618283Z","end":"2026-05-18T01:09:27.733693Z","steps":["trace[600305658] 'process raft request' (duration: 115.193741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:09:28.669948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.962483ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-18T01:10:40.463594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12998} {"level":"info","ts":"2026-05-18T01:10:40.546495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12998,"took":"79.076551ms","hash":3305735859,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":30707712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T01:10:40.546561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3305735859,"revision":12998,"compact-revision":10909} {"level":"warn","ts":"2026-05-18T01:15:00.546567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.588319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-d8szm\" ","response":"range_response_count:1 size:9793"} {"level":"info","ts":"2026-05-18T01:15:00.546645Z","caller":"traceutil/trace.go:171","msg":"trace[1894858528] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-d8szm; range_end:; response_count:1; response_revision:17390; }","duration":"129.705212ms","start":"2026-05-18T01:15:00.416925Z","end":"2026-05-18T01:15:00.54663Z","steps":["trace[1894858528] 'range keys from in-memory index tree' (duration: 129.452427ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:07.093776Z","caller":"traceutil/trace.go:171","msg":"trace[215793382] transaction","detail":"{read_only:false; response_revision:17428; number_of_response:1; }","duration":"117.355228ms","start":"2026-05-18T01:15:06.976388Z","end":"2026-05-18T01:15:07.093743Z","steps":["trace[215793382] 'process raft request' (duration: 117.098363ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:07.093772Z","caller":"traceutil/trace.go:171","msg":"trace[1027105826] transaction","detail":"{read_only:false; response_revision:17429; number_of_response:1; }","duration":"116.994071ms","start":"2026-05-18T01:15:06.976759Z","end":"2026-05-18T01:15:07.093753Z","steps":["trace[1027105826] 'process raft request' (duration: 116.872969ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:07.094113Z","caller":"traceutil/trace.go:171","msg":"trace[27914554] transaction","detail":"{read_only:false; response_revision:17430; number_of_response:1; }","duration":"115.771969ms","start":"2026-05-18T01:15:06.978328Z","end":"2026-05-18T01:15:07.0941Z","steps":["trace[27914554] 'process raft request' (duration: 115.353041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:07.525583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.14714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:15:07.526171Z","caller":"traceutil/trace.go:171","msg":"trace[1948896328] transaction","detail":"{read_only:false; response_revision:17431; number_of_response:1; }","duration":"497.607824ms","start":"2026-05-18T01:15:07.02851Z","end":"2026-05-18T01:15:07.526118Z","steps":["trace[1948896328] 'process raft request' (duration: 304.78969ms)","trace[1948896328] 'compare' (duration: 191.935357ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:15:07.526261Z","caller":"traceutil/trace.go:171","msg":"trace[372021029] transaction","detail":"{read_only:false; response_revision:17432; number_of_response:1; }","duration":"495.30451ms","start":"2026-05-18T01:15:07.030908Z","end":"2026-05-18T01:15:07.526212Z","steps":["trace[372021029] 'process raft request' (duration: 494.81559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:07.5264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:15:07.030891Z","time spent":"495.444093ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:15:07.5264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:15:07.028494Z","time spent":"497.774487ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:15:07.5266Z","caller":"traceutil/trace.go:171","msg":"trace[1516755060] linearizableReadLoop","detail":"{readStateIndex:18025; appliedIndex:18022; }","duration":"380.909438ms","start":"2026-05-18T01:15:07.145558Z","end":"2026-05-18T01:15:07.526467Z","steps":["trace[1516755060] 'read index received' (duration: 187.743838ms)","trace[1516755060] 'applied index is now lower than readState.Index' (duration: 193.16151ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:15:07.526611Z","caller":"traceutil/trace.go:171","msg":"trace[1463820594] transaction","detail":"{read_only:false; response_revision:17433; number_of_response:1; }","duration":"426.555111ms","start":"2026-05-18T01:15:07.099997Z","end":"2026-05-18T01:15:07.526553Z","steps":["trace[1463820594] 'process raft request' (duration: 425.807756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:07.526769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:15:07.099945Z","time spent":"426.796545ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:15:07.52693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.571951ms","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:6"} {"level":"warn","ts":"2026-05-18T01:15:07.526973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.391429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-18T01:15:07.526952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.369446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-th9n7\" ","response":"range_response_count:1 size:20869"} {"level":"info","ts":"2026-05-18T01:15:07.527025Z","caller":"traceutil/trace.go:171","msg":"trace[303340361] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17433; }","duration":"162.517141ms","start":"2026-05-18T01:15:07.364493Z","end":"2026-05-18T01:15:07.52701Z","steps":["trace[303340361] 'agreement among raft nodes before linearized reading' (duration: 162.310117ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:07.527068Z","caller":"traceutil/trace.go:171","msg":"trace[1758011374] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-th9n7; range_end:; response_count:1; response_revision:17433; }","duration":"381.524849ms","start":"2026-05-18T01:15:07.145511Z","end":"2026-05-18T01:15:07.527035Z","steps":["trace[1758011374] 'agreement among raft nodes before linearized reading' (duration: 381.119142ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:07.527024Z","caller":"traceutil/trace.go:171","msg":"trace[1908894831] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:17433; }","duration":"300.750854ms","start":"2026-05-18T01:15:07.226249Z","end":"2026-05-18T01:15:07.527Z","steps":["trace[1908894831] 'agreement among raft nodes before linearized reading' (duration: 300.55655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:07.527127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:15:07.145498Z","time spent":"381.620251ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20893,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-th9n7\" "} {"level":"warn","ts":"2026-05-18T01:15:07.527181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:15:07.226226Z","time spent":"300.904426ms","remote":"127.0.0.1:45388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:15:15.88232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.397231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2644"} {"level":"info","ts":"2026-05-18T01:15:15.88245Z","caller":"traceutil/trace.go:171","msg":"trace[279784860] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:17579; }","duration":"106.537593ms","start":"2026-05-18T01:15:15.775889Z","end":"2026-05-18T01:15:15.882427Z","steps":["trace[279784860] 'agreement among raft nodes before linearized reading' (duration: 106.283499ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:15.882805Z","caller":"traceutil/trace.go:171","msg":"trace[996983185] transaction","detail":"{read_only:false; response_revision:17579; number_of_response:1; }","duration":"101.341095ms","start":"2026-05-18T01:15:15.781452Z","end":"2026-05-18T01:15:15.882793Z","steps":["trace[996983185] 'process raft request' (duration: 100.54383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:15.883014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.54459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-th9n7-api\" ","response":"range_response_count:1 size:4292"} {"level":"info","ts":"2026-05-18T01:15:15.883052Z","caller":"traceutil/trace.go:171","msg":"trace[33553708] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-th9n7-api; range_end:; response_count:1; response_revision:17579; }","duration":"100.593031ms","start":"2026-05-18T01:15:15.782448Z","end":"2026-05-18T01:15:15.883041Z","steps":["trace[33553708] 'agreement among raft nodes before linearized reading' (duration: 100.486349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:15.883273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.997436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-th9n7-api-fmbll\" ","response":"range_response_count:1 size:2537"} {"level":"info","ts":"2026-05-18T01:15:15.883308Z","caller":"traceutil/trace.go:171","msg":"trace[1889089717] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-th9n7-api-fmbll; range_end:; response_count:1; response_revision:17579; }","duration":"103.054247ms","start":"2026-05-18T01:15:15.780245Z","end":"2026-05-18T01:15:15.883299Z","steps":["trace[1889089717] 'agreement among raft nodes before linearized reading' (duration: 102.941605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:15.883479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.216389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-05-18T01:15:15.883517Z","caller":"traceutil/trace.go:171","msg":"trace[955276660] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:17579; }","duration":"105.26472ms","start":"2026-05-18T01:15:15.778243Z","end":"2026-05-18T01:15:15.883508Z","steps":["trace[955276660] 'agreement among raft nodes before linearized reading' (duration: 105.175477ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:40.471538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15361} {"level":"info","ts":"2026-05-18T01:15:40.577911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15361,"took":"102.374255ms","hash":2029587067,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":32387072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T01:15:40.577978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2029587067,"revision":15361,"compact-revision":12998} {"level":"info","ts":"2026-05-18T01:19:07.734238Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T01:19:07.742464Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-05-18T01:19:07.742626Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-05-18T01:20:40.477516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17769} {"level":"info","ts":"2026-05-18T01:20:40.591572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17769,"took":"109.318253ms","hash":3070648460,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":33431552,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-18T01:20:40.591633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3070648460,"revision":17769,"compact-revision":15361} {"level":"warn","ts":"2026-05-18T01:20:50.298437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.521325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:20:50.298644Z","caller":"traceutil/trace.go:171","msg":"trace[1623902332] transaction","detail":"{read_only:false; response_revision:20091; number_of_response:1; }","duration":"228.144716ms","start":"2026-05-18T01:20:50.070435Z","end":"2026-05-18T01:20:50.29858Z","steps":["trace[1623902332] 'process raft request' (duration: 98.315435ms)","trace[1623902332] 'compare' (duration: 129.153777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:20:50.571082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.192216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:20:50.571202Z","caller":"traceutil/trace.go:171","msg":"trace[735365218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20092; }","duration":"131.404709ms","start":"2026-05-18T01:20:50.439771Z","end":"2026-05-18T01:20:50.571175Z","steps":["trace[735365218] 'range keys from in-memory index tree' (duration: 131.082573ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:25:40.484199Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20042} {"level":"info","ts":"2026-05-18T01:25:40.595725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20042,"took":"106.567491ms","hash":97017957,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":41971712,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-18T01:25:40.595809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":97017957,"revision":20042,"compact-revision":17769} {"level":"info","ts":"2026-05-18T01:27:49.179036Z","caller":"traceutil/trace.go:171","msg":"trace[1335697369] transaction","detail":"{read_only:false; response_revision:24452; number_of_response:1; }","duration":"194.049562ms","start":"2026-05-18T01:27:48.984958Z","end":"2026-05-18T01:27:49.179007Z","steps":["trace[1335697369] 'process raft request' (duration: 193.867838ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:49.199414Z","caller":"traceutil/trace.go:171","msg":"trace[1666548552] linearizableReadLoop","detail":"{readStateIndex:25265; appliedIndex:25264; }","duration":"155.618496ms","start":"2026-05-18T01:27:49.04376Z","end":"2026-05-18T01:27:49.199379Z","steps":["trace[1666548552] 'read index received' (duration: 135.800882ms)","trace[1666548552] 'applied index is now lower than readState.Index' (duration: 19.816134ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:49.199499Z","caller":"traceutil/trace.go:171","msg":"trace[2125109166] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"214.421367ms","start":"2026-05-18T01:27:48.985043Z","end":"2026-05-18T01:27:49.199464Z","steps":["trace[2125109166] 'process raft request' (duration: 214.156012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:49.19971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.917932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-18T01:27:49.199758Z","caller":"traceutil/trace.go:171","msg":"trace[1216360800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24453; }","duration":"156.048355ms","start":"2026-05-18T01:27:49.043697Z","end":"2026-05-18T01:27:49.199746Z","steps":["trace[1216360800] 'agreement among raft nodes before linearized reading' (duration: 155.846381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:49.199757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.892141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-18T01:27:49.199996Z","caller":"traceutil/trace.go:171","msg":"trace[392254058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24453; }","duration":"156.147667ms","start":"2026-05-18T01:27:49.043817Z","end":"2026-05-18T01:27:49.199964Z","steps":["trace[392254058] 'agreement among raft nodes before linearized reading' (duration: 155.807319ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:49.38807Z","caller":"traceutil/trace.go:171","msg":"trace[1568801876] transaction","detail":"{read_only:false; response_revision:24455; number_of_response:1; }","duration":"181.426044ms","start":"2026-05-18T01:27:49.206626Z","end":"2026-05-18T01:27:49.388052Z","steps":["trace[1568801876] 'process raft request' (duration: 108.61182ms)","trace[1568801876] 'compare' (duration: 72.691592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:49.724945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.043421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:27:49.725088Z","caller":"traceutil/trace.go:171","msg":"trace[1032429140] transaction","detail":"{read_only:false; response_revision:24456; number_of_response:1; }","duration":"332.335952ms","start":"2026-05-18T01:27:49.392722Z","end":"2026-05-18T01:27:49.725058Z","steps":["trace[1032429140] 'process raft request' (duration: 208.038346ms)","trace[1032429140] 'compare' (duration: 123.870708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:49.725169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:49.392695Z","time spent":"332.440765ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:50.0214Z","caller":"traceutil/trace.go:171","msg":"trace[1370454273] linearizableReadLoop","detail":"{readStateIndex:25269; appliedIndex:25267; }","duration":"627.238087ms","start":"2026-05-18T01:27:49.394137Z","end":"2026-05-18T01:27:50.021375Z","steps":["trace[1370454273] 'read index received' (duration: 206.64647ms)","trace[1370454273] 'applied index is now lower than readState.Index' (duration: 420.590327ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:50.021435Z","caller":"traceutil/trace.go:171","msg":"trace[1439811092] transaction","detail":"{read_only:false; response_revision:24457; number_of_response:1; }","duration":"628.621073ms","start":"2026-05-18T01:27:49.392768Z","end":"2026-05-18T01:27:50.021389Z","steps":["trace[1439811092] 'process raft request' (duration: 596.638449ms)","trace[1439811092] 'compare' (duration: 31.454005ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:50.021506Z","caller":"traceutil/trace.go:171","msg":"trace[1344412921] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"624.70716ms","start":"2026-05-18T01:27:49.396757Z","end":"2026-05-18T01:27:50.021464Z","steps":["trace[1344412921] 'process raft request' (duration: 624.561357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.021643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.485602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18366"} {"level":"warn","ts":"2026-05-18T01:27:50.02164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:49.392739Z","time spent":"628.787897ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:50.021673Z","caller":"traceutil/trace.go:171","msg":"trace[2090221763] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24458; }","duration":"627.568113ms","start":"2026-05-18T01:27:49.394097Z","end":"2026-05-18T01:27:50.021665Z","steps":["trace[2090221763] 'agreement among raft nodes before linearized reading' (duration: 627.40182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.021754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:49.396744Z","time spent":"624.878762ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:27:50.021889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:49.394087Z","time spent":"627.712967ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18390,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-18T01:27:50.112196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.242745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:50.112299Z","caller":"traceutil/trace.go:171","msg":"trace[263683577] transaction","detail":"{read_only:false; response_revision:24459; number_of_response:1; }","duration":"222.997938ms","start":"2026-05-18T01:27:49.889277Z","end":"2026-05-18T01:27:50.112275Z","steps":["trace[263683577] 'process raft request' (duration: 222.54065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.112359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.609091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:50.112396Z","caller":"traceutil/trace.go:171","msg":"trace[1494042493] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:24459; }","duration":"461.703803ms","start":"2026-05-18T01:27:49.650683Z","end":"2026-05-18T01:27:50.112387Z","steps":["trace[1494042493] 'agreement among raft nodes before linearized reading' (duration: 461.643852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.112367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.485781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:27:50.11243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:49.650652Z","time spent":"461.768985ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:27:50.112473Z","caller":"traceutil/trace.go:171","msg":"trace[1029908060] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24459; }","duration":"140.644034ms","start":"2026-05-18T01:27:49.971805Z","end":"2026-05-18T01:27:50.112449Z","steps":["trace[1029908060] 'agreement among raft nodes before linearized reading' (duration: 140.46245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.112664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.922603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-18T01:27:50.112685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.714461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:50.112707Z","caller":"traceutil/trace.go:171","msg":"trace[225428906] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24459; }","duration":"270.013035ms","start":"2026-05-18T01:27:49.842681Z","end":"2026-05-18T01:27:50.112694Z","steps":["trace[225428906] 'agreement among raft nodes before linearized reading' (duration: 269.873063ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:50.112735Z","caller":"traceutil/trace.go:171","msg":"trace[1838449961] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24459; }","duration":"596.802832ms","start":"2026-05-18T01:27:49.515905Z","end":"2026-05-18T01:27:50.112708Z","steps":["trace[1838449961] 'agreement among raft nodes before linearized reading' (duration: 596.719271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.112773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:49.515885Z","time spent":"596.879034ms","remote":"127.0.0.1:38502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:27:50.112299Z","caller":"traceutil/trace.go:171","msg":"trace[388646795] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24459; }","duration":"434.410908ms","start":"2026-05-18T01:27:49.677868Z","end":"2026-05-18T01:27:50.112279Z","steps":["trace[388646795] 'agreement among raft nodes before linearized reading' (duration: 434.201754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.112891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:49.677846Z","time spent":"435.03055ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:27:50.112226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.92029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:27:50.113053Z","caller":"traceutil/trace.go:171","msg":"trace[2031410486] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24459; }","duration":"433.831037ms","start":"2026-05-18T01:27:49.67921Z","end":"2026-05-18T01:27:50.113041Z","steps":["trace[2031410486] 'agreement among raft nodes before linearized reading' (duration: 432.853249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.113085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:49.679181Z","time spent":"433.894529ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":32,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:27:50.190711Z","caller":"traceutil/trace.go:171","msg":"trace[1182175740] transaction","detail":"{read_only:false; response_revision:24460; number_of_response:1; }","duration":"146.74426ms","start":"2026-05-18T01:27:50.043929Z","end":"2026-05-18T01:27:50.190673Z","steps":["trace[1182175740] 'process raft request' (duration: 108.257134ms)","trace[1182175740] 'compare' (duration: 38.291042ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:54.882873Z","caller":"traceutil/trace.go:171","msg":"trace[1593793884] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"126.590829ms","start":"2026-05-18T01:27:54.756253Z","end":"2026-05-18T01:27:54.882844Z","steps":["trace[1593793884] 'process raft request' (duration: 109.076199ms)","trace[1593793884] 'compare' (duration: 17.162453ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:55.098194Z","caller":"traceutil/trace.go:171","msg":"trace[226742583] linearizableReadLoop","detail":"{readStateIndex:25309; appliedIndex:25306; }","duration":"258.335485ms","start":"2026-05-18T01:27:54.839826Z","end":"2026-05-18T01:27:55.098161Z","steps":["trace[226742583] 'read index received' (duration: 25.43201ms)","trace[226742583] 'applied index is now lower than readState.Index' (duration: 232.902085ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:55.098383Z","caller":"traceutil/trace.go:171","msg":"trace[979494293] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"262.677236ms","start":"2026-05-18T01:27:54.835686Z","end":"2026-05-18T01:27:55.098364Z","steps":["trace[979494293] 'process raft request' (duration: 262.34179ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:55.098251Z","caller":"traceutil/trace.go:171","msg":"trace[1235668493] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"339.574908ms","start":"2026-05-18T01:27:54.758618Z","end":"2026-05-18T01:27:55.098193Z","steps":["trace[1235668493] 'process raft request' (duration: 291.876278ms)","trace[1235668493] 'compare' (duration: 47.318213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:55.098641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.793433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-18T01:27:55.098631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.190266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:27:55.098696Z","caller":"traceutil/trace.go:171","msg":"trace[1225360458] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24496; }","duration":"258.915756ms","start":"2026-05-18T01:27:54.839768Z","end":"2026-05-18T01:27:55.098684Z","steps":["trace[1225360458] 'agreement among raft nodes before linearized reading' (duration: 258.680641ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:55.098711Z","caller":"traceutil/trace.go:171","msg":"trace[984514617] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24496; }","duration":"113.323528ms","start":"2026-05-18T01:27:54.985366Z","end":"2026-05-18T01:27:55.09869Z","steps":["trace[984514617] 'agreement among raft nodes before linearized reading' (duration: 113.097503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:55.098844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:54.758597Z","time spent":"340.071298ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:55.334521Z","caller":"traceutil/trace.go:171","msg":"trace[6478176] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"108.431026ms","start":"2026-05-18T01:27:55.226066Z","end":"2026-05-18T01:27:55.334497Z","steps":["trace[6478176] 'process raft request' (duration: 108.373435ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:55.33482Z","caller":"traceutil/trace.go:171","msg":"trace[602658531] transaction","detail":"{read_only:false; response_revision:24498; number_of_response:1; }","duration":"132.946799ms","start":"2026-05-18T01:27:55.201865Z","end":"2026-05-18T01:27:55.334812Z","steps":["trace[602658531] 'process raft request' (duration: 114.888208ms)","trace[602658531] 'compare' (duration: 17.217666ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:55.334904Z","caller":"traceutil/trace.go:171","msg":"trace[1262441227] linearizableReadLoop","detail":"{readStateIndex:25311; appliedIndex:25310; }","duration":"116.318225ms","start":"2026-05-18T01:27:55.218575Z","end":"2026-05-18T01:27:55.334893Z","steps":["trace[1262441227] 'read index received' (duration: 98.195323ms)","trace[1262441227] 'applied index is now lower than readState.Index' (duration: 18.121632ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:55.335112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.517599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-18T01:27:55.33514Z","caller":"traceutil/trace.go:171","msg":"trace[1348259358] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24499; }","duration":"116.57177ms","start":"2026-05-18T01:27:55.218562Z","end":"2026-05-18T01:27:55.335133Z","steps":["trace[1348259358] 'agreement among raft nodes before linearized reading' (duration: 116.419257ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:55.630848Z","caller":"traceutil/trace.go:171","msg":"trace[1903807895] transaction","detail":"{read_only:false; response_revision:24501; number_of_response:1; }","duration":"175.567854ms","start":"2026-05-18T01:27:55.455239Z","end":"2026-05-18T01:27:55.630807Z","steps":["trace[1903807895] 'process raft request' (duration: 141.392628ms)","trace[1903807895] 'compare' (duration: 33.961521ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:55.630853Z","caller":"traceutil/trace.go:171","msg":"trace[1799768155] linearizableReadLoop","detail":"{readStateIndex:25314; appliedIndex:25313; }","duration":"118.690271ms","start":"2026-05-18T01:27:55.512132Z","end":"2026-05-18T01:27:55.630823Z","steps":["trace[1799768155] 'read index received' (duration: 84.728629ms)","trace[1799768155] 'applied index is now lower than readState.Index' (duration: 33.960432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:55.631128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.977576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:55.631192Z","caller":"traceutil/trace.go:171","msg":"trace[232754500] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24502; }","duration":"119.109318ms","start":"2026-05-18T01:27:55.512072Z","end":"2026-05-18T01:27:55.631181Z","steps":["trace[232754500] 'agreement among raft nodes before linearized reading' (duration: 118.843474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:55.944881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.722579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-18T01:27:55.944978Z","caller":"traceutil/trace.go:171","msg":"trace[283163338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24502; }","duration":"191.887131ms","start":"2026-05-18T01:27:55.753069Z","end":"2026-05-18T01:27:55.944957Z","steps":["trace[283163338] 'range keys from in-memory index tree' (duration: 191.504794ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:56.272284Z","caller":"traceutil/trace.go:171","msg":"trace[1233223693] transaction","detail":"{read_only:false; response_revision:24504; number_of_response:1; }","duration":"130.641316ms","start":"2026-05-18T01:27:56.141609Z","end":"2026-05-18T01:27:56.27225Z","steps":["trace[1233223693] 'process raft request' (duration: 130.393811ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:56.555875Z","caller":"traceutil/trace.go:171","msg":"trace[220940707] transaction","detail":"{read_only:false; response_revision:24505; number_of_response:1; }","duration":"413.32521ms","start":"2026-05-18T01:27:56.142519Z","end":"2026-05-18T01:27:56.555844Z","steps":["trace[220940707] 'process raft request' (duration: 413.050015ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:56.556039Z","caller":"traceutil/trace.go:171","msg":"trace[1548197461] linearizableReadLoop","detail":"{readStateIndex:25318; appliedIndex:25316; }","duration":"407.696004ms","start":"2026-05-18T01:27:56.148313Z","end":"2026-05-18T01:27:56.556009Z","steps":["trace[1548197461] 'read index received' (duration: 123.805567ms)","trace[1548197461] 'applied index is now lower than readState.Index' (duration: 283.887637ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:56.556073Z","caller":"traceutil/trace.go:171","msg":"trace[553903624] transaction","detail":"{read_only:false; response_revision:24506; number_of_response:1; }","duration":"339.915534ms","start":"2026-05-18T01:27:56.216124Z","end":"2026-05-18T01:27:56.556039Z","steps":["trace[553903624] 'process raft request' (duration: 339.664679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:56.55628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.95094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"warn","ts":"2026-05-18T01:27:56.55628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:56.216092Z","time spent":"340.064257ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:56.556319Z","caller":"traceutil/trace.go:171","msg":"trace[1723184756] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:24506; }","duration":"408.044982ms","start":"2026-05-18T01:27:56.14826Z","end":"2026-05-18T01:27:56.556305Z","steps":["trace[1723184756] 'agreement among raft nodes before linearized reading' (duration: 407.850607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:56.556346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:56.148237Z","time spent":"408.101712ms","remote":"127.0.0.1:41622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1886,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"warn","ts":"2026-05-18T01:27:56.556408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.968591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18366"} {"level":"info","ts":"2026-05-18T01:27:56.556506Z","caller":"traceutil/trace.go:171","msg":"trace[323321459] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24506; }","duration":"389.130924ms","start":"2026-05-18T01:27:56.167353Z","end":"2026-05-18T01:27:56.556484Z","steps":["trace[323321459] 'agreement among raft nodes before linearized reading' (duration: 388.881119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:56.556558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:56.16733Z","time spent":"389.217695ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18390,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-05-18T01:27:56.556748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:56.142491Z","time spent":"414.175236ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:57.235725Z","caller":"traceutil/trace.go:171","msg":"trace[448864631] transaction","detail":"{read_only:false; response_revision:24509; number_of_response:1; }","duration":"132.242626ms","start":"2026-05-18T01:27:57.10345Z","end":"2026-05-18T01:27:57.235693Z","steps":["trace[448864631] 'process raft request' (duration: 122.198086ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:57.238166Z","caller":"traceutil/trace.go:171","msg":"trace[68032090] linearizableReadLoop","detail":"{readStateIndex:25323; appliedIndex:25321; }","duration":"117.930556ms","start":"2026-05-18T01:27:57.1202Z","end":"2026-05-18T01:27:57.23813Z","steps":["trace[68032090] 'read index received' (duration: 105.43171ms)","trace[68032090] 'applied index is now lower than readState.Index' (duration: 12.497246ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:57.23825Z","caller":"traceutil/trace.go:171","msg":"trace[1316664232] transaction","detail":"{read_only:false; response_revision:24510; number_of_response:1; }","duration":"129.409023ms","start":"2026-05-18T01:27:57.108813Z","end":"2026-05-18T01:27:57.238222Z","steps":["trace[1316664232] 'process raft request' (duration: 129.087456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:57.238433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.207071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-18T01:27:57.238482Z","caller":"traceutil/trace.go:171","msg":"trace[41852679] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24510; }","duration":"118.320423ms","start":"2026-05-18T01:27:57.120146Z","end":"2026-05-18T01:27:57.238467Z","steps":["trace[41852679] 'agreement among raft nodes before linearized reading' (duration: 118.108159ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:57.426311Z","caller":"traceutil/trace.go:171","msg":"trace[169161626] transaction","detail":"{read_only:false; response_revision:24511; number_of_response:1; }","duration":"181.809161ms","start":"2026-05-18T01:27:57.24449Z","end":"2026-05-18T01:27:57.426299Z","steps":["trace[169161626] 'process raft request' (duration: 160.136001ms)","trace[169161626] 'compare' (duration: 21.474227ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:10.35276Z","caller":"traceutil/trace.go:171","msg":"trace[1005186916] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"201.111515ms","start":"2026-05-18T01:28:10.151613Z","end":"2026-05-18T01:28:10.352724Z","steps":["trace[1005186916] 'process raft request' (duration: 200.84775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:10.612257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.771967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:28:10.612499Z","caller":"traceutil/trace.go:171","msg":"trace[724280391] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"252.024007ms","start":"2026-05-18T01:28:10.360447Z","end":"2026-05-18T01:28:10.612471Z","steps":["trace[724280391] 'process raft request' (duration: 110.919253ms)","trace[724280391] 'compare' (duration: 140.378349ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:10.612729Z","caller":"traceutil/trace.go:171","msg":"trace[60887130] linearizableReadLoop","detail":"{readStateIndex:25484; appliedIndex:25482; }","duration":"176.973369ms","start":"2026-05-18T01:28:10.435706Z","end":"2026-05-18T01:28:10.61268Z","steps":["trace[60887130] 'read index received' (duration: 35.684023ms)","trace[60887130] 'applied index is now lower than readState.Index' (duration: 141.286506ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:10.6128Z","caller":"traceutil/trace.go:171","msg":"trace[693207572] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"230.868248ms","start":"2026-05-18T01:28:10.381871Z","end":"2026-05-18T01:28:10.612739Z","steps":["trace[693207572] 'process raft request' (duration: 230.518051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:10.612968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.241905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:10.613093Z","caller":"traceutil/trace.go:171","msg":"trace[1843438272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24668; }","duration":"177.424768ms","start":"2026-05-18T01:28:10.435633Z","end":"2026-05-18T01:28:10.613058Z","steps":["trace[1843438272] 'agreement among raft nodes before linearized reading' (duration: 177.148453ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:10.780346Z","caller":"traceutil/trace.go:171","msg":"trace[39807048] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"140.117324ms","start":"2026-05-18T01:28:10.640201Z","end":"2026-05-18T01:28:10.780318Z","steps":["trace[39807048] 'process raft request' (duration: 135.313653ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:10.813054Z","caller":"traceutil/trace.go:171","msg":"trace[1915547009] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"156.162687ms","start":"2026-05-18T01:28:10.65685Z","end":"2026-05-18T01:28:10.813013Z","steps":["trace[1915547009] 'process raft request' (duration: 155.930413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:11.037519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.264471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:11.037588Z","caller":"traceutil/trace.go:171","msg":"trace[928115543] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24672; }","duration":"100.546736ms","start":"2026-05-18T01:28:10.937025Z","end":"2026-05-18T01:28:11.037572Z","steps":["trace[928115543] 'range keys from in-memory index tree' (duration: 100.193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.624666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.307539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:28:16.624787Z","caller":"traceutil/trace.go:171","msg":"trace[1850927003] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"303.111402ms","start":"2026-05-18T01:28:16.321655Z","end":"2026-05-18T01:28:16.624766Z","steps":["trace[1850927003] 'process raft request' (duration: 38.60162ms)","trace[1850927003] 'compare' (duration: 263.91774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:16.624848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:16.321629Z","time spent":"303.190933ms","remote":"127.0.0.1:41736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:16.654474Z","caller":"traceutil/trace.go:171","msg":"trace[154289363] linearizableReadLoop","detail":"{readStateIndex:25551; appliedIndex:25548; }","duration":"260.786102ms","start":"2026-05-18T01:28:16.393669Z","end":"2026-05-18T01:28:16.654455Z","steps":["trace[154289363] 'read index received' (duration: 260.526127ms)","trace[154289363] 'applied index is now lower than readState.Index' (duration: 258.844µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:16.654876Z","caller":"traceutil/trace.go:171","msg":"trace[566849592] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"333.011915ms","start":"2026-05-18T01:28:16.321852Z","end":"2026-05-18T01:28:16.654864Z","steps":["trace[566849592] 'process raft request' (duration: 332.364153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.654995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:16.321841Z","time spent":"333.064836ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:16.655155Z","caller":"traceutil/trace.go:171","msg":"trace[1816500092] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"333.267259ms","start":"2026-05-18T01:28:16.321881Z","end":"2026-05-18T01:28:16.655148Z","steps":["trace[1816500092] 'process raft request' (duration: 332.450293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.655202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:16.321843Z","time spent":"333.337682ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:16.655367Z","caller":"traceutil/trace.go:171","msg":"trace[1351977281] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"333.607947ms","start":"2026-05-18T01:28:16.321752Z","end":"2026-05-18T01:28:16.65536Z","steps":["trace[1351977281] 'process raft request' (duration: 332.624018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.655412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:16.321678Z","time spent":"333.712578ms","remote":"127.0.0.1:41736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:16.655592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.922883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-18T01:28:16.655625Z","caller":"traceutil/trace.go:171","msg":"trace[154480613] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24734; }","duration":"261.980914ms","start":"2026-05-18T01:28:16.393635Z","end":"2026-05-18T01:28:16.655616Z","steps":["trace[154480613] 'agreement among raft nodes before linearized reading' (duration: 261.883422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.905529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.631171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:28:16.905637Z","caller":"traceutil/trace.go:171","msg":"trace[814193639] linearizableReadLoop","detail":"{readStateIndex:25552; appliedIndex:25551; }","duration":"251.076778ms","start":"2026-05-18T01:28:16.654543Z","end":"2026-05-18T01:28:16.90562Z","steps":["trace[814193639] 'read index received' (duration: 108.194362ms)","trace[814193639] 'applied index is now lower than readState.Index' (duration: 142.881116ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:16.905707Z","caller":"traceutil/trace.go:171","msg":"trace[583258084] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"251.325072ms","start":"2026-05-18T01:28:16.654371Z","end":"2026-05-18T01:28:16.905696Z","steps":["trace[583258084] 'process raft request' (duration: 108.431066ms)","trace[583258084] 'compare' (duration: 142.486559ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:16.90592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.729324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:16.905943Z","caller":"traceutil/trace.go:171","msg":"trace[1268564321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24735; }","duration":"470.773105ms","start":"2026-05-18T01:28:16.435163Z","end":"2026-05-18T01:28:16.905936Z","steps":["trace[1268564321] 'agreement among raft nodes before linearized reading' (duration: 470.719024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.905969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:16.435119Z","time spent":"470.838966ms","remote":"127.0.0.1:41478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-18T01:28:16.906006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.903652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-18T01:28:16.906136Z","caller":"traceutil/trace.go:171","msg":"trace[2012999449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24735; }","duration":"271.074736ms","start":"2026-05-18T01:28:16.635035Z","end":"2026-05-18T01:28:16.90611Z","steps":["trace[2012999449] 'agreement among raft nodes before linearized reading' (duration: 270.803671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.90601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.141432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:28:16.908294Z","caller":"traceutil/trace.go:171","msg":"trace[1778214318] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:24735; }","duration":"382.646661ms","start":"2026-05-18T01:28:16.523798Z","end":"2026-05-18T01:28:16.906445Z","steps":["trace[1778214318] 'agreement among raft nodes before linearized reading' (duration: 382.03796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.908404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:16.523785Z","time spent":"384.599527ms","remote":"127.0.0.1:36064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":37,"response size":32,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:28:16.999377Z","caller":"traceutil/trace.go:171","msg":"trace[10431359] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"143.478107ms","start":"2026-05-18T01:28:16.855856Z","end":"2026-05-18T01:28:16.999334Z","steps":["trace[10431359] 'process raft request' (duration: 143.184272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.999437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.170476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-18T01:28:16.999511Z","caller":"traceutil/trace.go:171","msg":"trace[1996417367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24736; }","duration":"192.306839ms","start":"2026-05-18T01:28:16.807187Z","end":"2026-05-18T01:28:16.999494Z","steps":["trace[1996417367] 'agreement among raft nodes before linearized reading' (duration: 192.090905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.999421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.086454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:16.999652Z","caller":"traceutil/trace.go:171","msg":"trace[1815828109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24736; }","duration":"273.40213ms","start":"2026-05-18T01:28:16.72624Z","end":"2026-05-18T01:28:16.999642Z","steps":["trace[1815828109] 'agreement among raft nodes before linearized reading' (duration: 273.061973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:16.99981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.824432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-22361fd5-1976-4840-88c9-562f526e889a\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-18T01:28:16.999924Z","caller":"traceutil/trace.go:171","msg":"trace[453288486] range","detail":"{range_begin:/registry/persistentvolumes/pvc-22361fd5-1976-4840-88c9-562f526e889a; range_end:; response_count:1; response_revision:24736; }","duration":"340.920394ms","start":"2026-05-18T01:28:16.658929Z","end":"2026-05-18T01:28:16.999849Z","steps":["trace[453288486] 'agreement among raft nodes before linearized reading' (duration: 340.563017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:17.000024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:16.658905Z","time spent":"341.107618ms","remote":"127.0.0.1:41616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-22361fd5-1976-4840-88c9-562f526e889a\" "} {"level":"warn","ts":"2026-05-18T01:28:17.154249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.748427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:28:17.154412Z","caller":"traceutil/trace.go:171","msg":"trace[1827172225] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"149.244405ms","start":"2026-05-18T01:28:17.005149Z","end":"2026-05-18T01:28:17.154393Z","steps":["trace[1827172225] 'compare' (duration: 140.39315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:17.364465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.434154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:28:17.364586Z","caller":"traceutil/trace.go:171","msg":"trace[1858564441] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24739; }","duration":"101.615008ms","start":"2026-05-18T01:28:17.262949Z","end":"2026-05-18T01:28:17.364564Z","steps":["trace[1858564441] 'count revisions from in-memory index tree' (duration: 101.329742ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:31.124808Z","caller":"traceutil/trace.go:171","msg":"trace[2057771925] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"277.990597ms","start":"2026-05-18T01:28:30.846778Z","end":"2026-05-18T01:28:31.124768Z","steps":["trace[2057771925] 'process raft request' (duration: 277.733632ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:31.827932Z","caller":"traceutil/trace.go:171","msg":"trace[1914928312] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"861.783793ms","start":"2026-05-18T01:28:30.966119Z","end":"2026-05-18T01:28:31.827903Z","steps":["trace[1914928312] 'process raft request' (duration: 861.561459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:31.82821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:30.966061Z","time spent":"862.041768ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:31.828387Z","caller":"traceutil/trace.go:171","msg":"trace[32001935] linearizableReadLoop","detail":"{readStateIndex:25670; appliedIndex:25666; }","duration":"764.822744ms","start":"2026-05-18T01:28:31.063528Z","end":"2026-05-18T01:28:31.828351Z","steps":["trace[32001935] 'read index received' (duration: 60.95811ms)","trace[32001935] 'applied index is now lower than readState.Index' (duration: 703.863584ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:31.82846Z","caller":"traceutil/trace.go:171","msg":"trace[1638027444] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"769.523732ms","start":"2026-05-18T01:28:31.058895Z","end":"2026-05-18T01:28:31.828419Z","steps":["trace[1638027444] 'process raft request' (duration: 769.300427ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:31.828481Z","caller":"traceutil/trace.go:171","msg":"trace[1055071359] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"826.998168ms","start":"2026-05-18T01:28:31.001426Z","end":"2026-05-18T01:28:31.828424Z","steps":["trace[1055071359] 'process raft request' (duration: 826.432436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:31.828687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.058868Z","time spent":"769.702626ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:31.828691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.145271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-18T01:28:31.82872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.001412Z","time spent":"827.183841ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:31.828782Z","caller":"traceutil/trace.go:171","msg":"trace[940472332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24850; }","duration":"765.277023ms","start":"2026-05-18T01:28:31.06348Z","end":"2026-05-18T01:28:31.828757Z","steps":["trace[940472332] 'agreement among raft nodes before linearized reading' (duration: 765.022788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:31.828868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.06347Z","time spent":"765.387985ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-18T01:28:32.168205Z","caller":"traceutil/trace.go:171","msg":"trace[454316182] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"962.066926ms","start":"2026-05-18T01:28:31.20611Z","end":"2026-05-18T01:28:32.168177Z","steps":["trace[454316182] 'process raft request' (duration: 961.792421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.168386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.206057Z","time spent":"962.231989ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:32.168796Z","caller":"traceutil/trace.go:171","msg":"trace[1151671414] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"949.838876ms","start":"2026-05-18T01:28:31.218912Z","end":"2026-05-18T01:28:32.168751Z","steps":["trace[1151671414] 'process raft request' (duration: 949.217774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.169035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.218898Z","time spent":"949.991738ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:32.169209Z","caller":"traceutil/trace.go:171","msg":"trace[299602324] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"747.542808ms","start":"2026-05-18T01:28:31.421652Z","end":"2026-05-18T01:28:32.169195Z","steps":["trace[299602324] 'process raft request' (duration: 747.163011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.169327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.421624Z","time spent":"747.617839ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:32.169332Z","caller":"traceutil/trace.go:171","msg":"trace[372359901] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"745.805875ms","start":"2026-05-18T01:28:31.423509Z","end":"2026-05-18T01:28:32.169315Z","steps":["trace[372359901] 'process raft request' (duration: 745.49997ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:32.169362Z","caller":"traceutil/trace.go:171","msg":"trace[809312097] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"607.937493ms","start":"2026-05-18T01:28:31.561375Z","end":"2026-05-18T01:28:32.169312Z","steps":["trace[809312097] 'process raft request' (duration: 607.691669ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:32.169447Z","caller":"traceutil/trace.go:171","msg":"trace[928192465] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"607.468444ms","start":"2026-05-18T01:28:31.561967Z","end":"2026-05-18T01:28:32.169436Z","steps":["trace[928192465] 'process raft request' (duration: 607.138538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.169517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.561334Z","time spent":"608.111446ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:32.169606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.561927Z","time spent":"607.562106ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:32.169337Z","caller":"traceutil/trace.go:171","msg":"trace[2032331139] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"351.410531ms","start":"2026-05-18T01:28:31.817859Z","end":"2026-05-18T01:28:32.16927Z","steps":["trace[2032331139] 'process raft request' (duration: 351.288519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.169706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.817839Z","time spent":"351.819449ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:32.169883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.285336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-18T01:28:32.16992Z","caller":"traceutil/trace.go:171","msg":"trace[572274623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24857; }","duration":"344.365858ms","start":"2026-05-18T01:28:31.825543Z","end":"2026-05-18T01:28:32.169908Z","steps":["trace[572274623] 'agreement among raft nodes before linearized reading' (duration: 344.208945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.169949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.82552Z","time spent":"344.42275ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-18T01:28:32.169232Z","caller":"traceutil/trace.go:171","msg":"trace[442075361] linearizableReadLoop","detail":"{readStateIndex:25677; appliedIndex:25670; }","duration":"340.74337ms","start":"2026-05-18T01:28:31.82846Z","end":"2026-05-18T01:28:32.169204Z","steps":["trace[442075361] 'read index received' (duration: 339.349404ms)","trace[442075361] 'applied index is now lower than readState.Index' (duration: 1.392656ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:32.170011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.554161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-18T01:28:32.170048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.994128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-18T01:28:32.170051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.423486Z","time spent":"745.880606ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:32.170105Z","caller":"traceutil/trace.go:171","msg":"trace[969503016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24857; }","duration":"445.10733ms","start":"2026-05-18T01:28:31.724988Z","end":"2026-05-18T01:28:32.170095Z","steps":["trace[969503016] 'agreement among raft nodes before linearized reading' (duration: 444.627091ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:32.170186Z","caller":"traceutil/trace.go:171","msg":"trace[454544914] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24857; }","duration":"780.687344ms","start":"2026-05-18T01:28:31.389376Z","end":"2026-05-18T01:28:32.170063Z","steps":["trace[454544914] 'agreement among raft nodes before linearized reading' (duration: 780.449298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.170243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.724957Z","time spent":"445.265073ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T01:28:32.170255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.389353Z","time spent":"780.887747ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-18T01:28:32.169993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.208006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-18T01:28:32.17031Z","caller":"traceutil/trace.go:171","msg":"trace[1524718354] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24857; }","duration":"556.600054ms","start":"2026-05-18T01:28:31.613694Z","end":"2026-05-18T01:28:32.170294Z","steps":["trace[1524718354] 'agreement among raft nodes before linearized reading' (duration: 556.075643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.17035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.613667Z","time spent":"556.669496ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-18T01:28:32.17032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.727232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-18T01:28:32.170425Z","caller":"traceutil/trace.go:171","msg":"trace[611991162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24857; }","duration":"950.882585ms","start":"2026-05-18T01:28:31.21951Z","end":"2026-05-18T01:28:32.170393Z","steps":["trace[611991162] 'agreement among raft nodes before linearized reading' (duration: 950.499468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.170477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.2195Z","time spent":"950.968206ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-18T01:28:32.209659Z","caller":"traceutil/trace.go:171","msg":"trace[1822371946] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"374.421676ms","start":"2026-05-18T01:28:31.835205Z","end":"2026-05-18T01:28:32.209626Z","steps":["trace[1822371946] 'process raft request' (duration: 374.222863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.209816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:31.835174Z","time spent":"374.553089ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:32.630719Z","caller":"traceutil/trace.go:171","msg":"trace[1229457351] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"452.432179ms","start":"2026-05-18T01:28:32.178256Z","end":"2026-05-18T01:28:32.630688Z","steps":["trace[1229457351] 'process raft request' (duration: 452.154923ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:32.630747Z","caller":"traceutil/trace.go:171","msg":"trace[793096040] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"452.431359ms","start":"2026-05-18T01:28:32.178292Z","end":"2026-05-18T01:28:32.630723Z","steps":["trace[793096040] 'process raft request' (duration: 452.170784ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:32.630599Z","caller":"traceutil/trace.go:171","msg":"trace[581288579] transaction","detail":"{read_only:false; response_revision:24862; number_of_response:1; }","duration":"452.057931ms","start":"2026-05-18T01:28:32.178508Z","end":"2026-05-18T01:28:32.630566Z","steps":["trace[581288579] 'process raft request' (duration: 452.001769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.630875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:32.178244Z","time spent":"452.553122ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:32.630945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:32.178282Z","time spent":"452.531601ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:32.630814Z","caller":"traceutil/trace.go:171","msg":"trace[1542055630] linearizableReadLoop","detail":"{readStateIndex:25682; appliedIndex:25682; }","duration":"421.189928ms","start":"2026-05-18T01:28:32.209582Z","end":"2026-05-18T01:28:32.630772Z","steps":["trace[1542055630] 'read index received' (duration: 421.159828ms)","trace[1542055630] 'applied index is now lower than readState.Index' (duration: 26.96µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:32.631017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:32.178501Z","time spent":"452.409328ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:32.630637Z","caller":"traceutil/trace.go:171","msg":"trace[2072575645] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"452.415938ms","start":"2026-05-18T01:28:32.178179Z","end":"2026-05-18T01:28:32.630595Z","steps":["trace[2072575645] 'process raft request' (duration: 452.009771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.631161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.206603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-18T01:28:32.63122Z","caller":"traceutil/trace.go:171","msg":"trace[635547314] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24862; }","duration":"453.421787ms","start":"2026-05-18T01:28:32.177785Z","end":"2026-05-18T01:28:32.631207Z","steps":["trace[635547314] 'agreement among raft nodes before linearized reading' (duration: 453.098691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.631262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:32.178151Z","time spent":"453.03029ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:32.63127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:32.177765Z","time spent":"453.495078ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-18T01:28:32.66961Z","caller":"traceutil/trace.go:171","msg":"trace[103025900] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"453.220753ms","start":"2026-05-18T01:28:32.216354Z","end":"2026-05-18T01:28:32.669575Z","steps":["trace[103025900] 'process raft request' (duration: 453.0389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.669659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.261814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:32.66971Z","caller":"traceutil/trace.go:171","msg":"trace[2063299431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24863; }","duration":"231.372846ms","start":"2026-05-18T01:28:32.438324Z","end":"2026-05-18T01:28:32.669697Z","steps":["trace[2063299431] 'agreement among raft nodes before linearized reading' (duration: 231.261214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.674218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.753577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:32.674356Z","caller":"traceutil/trace.go:171","msg":"trace[1839727073] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:24863; }","duration":"104.949891ms","start":"2026-05-18T01:28:32.569353Z","end":"2026-05-18T01:28:32.674303Z","steps":["trace[1839727073] 'agreement among raft nodes before linearized reading' (duration: 100.228081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:32.679632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:32.216328Z","time spent":"453.355405ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:32.999871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.337628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:32.999988Z","caller":"traceutil/trace.go:171","msg":"trace[1929438103] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24863; }","duration":"264.532462ms","start":"2026-05-18T01:28:32.735427Z","end":"2026-05-18T01:28:32.999959Z","steps":["trace[1929438103] 'range keys from in-memory index tree' (duration: 264.198886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:33.985654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.00785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:28:33.985757Z","caller":"traceutil/trace.go:171","msg":"trace[663720687] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24864; }","duration":"196.193963ms","start":"2026-05-18T01:28:33.789541Z","end":"2026-05-18T01:28:33.985735Z","steps":["trace[663720687] 'count revisions from in-memory index tree' (duration: 195.901557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:33.986161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.507766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:33.986271Z","caller":"traceutil/trace.go:171","msg":"trace[518232514] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24864; }","duration":"376.7147ms","start":"2026-05-18T01:28:33.609529Z","end":"2026-05-18T01:28:33.986244Z","steps":["trace[518232514] 'count revisions from in-memory index tree' (duration: 376.368154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:33.986326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:33.609499Z","time spent":"376.814321ms","remote":"127.0.0.1:38536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:28:33.986354Z","caller":"traceutil/trace.go:171","msg":"trace[1045120156] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"151.456788ms","start":"2026-05-18T01:28:33.834844Z","end":"2026-05-18T01:28:33.986301Z","steps":["trace[1045120156] 'process raft request' (duration: 81.488917ms)","trace[1045120156] 'compare' (duration: 69.523372ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:34.017402Z","caller":"traceutil/trace.go:171","msg":"trace[764332450] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"181.719549ms","start":"2026-05-18T01:28:33.835644Z","end":"2026-05-18T01:28:34.017364Z","steps":["trace[764332450] 'process raft request' (duration: 181.290221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:34.332894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.450846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:34.333001Z","caller":"traceutil/trace.go:171","msg":"trace[1280000432] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24866; }","duration":"162.608609ms","start":"2026-05-18T01:28:34.170359Z","end":"2026-05-18T01:28:34.332968Z","steps":["trace[1280000432] 'count revisions from in-memory index tree' (duration: 162.363835ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:34.333487Z","caller":"traceutil/trace.go:171","msg":"trace[1532916989] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"155.23129ms","start":"2026-05-18T01:28:34.178215Z","end":"2026-05-18T01:28:34.333447Z","steps":["trace[1532916989] 'process raft request' (duration: 152.809684ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:34.3534Z","caller":"traceutil/trace.go:171","msg":"trace[291372760] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"172.997435ms","start":"2026-05-18T01:28:34.180369Z","end":"2026-05-18T01:28:34.353367Z","steps":["trace[291372760] 'process raft request' (duration: 172.920014ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:34.35351Z","caller":"traceutil/trace.go:171","msg":"trace[416682878] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"173.054306ms","start":"2026-05-18T01:28:34.180442Z","end":"2026-05-18T01:28:34.353496Z","steps":["trace[416682878] 'process raft request' (duration: 172.884663ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:34.353396Z","caller":"traceutil/trace.go:171","msg":"trace[1884421667] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"173.480914ms","start":"2026-05-18T01:28:34.17987Z","end":"2026-05-18T01:28:34.353351Z","steps":["trace[1884421667] 'process raft request' (duration: 173.247539ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:34.3535Z","caller":"traceutil/trace.go:171","msg":"trace[982387474] linearizableReadLoop","detail":"{readStateIndex:25692; appliedIndex:25687; }","duration":"139.778128ms","start":"2026-05-18T01:28:34.213707Z","end":"2026-05-18T01:28:34.353485Z","steps":["trace[982387474] 'read index received' (duration: 117.337405ms)","trace[982387474] 'applied index is now lower than readState.Index' (duration: 22.439683ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:34.353706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.992702ms","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-05-18T01:28:34.353757Z","caller":"traceutil/trace.go:171","msg":"trace[2004921379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24871; }","duration":"140.079784ms","start":"2026-05-18T01:28:34.213667Z","end":"2026-05-18T01:28:34.353747Z","steps":["trace[2004921379] 'agreement among raft nodes before linearized reading' (duration: 139.8707ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:34.353965Z","caller":"traceutil/trace.go:171","msg":"trace[218567049] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"173.346791ms","start":"2026-05-18T01:28:34.180045Z","end":"2026-05-18T01:28:34.353392Z","steps":["trace[218567049] 'process raft request' (duration: 173.196649ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:54.912737Z","caller":"traceutil/trace.go:171","msg":"trace[69755566] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"117.175582ms","start":"2026-05-18T01:28:54.795502Z","end":"2026-05-18T01:28:54.912677Z","steps":["trace[69755566] 'process raft request' (duration: 116.841426ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:54.912958Z","caller":"traceutil/trace.go:171","msg":"trace[332343727] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"116.982438ms","start":"2026-05-18T01:28:54.79595Z","end":"2026-05-18T01:28:54.912933Z","steps":["trace[332343727] 'process raft request' (duration: 116.52455ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:54.913307Z","caller":"traceutil/trace.go:171","msg":"trace[1081930771] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"117.1353ms","start":"2026-05-18T01:28:54.796157Z","end":"2026-05-18T01:28:54.913293Z","steps":["trace[1081930771] 'process raft request' (duration: 116.705532ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:54.91367Z","caller":"traceutil/trace.go:171","msg":"trace[656087560] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"117.029278ms","start":"2026-05-18T01:28:54.796224Z","end":"2026-05-18T01:28:54.913253Z","steps":["trace[656087560] 'process raft request' (duration: 116.982107ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:59.008786Z","caller":"traceutil/trace.go:171","msg":"trace[928506495] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"153.16134ms","start":"2026-05-18T01:28:58.855575Z","end":"2026-05-18T01:28:59.008736Z","steps":["trace[928506495] 'process raft request' (duration: 152.569329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:59.187866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.116124ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-18T01:28:59.188015Z","caller":"traceutil/trace.go:171","msg":"trace[81593174] linearizableReadLoop","detail":"{readStateIndex:25886; appliedIndex:25885; }","duration":"179.334845ms","start":"2026-05-18T01:28:59.008657Z","end":"2026-05-18T01:28:59.187992Z","steps":["trace[81593174] 'read index received' (duration: 29.983637ms)","trace[81593174] 'applied index is now lower than readState.Index' (duration: 149.349448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:59.188216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.376112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-18T01:28:59.188266Z","caller":"traceutil/trace.go:171","msg":"trace[647912511] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25059; }","duration":"261.484104ms","start":"2026-05-18T01:28:58.926766Z","end":"2026-05-18T01:28:59.18825Z","steps":["trace[647912511] 'agreement among raft nodes before linearized reading' (duration: 261.283441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:59.188449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.24291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:59.188495Z","caller":"traceutil/trace.go:171","msg":"trace[401630166] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25059; }","duration":"244.334282ms","start":"2026-05-18T01:28:58.944149Z","end":"2026-05-18T01:28:59.188483Z","steps":["trace[401630166] 'agreement among raft nodes before linearized reading' (duration: 244.24559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:59.18862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.437958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-18T01:28:59.188715Z","caller":"traceutil/trace.go:171","msg":"trace[1117419582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25059; }","duration":"258.57756ms","start":"2026-05-18T01:28:58.930115Z","end":"2026-05-18T01:28:59.188692Z","steps":["trace[1117419582] 'agreement among raft nodes before linearized reading' (duration: 258.247354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:59.188842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.555259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-18T01:28:59.188938Z","caller":"traceutil/trace.go:171","msg":"trace[1107529730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25059; }","duration":"259.679071ms","start":"2026-05-18T01:28:58.929233Z","end":"2026-05-18T01:28:59.188912Z","steps":["trace[1107529730] 'agreement among raft nodes before linearized reading' (duration: 259.282924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:59.189916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.203421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-18T01:28:59.190016Z","caller":"traceutil/trace.go:171","msg":"trace[820011912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25059; }","duration":"260.331203ms","start":"2026-05-18T01:28:58.92966Z","end":"2026-05-18T01:28:59.189991Z","steps":["trace[820011912] 'agreement among raft nodes before linearized reading' (duration: 258.852135ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:59.329543Z","caller":"traceutil/trace.go:171","msg":"trace[1981367337] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"134.771083ms","start":"2026-05-18T01:28:59.194732Z","end":"2026-05-18T01:28:59.329503Z","steps":["trace[1981367337] 'process raft request' (duration: 134.529769ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:59.368122Z","caller":"traceutil/trace.go:171","msg":"trace[237390087] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"173.162068ms","start":"2026-05-18T01:28:59.194936Z","end":"2026-05-18T01:28:59.368098Z","steps":["trace[237390087] 'process raft request' (duration: 173.016255ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:59.368518Z","caller":"traceutil/trace.go:171","msg":"trace[53033316] linearizableReadLoop","detail":"{readStateIndex:25890; appliedIndex:25886; }","duration":"166.107234ms","start":"2026-05-18T01:28:59.202395Z","end":"2026-05-18T01:28:59.368502Z","steps":["trace[53033316] 'read index received' (duration: 126.833943ms)","trace[53033316] 'applied index is now lower than readState.Index' (duration: 39.271891ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:59.368648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.208436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:59.368691Z","caller":"traceutil/trace.go:171","msg":"trace[1059157222] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25063; }","duration":"166.333529ms","start":"2026-05-18T01:28:59.202342Z","end":"2026-05-18T01:28:59.368676Z","steps":["trace[1059157222] 'agreement among raft nodes before linearized reading' (duration: 166.218326ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:59.368731Z","caller":"traceutil/trace.go:171","msg":"trace[88522675] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"172.78675ms","start":"2026-05-18T01:28:59.195913Z","end":"2026-05-18T01:28:59.3687Z","steps":["trace[88522675] 'process raft request' (duration: 172.141768ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:59.368918Z","caller":"traceutil/trace.go:171","msg":"trace[906714055] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"172.942723ms","start":"2026-05-18T01:28:59.195961Z","end":"2026-05-18T01:28:59.368904Z","steps":["trace[906714055] 'process raft request' (duration: 172.477994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:59.589132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.1109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:59.589246Z","caller":"traceutil/trace.go:171","msg":"trace[1571836694] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25063; }","duration":"117.308484ms","start":"2026-05-18T01:28:59.471913Z","end":"2026-05-18T01:28:59.589222Z","steps":["trace[1571836694] 'count revisions from in-memory index tree' (duration: 116.985508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:59.589243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.926877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:59.589376Z","caller":"traceutil/trace.go:171","msg":"trace[911111402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25063; }","duration":"152.148021ms","start":"2026-05-18T01:28:59.437199Z","end":"2026-05-18T01:28:59.589347Z","steps":["trace[911111402] 'range keys from in-memory index tree' (duration: 151.789995ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:00.410345Z","caller":"traceutil/trace.go:171","msg":"trace[1577593458] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"319.667843ms","start":"2026-05-18T01:29:00.090646Z","end":"2026-05-18T01:29:00.410313Z","steps":["trace[1577593458] 'process raft request' (duration: 319.472679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.410617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.090615Z","time spent":"319.811235ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:00.610807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.714109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:00.610956Z","caller":"traceutil/trace.go:171","msg":"trace[965792810] linearizableReadLoop","detail":"{readStateIndex:25892; appliedIndex:25890; }","duration":"396.926701ms","start":"2026-05-18T01:29:00.214007Z","end":"2026-05-18T01:29:00.610933Z","steps":["trace[965792810] 'read index received' (duration: 196.093171ms)","trace[965792810] 'applied index is now lower than readState.Index' (duration: 200.83187ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:00.610978Z","caller":"traceutil/trace.go:171","msg":"trace[296565042] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"499.11372ms","start":"2026-05-18T01:29:00.111836Z","end":"2026-05-18T01:29:00.610949Z","steps":["trace[296565042] 'process raft request' (duration: 397.136885ms)","trace[296565042] 'compare' (duration: 101.535206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:00.611067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.111795Z","time spent":"499.238152ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:00.611097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.061834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:00.611187Z","caller":"traceutil/trace.go:171","msg":"trace[44763625] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25065; }","duration":"397.212247ms","start":"2026-05-18T01:29:00.213951Z","end":"2026-05-18T01:29:00.611164Z","steps":["trace[44763625] 'agreement among raft nodes before linearized reading' (duration: 397.037483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.611231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.213917Z","time spent":"397.304839ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:29:00.879171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.939262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:00.879444Z","caller":"traceutil/trace.go:171","msg":"trace[552732629] linearizableReadLoop","detail":"{readStateIndex:25897; appliedIndex:25892; }","duration":"268.449856ms","start":"2026-05-18T01:29:00.610981Z","end":"2026-05-18T01:29:00.879431Z","steps":["trace[552732629] 'read index received' (duration: 129.128027ms)","trace[552732629] 'applied index is now lower than readState.Index' (duration: 139.320889ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:00.879522Z","caller":"traceutil/trace.go:171","msg":"trace[1386698092] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"435.171261ms","start":"2026-05-18T01:29:00.444344Z","end":"2026-05-18T01:29:00.879515Z","steps":["trace[1386698092] 'process raft request' (duration: 434.915347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.879562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.44431Z","time spent":"435.231453ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:00.879686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.17753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-18T01:29:00.879771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.298909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:00.879792Z","caller":"traceutil/trace.go:171","msg":"trace[1218083174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25070; }","duration":"441.34321ms","start":"2026-05-18T01:29:00.438443Z","end":"2026-05-18T01:29:00.879786Z","steps":["trace[1218083174] 'agreement among raft nodes before linearized reading' (duration: 441.301019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.879817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.438425Z","time spent":"441.389711ms","remote":"127.0.0.1:41478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-18T01:29:00.879885Z","caller":"traceutil/trace.go:171","msg":"trace[836507396] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"431.32575ms","start":"2026-05-18T01:29:00.448514Z","end":"2026-05-18T01:29:00.879839Z","steps":["trace[836507396] 'process raft request' (duration: 430.857661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.880013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.448489Z","time spent":"431.451672ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:00.879981Z","caller":"traceutil/trace.go:171","msg":"trace[1116453956] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"429.103489ms","start":"2026-05-18T01:29:00.45083Z","end":"2026-05-18T01:29:00.879933Z","steps":["trace[1116453956] 'process raft request' (duration: 428.566988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.879982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.135766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-18T01:29:00.88006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.154726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-18T01:29:00.880108Z","caller":"traceutil/trace.go:171","msg":"trace[1301518246] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25070; }","duration":"618.34238ms","start":"2026-05-18T01:29:00.261754Z","end":"2026-05-18T01:29:00.880096Z","steps":["trace[1301518246] 'agreement among raft nodes before linearized reading' (duration: 618.078715ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:00.880113Z","caller":"traceutil/trace.go:171","msg":"trace[1166436091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25070; }","duration":"396.244858ms","start":"2026-05-18T01:29:00.483854Z","end":"2026-05-18T01:29:00.880099Z","steps":["trace[1166436091] 'agreement among raft nodes before linearized reading' (duration: 396.039294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.880135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.261728Z","time spent":"618.3981ms","remote":"127.0.0.1:34932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:29:00.880171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.45079Z","time spent":"429.295382ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:00.880176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.483839Z","time spent":"396.328379ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T01:29:00.880182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.510368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-18T01:29:00.880218Z","caller":"traceutil/trace.go:171","msg":"trace[538261994] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25070; }","duration":"214.57929ms","start":"2026-05-18T01:29:00.665629Z","end":"2026-05-18T01:29:00.880208Z","steps":["trace[538261994] 'agreement among raft nodes before linearized reading' (duration: 214.426127ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:00.879978Z","caller":"traceutil/trace.go:171","msg":"trace[1545667766] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"439.839602ms","start":"2026-05-18T01:29:00.439865Z","end":"2026-05-18T01:29:00.879705Z","steps":["trace[1545667766] 'process raft request' (duration: 300.279087ms)","trace[1545667766] 'compare' (duration: 138.592896ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:00.879775Z","caller":"traceutil/trace.go:171","msg":"trace[448982275] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25070; }","duration":"420.311543ms","start":"2026-05-18T01:29:00.459443Z","end":"2026-05-18T01:29:00.879754Z","steps":["trace[448982275] 'agreement among raft nodes before linearized reading' (duration: 420.100858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.880484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.439848Z","time spent":"440.488393ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:00.880517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.45943Z","time spent":"421.023866ms","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":32,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:29:00.881044Z","caller":"traceutil/trace.go:171","msg":"trace[942502658] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"433.357578ms","start":"2026-05-18T01:29:00.447665Z","end":"2026-05-18T01:29:00.881022Z","steps":["trace[942502658] 'process raft request' (duration: 431.683356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:00.881115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:00.447655Z","time spent":"433.421689ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:01.029293Z","caller":"traceutil/trace.go:171","msg":"trace[401967447] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"144.13894ms","start":"2026-05-18T01:29:00.885109Z","end":"2026-05-18T01:29:01.029248Z","steps":["trace[401967447] 'process raft request' (duration: 137.071907ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:01.213257Z","caller":"traceutil/trace.go:171","msg":"trace[943424877] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"178.848135ms","start":"2026-05-18T01:29:01.034347Z","end":"2026-05-18T01:29:01.213195Z","steps":["trace[943424877] 'process raft request' (duration: 119.290261ms)","trace[943424877] 'compare' (duration: 58.913811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:01.58145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.571718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-18T01:29:01.581529Z","caller":"traceutil/trace.go:171","msg":"trace[141634829] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25073; }","duration":"207.6786ms","start":"2026-05-18T01:29:01.373829Z","end":"2026-05-18T01:29:01.581508Z","steps":["trace[141634829] 'range keys from in-memory index tree' (duration: 207.425885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:01.581601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.901234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-18T01:29:01.581601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.805833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-18T01:29:01.581659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.40585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-18T01:29:01.582424Z","caller":"traceutil/trace.go:171","msg":"trace[874074026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25073; }","duration":"247.223205ms","start":"2026-05-18T01:29:01.335169Z","end":"2026-05-18T01:29:01.582392Z","steps":["trace[874074026] 'range keys from in-memory index tree' (duration: 246.134455ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:01.581704Z","caller":"traceutil/trace.go:171","msg":"trace[1201023230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25073; }","duration":"207.938965ms","start":"2026-05-18T01:29:01.373741Z","end":"2026-05-18T01:29:01.58168Z","steps":["trace[1201023230] 'range keys from in-memory index tree' (duration: 207.597799ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:01.581713Z","caller":"traceutil/trace.go:171","msg":"trace[2112912119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25073; }","duration":"208.061537ms","start":"2026-05-18T01:29:01.37361Z","end":"2026-05-18T01:29:01.581671Z","steps":["trace[2112912119] 'range keys from in-memory index tree' (duration: 207.559408ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:01.777094Z","caller":"traceutil/trace.go:171","msg":"trace[35427868] linearizableReadLoop","detail":"{readStateIndex:25904; appliedIndex:25901; }","duration":"149.397669ms","start":"2026-05-18T01:29:01.627665Z","end":"2026-05-18T01:29:01.777063Z","steps":["trace[35427868] 'read index received' (duration: 148.638935ms)","trace[35427868] 'applied index is now lower than readState.Index' (duration: 757.184µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:01.777446Z","caller":"traceutil/trace.go:171","msg":"trace[573533207] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"184.733106ms","start":"2026-05-18T01:29:01.592672Z","end":"2026-05-18T01:29:01.777405Z","steps":["trace[573533207] 'process raft request' (duration: 183.830409ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:01.777578Z","caller":"traceutil/trace.go:171","msg":"trace[755300124] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"182.216329ms","start":"2026-05-18T01:29:01.595332Z","end":"2026-05-18T01:29:01.777548Z","steps":["trace[755300124] 'process raft request' (duration: 181.21243ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:01.777429Z","caller":"traceutil/trace.go:171","msg":"trace[1094386583] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"184.795768ms","start":"2026-05-18T01:29:01.592593Z","end":"2026-05-18T01:29:01.777388Z","steps":["trace[1094386583] 'process raft request' (duration: 183.759768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:01.779633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.95024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:01.779993Z","caller":"traceutil/trace.go:171","msg":"trace[1362678407] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25077; }","duration":"152.362235ms","start":"2026-05-18T01:29:01.62761Z","end":"2026-05-18T01:29:01.779972Z","steps":["trace[1362678407] 'agreement among raft nodes before linearized reading' (duration: 149.574343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:02.672211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.117867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:02.67234Z","caller":"traceutil/trace.go:171","msg":"trace[1283186782] linearizableReadLoop","detail":"{readStateIndex:25906; appliedIndex:25905; }","duration":"234.418985ms","start":"2026-05-18T01:29:02.4379Z","end":"2026-05-18T01:29:02.672319Z","steps":["trace[1283186782] 'read index received' (duration: 95.973602ms)","trace[1283186782] 'applied index is now lower than readState.Index' (duration: 138.443653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:02.672443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.542977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:02.672472Z","caller":"traceutil/trace.go:171","msg":"trace[1630773153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25079; }","duration":"234.614048ms","start":"2026-05-18T01:29:02.437846Z","end":"2026-05-18T01:29:02.672461Z","steps":["trace[1630773153] 'agreement among raft nodes before linearized reading' (duration: 234.549267ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:02.672438Z","caller":"traceutil/trace.go:171","msg":"trace[1435385104] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"257.235045ms","start":"2026-05-18T01:29:02.415138Z","end":"2026-05-18T01:29:02.672373Z","steps":["trace[1435385104] 'process raft request' (duration: 118.65255ms)","trace[1435385104] 'compare' (duration: 137.760709ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:02.932711Z","caller":"traceutil/trace.go:171","msg":"trace[934712937] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"485.050965ms","start":"2026-05-18T01:29:02.447632Z","end":"2026-05-18T01:29:02.932683Z","steps":["trace[934712937] 'process raft request' (duration: 466.501745ms)","trace[934712937] 'compare' (duration: 18.280085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:02.933452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.447613Z","time spent":"485.717576ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7732,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:03.194802Z","caller":"traceutil/trace.go:171","msg":"trace[205716197] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"654.796837ms","start":"2026-05-18T01:29:02.539963Z","end":"2026-05-18T01:29:03.19476Z","steps":["trace[205716197] 'process raft request' (duration: 654.441721ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:03.194822Z","caller":"traceutil/trace.go:171","msg":"trace[428776437] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"576.621062ms","start":"2026-05-18T01:29:02.618165Z","end":"2026-05-18T01:29:03.194786Z","steps":["trace[428776437] 'process raft request' (duration: 576.474359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.19501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.539933Z","time spent":"654.958611ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:03.195139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.618125Z","time spent":"576.791495ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:03.195163Z","caller":"traceutil/trace.go:171","msg":"trace[1635304099] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"561.235102ms","start":"2026-05-18T01:29:02.63389Z","end":"2026-05-18T01:29:03.195125Z","steps":["trace[1635304099] 'process raft request' (duration: 560.827605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.195331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.633858Z","time spent":"561.385424ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:03.195692Z","caller":"traceutil/trace.go:171","msg":"trace[1188882511] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"308.92157ms","start":"2026-05-18T01:29:02.886759Z","end":"2026-05-18T01:29:03.19568Z","steps":["trace[1188882511] 'process raft request' (duration: 308.303168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.195753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.886735Z","time spent":"308.991362ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:03.195809Z","caller":"traceutil/trace.go:171","msg":"trace[154295253] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"308.928741ms","start":"2026-05-18T01:29:02.886848Z","end":"2026-05-18T01:29:03.195777Z","steps":["trace[154295253] 'process raft request' (duration: 308.643025ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:03.195862Z","caller":"traceutil/trace.go:171","msg":"trace[1159510785] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"307.86133ms","start":"2026-05-18T01:29:02.88799Z","end":"2026-05-18T01:29:03.195852Z","steps":["trace[1159510785] 'process raft request' (duration: 307.636896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.195921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.88795Z","time spent":"307.941872ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:03.195975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.886843Z","time spent":"309.048173ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:03.19602Z","caller":"traceutil/trace.go:171","msg":"trace[1722511090] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"305.839822ms","start":"2026-05-18T01:29:02.890156Z","end":"2026-05-18T01:29:03.195996Z","steps":["trace[1722511090] 'process raft request' (duration: 305.535306ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:03.195938Z","caller":"traceutil/trace.go:171","msg":"trace[1462811878] linearizableReadLoop","detail":"{readStateIndex:25910; appliedIndex:25906; }","duration":"523.54556ms","start":"2026-05-18T01:29:02.672367Z","end":"2026-05-18T01:29:03.195912Z","steps":["trace[1462811878] 'read index received' (duration: 241.866735ms)","trace[1462811878] 'applied index is now lower than readState.Index' (duration: 281.677235ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:03.196068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.890147Z","time spent":"305.889103ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:03.196546Z","caller":"traceutil/trace.go:171","msg":"trace[1489310282] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"306.249609ms","start":"2026-05-18T01:29:02.890287Z","end":"2026-05-18T01:29:03.196536Z","steps":["trace[1489310282] 'process raft request' (duration: 305.525566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.198923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.159051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:03.198948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.890279Z","time spent":"308.619984ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:03.198981Z","caller":"traceutil/trace.go:171","msg":"trace[979239571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25088; }","duration":"525.261373ms","start":"2026-05-18T01:29:02.673703Z","end":"2026-05-18T01:29:03.198964Z","steps":["trace[979239571] 'agreement among raft nodes before linearized reading' (duration: 525.170691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.196644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.03875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:03.199031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.504584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-18T01:29:03.19904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.673682Z","time spent":"525.348254ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-18T01:29:03.199065Z","caller":"traceutil/trace.go:171","msg":"trace[2111383579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25088; }","duration":"164.596606ms","start":"2026-05-18T01:29:03.034458Z","end":"2026-05-18T01:29:03.199054Z","steps":["trace[2111383579] 'agreement among raft nodes before linearized reading' (duration: 164.450904ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:03.199071Z","caller":"traceutil/trace.go:171","msg":"trace[1669675329] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:25088; }","duration":"738.510367ms","start":"2026-05-18T01:29:02.460539Z","end":"2026-05-18T01:29:03.199049Z","steps":["trace[1669675329] 'agreement among raft nodes before linearized reading' (duration: 736.05437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.199114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:02.460514Z","time spent":"738.585598ms","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:29:03.19933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.282755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18366"} {"level":"info","ts":"2026-05-18T01:29:03.199362Z","caller":"traceutil/trace.go:171","msg":"trace[1673992198] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25088; }","duration":"248.355787ms","start":"2026-05-18T01:29:02.950996Z","end":"2026-05-18T01:29:03.199352Z","steps":["trace[1673992198] 'agreement among raft nodes before linearized reading' (duration: 248.206574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.508956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.479464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:03.509126Z","caller":"traceutil/trace.go:171","msg":"trace[1396754787] linearizableReadLoop","detail":"{readStateIndex:25916; appliedIndex:25915; }","duration":"291.502211ms","start":"2026-05-18T01:29:03.217596Z","end":"2026-05-18T01:29:03.509098Z","steps":["trace[1396754787] 'read index received' (duration: 144.786943ms)","trace[1396754787] 'applied index is now lower than readState.Index' (duration: 146.713078ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:03.509138Z","caller":"traceutil/trace.go:171","msg":"trace[741449593] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"304.497896ms","start":"2026-05-18T01:29:03.204612Z","end":"2026-05-18T01:29:03.50911Z","steps":["trace[741449593] 'process raft request' (duration: 157.736698ms)","trace[741449593] 'compare' (duration: 146.28613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:03.509284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:03.204597Z","time spent":"304.592459ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:03.509485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.888629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-18T01:29:03.509555Z","caller":"traceutil/trace.go:171","msg":"trace[321925797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25089; }","duration":"291.987471ms","start":"2026-05-18T01:29:03.217541Z","end":"2026-05-18T01:29:03.509528Z","steps":["trace[321925797] 'agreement among raft nodes before linearized reading' (duration: 291.776267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:03.743899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.178926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-18T01:29:03.744001Z","caller":"traceutil/trace.go:171","msg":"trace[389250261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25091; }","duration":"131.309728ms","start":"2026-05-18T01:29:03.612667Z","end":"2026-05-18T01:29:03.743976Z","steps":["trace[389250261] 'range keys from in-memory index tree' (duration: 130.940982ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:07.321518Z","caller":"traceutil/trace.go:171","msg":"trace[363357972] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"110.27903ms","start":"2026-05-18T01:29:07.211212Z","end":"2026-05-18T01:29:07.321491Z","steps":["trace[363357972] 'process raft request' (duration: 110.102177ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:07.43784Z","caller":"traceutil/trace.go:171","msg":"trace[2024653428] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"226.097686ms","start":"2026-05-18T01:29:07.211712Z","end":"2026-05-18T01:29:07.43781Z","steps":["trace[2024653428] 'process raft request' (duration: 225.815191ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:07.438317Z","caller":"traceutil/trace.go:171","msg":"trace[1276634691] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"225.836253ms","start":"2026-05-18T01:29:07.212431Z","end":"2026-05-18T01:29:07.438267Z","steps":["trace[1276634691] 'process raft request' (duration: 225.324382ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:07.438488Z","caller":"traceutil/trace.go:171","msg":"trace[1675682361] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"225.620037ms","start":"2026-05-18T01:29:07.212851Z","end":"2026-05-18T01:29:07.438471Z","steps":["trace[1675682361] 'process raft request' (duration: 225.316482ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:07.438587Z","caller":"traceutil/trace.go:171","msg":"trace[1423685149] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"225.463235ms","start":"2026-05-18T01:29:07.21311Z","end":"2026-05-18T01:29:07.438573Z","steps":["trace[1423685149] 'process raft request' (duration: 225.266712ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:07.438573Z","caller":"traceutil/trace.go:171","msg":"trace[1803051619] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"225.602378ms","start":"2026-05-18T01:29:07.212948Z","end":"2026-05-18T01:29:07.43855Z","steps":["trace[1803051619] 'process raft request' (duration: 225.374054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:07.438807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.232661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:07.438907Z","caller":"traceutil/trace.go:171","msg":"trace[367443764] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25118; }","duration":"180.414175ms","start":"2026-05-18T01:29:07.258468Z","end":"2026-05-18T01:29:07.438882Z","steps":["trace[367443764] 'agreement among raft nodes before linearized reading' (duration: 180.228601ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:07.438472Z","caller":"traceutil/trace.go:171","msg":"trace[910944459] linearizableReadLoop","detail":"{readStateIndex:25946; appliedIndex:25940; }","duration":"179.888636ms","start":"2026-05-18T01:29:07.258543Z","end":"2026-05-18T01:29:07.438432Z","steps":["trace[910944459] 'read index received' (duration: 62.872197ms)","trace[910944459] 'applied index is now lower than readState.Index' (duration: 117.014649ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:30.028604Z","caller":"traceutil/trace.go:171","msg":"trace[1887086965] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"105.743865ms","start":"2026-05-18T01:29:29.922836Z","end":"2026-05-18T01:29:30.028579Z","steps":["trace[1887086965] 'process raft request' (duration: 105.102984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:30.300254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.074542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:30.300556Z","caller":"traceutil/trace.go:171","msg":"trace[1830322352] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"266.510349ms","start":"2026-05-18T01:29:30.034019Z","end":"2026-05-18T01:29:30.30053Z","steps":["trace[1830322352] 'process raft request' (duration: 125.004949ms)","trace[1830322352] 'compare' (duration: 140.704106ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:30.300943Z","caller":"traceutil/trace.go:171","msg":"trace[322966558] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"266.659793ms","start":"2026-05-18T01:29:30.034224Z","end":"2026-05-18T01:29:30.300883Z","steps":["trace[322966558] 'process raft request' (duration: 266.207053ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:30.301064Z","caller":"traceutil/trace.go:171","msg":"trace[1352381176] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"266.587952ms","start":"2026-05-18T01:29:30.034421Z","end":"2026-05-18T01:29:30.301009Z","steps":["trace[1352381176] 'process raft request' (duration: 266.389438ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:30.300972Z","caller":"traceutil/trace.go:171","msg":"trace[1477324944] linearizableReadLoop","detail":"{readStateIndex:26271; appliedIndex:26268; }","duration":"161.589151ms","start":"2026-05-18T01:29:30.139345Z","end":"2026-05-18T01:29:30.300934Z","steps":["trace[1477324944] 'read index received' (duration: 19.686672ms)","trace[1477324944] 'applied index is now lower than readState.Index' (duration: 141.900388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:30.30122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.853115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-05-18T01:29:30.301293Z","caller":"traceutil/trace.go:171","msg":"trace[1561414033] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25437; }","duration":"161.974757ms","start":"2026-05-18T01:29:30.139296Z","end":"2026-05-18T01:29:30.301271Z","steps":["trace[1561414033] 'agreement among raft nodes before linearized reading' (duration: 161.745202ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:31.070342Z","caller":"traceutil/trace.go:171","msg":"trace[1017380107] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"229.812307ms","start":"2026-05-18T01:29:30.840415Z","end":"2026-05-18T01:29:31.070227Z","steps":["trace[1017380107] 'process raft request' (duration: 229.370239ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:31.436187Z","caller":"traceutil/trace.go:171","msg":"trace[1525728612] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"179.335825ms","start":"2026-05-18T01:29:31.256815Z","end":"2026-05-18T01:29:31.436151Z","steps":["trace[1525728612] 'process raft request' (duration: 179.132751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:31.989908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.927123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:31.990169Z","caller":"traceutil/trace.go:171","msg":"trace[608666949] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"440.540184ms","start":"2026-05-18T01:29:31.549507Z","end":"2026-05-18T01:29:31.990048Z","steps":["trace[608666949] 'process raft request' (duration: 178.364877ms)","trace[608666949] 'compare' (duration: 261.725849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:31.990257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.549471Z","time spent":"440.748288ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:32.026537Z","caller":"traceutil/trace.go:171","msg":"trace[1874160353] linearizableReadLoop","detail":"{readStateIndex:26279; appliedIndex:26274; }","duration":"403.610796ms","start":"2026-05-18T01:29:31.622889Z","end":"2026-05-18T01:29:32.0265Z","steps":["trace[1874160353] 'read index received' (duration: 105.043552ms)","trace[1874160353] 'applied index is now lower than readState.Index' (duration: 298.566404ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:32.026591Z","caller":"traceutil/trace.go:171","msg":"trace[1965377118] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"471.516168ms","start":"2026-05-18T01:29:31.555044Z","end":"2026-05-18T01:29:32.02656Z","steps":["trace[1965377118] 'process raft request' (duration: 471.335315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.026724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.17678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:32.02672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.555032Z","time spent":"471.615581ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:32.026703Z","caller":"traceutil/trace.go:171","msg":"trace[589193786] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"467.971032ms","start":"2026-05-18T01:29:31.558678Z","end":"2026-05-18T01:29:32.026649Z","steps":["trace[589193786] 'process raft request' (duration: 467.754338ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.026664Z","caller":"traceutil/trace.go:171","msg":"trace[363233118] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"475.649806ms","start":"2026-05-18T01:29:31.55096Z","end":"2026-05-18T01:29:32.02661Z","steps":["trace[363233118] 'process raft request' (duration: 475.233699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.026859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.932051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:32.02689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.126637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-18T01:29:32.026918Z","caller":"traceutil/trace.go:171","msg":"trace[986840289] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25445; }","duration":"200.175118ms","start":"2026-05-18T01:29:31.826737Z","end":"2026-05-18T01:29:32.026912Z","steps":["trace[986840289] 'agreement among raft nodes before linearized reading' (duration: 200.051015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.026908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.558657Z","time spent":"468.144035ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:32.026922Z","caller":"traceutil/trace.go:171","msg":"trace[931458272] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25445; }","duration":"298.996703ms","start":"2026-05-18T01:29:31.727907Z","end":"2026-05-18T01:29:32.026904Z","steps":["trace[931458272] 'agreement among raft nodes before linearized reading' (duration: 298.929311ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.027057Z","caller":"traceutil/trace.go:171","msg":"trace[642484272] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"467.434802ms","start":"2026-05-18T01:29:31.559263Z","end":"2026-05-18T01:29:32.026697Z","steps":["trace[642484272] 'process raft request' (duration: 467.198887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.027039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.550926Z","time spent":"475.934702ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:32.027073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.358758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-18T01:29:32.027135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.512836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:32.027175Z","caller":"traceutil/trace.go:171","msg":"trace[1934569938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25445; }","duration":"353.510932ms","start":"2026-05-18T01:29:31.673641Z","end":"2026-05-18T01:29:32.027152Z","steps":["trace[1934569938] 'agreement among raft nodes before linearized reading' (duration: 353.238446ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.027193Z","caller":"traceutil/trace.go:171","msg":"trace[790155999] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25445; }","duration":"402.580598ms","start":"2026-05-18T01:29:31.624599Z","end":"2026-05-18T01:29:32.02718Z","steps":["trace[790155999] 'agreement among raft nodes before linearized reading' (duration: 402.483676ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.026767Z","caller":"traceutil/trace.go:171","msg":"trace[978509338] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25445; }","duration":"402.260981ms","start":"2026-05-18T01:29:31.624495Z","end":"2026-05-18T01:29:32.026756Z","steps":["trace[978509338] 'agreement among raft nodes before linearized reading' (duration: 402.171329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.027227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.624594Z","time spent":"402.625938ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-05-18T01:29:32.027246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.624486Z","time spent":"402.748481ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-05-18T01:29:32.027283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.524882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:32.026759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.83742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:32.027321Z","caller":"traceutil/trace.go:171","msg":"trace[248838221] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25445; }","duration":"299.620615ms","start":"2026-05-18T01:29:31.727691Z","end":"2026-05-18T01:29:32.027312Z","steps":["trace[248838221] 'agreement among raft nodes before linearized reading' (duration: 299.565614ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.027334Z","caller":"traceutil/trace.go:171","msg":"trace[284326277] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:25445; }","duration":"404.483113ms","start":"2026-05-18T01:29:31.622841Z","end":"2026-05-18T01:29:32.027324Z","steps":["trace[284326277] 'agreement among raft nodes before linearized reading' (duration: 403.82337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.027326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.667389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:32.02734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.261358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-18T01:29:32.027358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.622822Z","time spent":"404.529854ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"info","ts":"2026-05-18T01:29:32.027366Z","caller":"traceutil/trace.go:171","msg":"trace[202454520] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25445; }","duration":"402.713671ms","start":"2026-05-18T01:29:31.624641Z","end":"2026-05-18T01:29:32.027355Z","steps":["trace[202454520] 'agreement among raft nodes before linearized reading' (duration: 402.631689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.027232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.673628Z","time spent":"353.590473ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T01:29:32.027393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.624638Z","time spent":"402.750931ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-05-18T01:29:32.02743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.879491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:32.027388Z","caller":"traceutil/trace.go:171","msg":"trace[138490255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25445; }","duration":"290.33027ms","start":"2026-05-18T01:29:31.737046Z","end":"2026-05-18T01:29:32.027377Z","steps":["trace[138490255] 'agreement among raft nodes before linearized reading' (duration: 290.155447ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.027465Z","caller":"traceutil/trace.go:171","msg":"trace[1856876172] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25445; }","duration":"298.923292ms","start":"2026-05-18T01:29:31.728533Z","end":"2026-05-18T01:29:32.027456Z","steps":["trace[1856876172] 'agreement among raft nodes before linearized reading' (duration: 298.876031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.027543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.36258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:32.027632Z","caller":"traceutil/trace.go:171","msg":"trace[140382113] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:25445; }","duration":"299.506182ms","start":"2026-05-18T01:29:31.728112Z","end":"2026-05-18T01:29:32.027619Z","steps":["trace[140382113] 'agreement among raft nodes before linearized reading' (duration: 299.331659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.02772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:31.559249Z","time spent":"467.85304ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:32.567318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.347197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:32.567644Z","caller":"traceutil/trace.go:171","msg":"trace[397336057] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"534.06847ms","start":"2026-05-18T01:29:32.033535Z","end":"2026-05-18T01:29:32.567603Z","steps":["trace[397336057] 'process raft request' (duration: 259.273093ms)","trace[397336057] 'compare' (duration: 273.749657ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:32.567672Z","caller":"traceutil/trace.go:171","msg":"trace[627230911] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"534.015209ms","start":"2026-05-18T01:29:32.033639Z","end":"2026-05-18T01:29:32.567655Z","steps":["trace[627230911] 'process raft request' (duration: 533.882066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.567773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.03361Z","time spent":"534.108281ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:32.567791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.033506Z","time spent":"534.212032ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:32.567812Z","caller":"traceutil/trace.go:171","msg":"trace[1806711837] linearizableReadLoop","detail":"{readStateIndex:26280; appliedIndex:26279; }","duration":"534.09612ms","start":"2026-05-18T01:29:32.033578Z","end":"2026-05-18T01:29:32.567674Z","steps":["trace[1806711837] 'read index received' (duration: 259.246702ms)","trace[1806711837] 'applied index is now lower than readState.Index' (duration: 274.840968ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:32.567922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.310214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:32.568018Z","caller":"traceutil/trace.go:171","msg":"trace[947617497] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25447; }","duration":"534.427196ms","start":"2026-05-18T01:29:32.033569Z","end":"2026-05-18T01:29:32.567996Z","steps":["trace[947617497] 'agreement among raft nodes before linearized reading' (duration: 534.258623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.568101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.033512Z","time spent":"534.572298ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-05-18T01:29:32.670382Z","caller":"traceutil/trace.go:171","msg":"trace[1690973656] linearizableReadLoop","detail":"{readStateIndex:26282; appliedIndex:26281; }","duration":"102.373792ms","start":"2026-05-18T01:29:32.567972Z","end":"2026-05-18T01:29:32.670346Z","steps":["trace[1690973656] 'read index received' (duration: 98.972448ms)","trace[1690973656] 'applied index is now lower than readState.Index' (duration: 3.400074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:32.670549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.91039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:32.670662Z","caller":"traceutil/trace.go:171","msg":"trace[196143954] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25448; }","duration":"637.070063ms","start":"2026-05-18T01:29:32.033579Z","end":"2026-05-18T01:29:32.670649Z","steps":["trace[196143954] 'agreement among raft nodes before linearized reading' (duration: 636.899099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.670716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.033558Z","time spent":"637.145975ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-05-18T01:29:32.670707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.039512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:32.670713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.009792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-18T01:29:32.670744Z","caller":"traceutil/trace.go:171","msg":"trace[1828622247] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"276.154642ms","start":"2026-05-18T01:29:32.394551Z","end":"2026-05-18T01:29:32.670705Z","steps":["trace[1828622247] 'process raft request' (duration: 272.495573ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.670833Z","caller":"traceutil/trace.go:171","msg":"trace[1643859030] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:25448; }","duration":"637.164224ms","start":"2026-05-18T01:29:32.033621Z","end":"2026-05-18T01:29:32.670785Z","steps":["trace[1643859030] 'agreement among raft nodes before linearized reading' (duration: 636.999861ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.670854Z","caller":"traceutil/trace.go:171","msg":"trace[909189463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25448; }","duration":"637.179885ms","start":"2026-05-18T01:29:32.033649Z","end":"2026-05-18T01:29:32.670829Z","steps":["trace[909189463] 'agreement among raft nodes before linearized reading' (duration: 636.769287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.670886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.033614Z","time spent":"637.262346ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-05-18T01:29:32.670915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.03364Z","time spent":"637.266356ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T01:29:32.67097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.479568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-18T01:29:32.670945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.221663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-18T01:29:32.67104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.416689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:32.670624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.127881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:32.671085Z","caller":"traceutil/trace.go:171","msg":"trace[909797872] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25448; }","duration":"637.47688ms","start":"2026-05-18T01:29:32.033598Z","end":"2026-05-18T01:29:32.671075Z","steps":["trace[909797872] 'agreement among raft nodes before linearized reading' (duration: 637.409419ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.671074Z","caller":"traceutil/trace.go:171","msg":"trace[1810475556] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25448; }","duration":"365.429297ms","start":"2026-05-18T01:29:32.30561Z","end":"2026-05-18T01:29:32.671039Z","steps":["trace[1810475556] 'agreement among raft nodes before linearized reading' (duration: 365.007919ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.6711Z","caller":"traceutil/trace.go:171","msg":"trace[698816821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25448; }","duration":"232.695672ms","start":"2026-05-18T01:29:32.438391Z","end":"2026-05-18T01:29:32.671086Z","steps":["trace[698816821] 'agreement among raft nodes before linearized reading' (duration: 232.08398ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:32.67107Z","caller":"traceutil/trace.go:171","msg":"trace[851016458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25448; }","duration":"364.58797ms","start":"2026-05-18T01:29:32.306431Z","end":"2026-05-18T01:29:32.671019Z","steps":["trace[851016458] 'agreement among raft nodes before linearized reading' (duration: 364.406407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.671133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.033567Z","time spent":"637.559682ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-05-18T01:29:32.670708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.942316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-18T01:29:32.671158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.30555Z","time spent":"365.596891ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-18T01:29:32.671172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.306418Z","time spent":"364.741534ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-18T01:29:32.671236Z","caller":"traceutil/trace.go:171","msg":"trace[535687830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25448; }","duration":"365.534479ms","start":"2026-05-18T01:29:32.305676Z","end":"2026-05-18T01:29:32.67121Z","steps":["trace[535687830] 'agreement among raft nodes before linearized reading' (duration: 364.701553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:32.671294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:32.305549Z","time spent":"365.727203ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-18T01:30:04.582691Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067d3.wal"} {"level":"info","ts":"2026-05-18T01:30:40.497052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23222} {"level":"info","ts":"2026-05-18T01:30:40.655051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23222,"took":"153.13284ms","hash":2813728152,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":35979264,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-18T01:30:40.655129Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2813728152,"revision":23222,"compact-revision":20042} {"level":"info","ts":"2026-05-18T01:35:40.505315Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26069} {"level":"info","ts":"2026-05-18T01:35:40.642912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26069,"took":"132.413869ms","hash":2438096714,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":36782080,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-18T01:35:40.642979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2438096714,"revision":26069,"compact-revision":23222} {"level":"info","ts":"2026-05-18T01:36:29.69027Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T01:36:29.694724Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-05-18T01:36:29.694835Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-05-18T01:40:40.512086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28705} {"level":"info","ts":"2026-05-18T01:40:40.650527Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28705,"took":"133.078248ms","hash":2785038514,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":30724096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T01:40:40.650606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2785038514,"revision":28705,"compact-revision":26069} {"level":"info","ts":"2026-05-18T01:45:40.518672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30931} {"level":"info","ts":"2026-05-18T01:45:40.642453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30931,"took":"118.578084ms","hash":1929467817,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":32182272,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T01:45:40.642536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1929467817,"revision":30931,"compact-revision":28705} {"level":"info","ts":"2026-05-18T01:50:40.531857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33198} {"level":"info","ts":"2026-05-18T01:50:40.696897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33198,"took":"160.563474ms","hash":1426850401,"current-db-size-bytes":60624896,"current-db-size":"61 MB","current-db-size-in-use-bytes":32731136,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-18T01:50:40.696989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1426850401,"revision":33198,"compact-revision":30931} {"level":"info","ts":"2026-05-18T01:55:40.545351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35491} {"level":"info","ts":"2026-05-18T01:55:40.692018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35491,"took":"140.989176ms","hash":3974078290,"current-db-size-bytes":60624896,"current-db-size":"61 MB","current-db-size-in-use-bytes":30781440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T01:55:40.692115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3974078290,"revision":35491,"compact-revision":33198} {"level":"info","ts":"2026-05-18T01:57:59.238632Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T01:57:59.243224Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-05-18T01:57:59.243333Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-05-18T02:00:40.55434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37718} {"level":"info","ts":"2026-05-18T02:00:40.681201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37718,"took":"120.437309ms","hash":1042968893,"current-db-size-bytes":60624896,"current-db-size":"61 MB","current-db-size-in-use-bytes":31604736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T02:00:40.681268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1042968893,"revision":37718,"compact-revision":35491}