{"level":"warn","ts":"2026-04-17T18:44:12.044729Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T18:44:12.044881Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-17T18:44:12.04499Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T18:44:12.045046Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-17T18:44:12.045103Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T18:44:12.046089Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-04-17T18:44:12.046209Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-17T18:44:12.050117Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.570267ms"} {"level":"info","ts":"2026-04-17T18:44:12.05535Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-04-17T18:44:12.055437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T18:44:12.055462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-04-17T18:44:12.055479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T18:44:12.055489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-04-17T18:44:12.0556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-04-17T18:44:12.061397Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T18:44:12.062639Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T18:44:12.063838Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-17T18:44:12.066127Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T18:44:12.066409Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T18:44:12.066628Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T18:44:12.066912Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T18:44:12.067193Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T18:44:12.068184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-04-17T18:44:12.069664Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-17T18:44:12.071023Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T18:44:12.071186Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-17T18:44:12.071284Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-17T18:44:12.071542Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T18:44:12.071603Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T18:44:12.956521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T18:44:12.956597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T18:44:12.956637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-04-17T18:44:12.956657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-04-17T18:44:12.956669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-17T18:44:12.956686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-04-17T18:44:12.956706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-17T18:44:12.958006Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:44:12.958805Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T18:44:12.958874Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:44:12.959083Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:44:12.959573Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:44:12.959751Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:44:12.959809Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:44:12.960943Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T18:44:12.960979Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T18:44:12.962393Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T18:44:12.962545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-04-17T18:44:41.25626Z","caller":"traceutil/trace.go:171","msg":"trace[1791265465] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"118.555771ms","start":"2026-04-17T18:44:41.13767Z","end":"2026-04-17T18:44:41.256226Z","steps":["trace[1791265465] 'read index received' (duration: 23.89542ms)","trace[1791265465] 'applied index is now lower than readState.Index' (duration: 94.658771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:44:41.25653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.839029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-04-17T18:44:41.256664Z","caller":"traceutil/trace.go:171","msg":"trace[1786489329] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:430; }","duration":"119.011794ms","start":"2026-04-17T18:44:41.137622Z","end":"2026-04-17T18:44:41.256634Z","steps":["trace[1786489329] 'agreement among raft nodes before linearized reading' (duration: 118.702035ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:49.853775Z","caller":"traceutil/trace.go:171","msg":"trace[1259861917] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"292.182397ms","start":"2026-04-17T18:44:49.561568Z","end":"2026-04-17T18:44:49.85375Z","steps":["trace[1259861917] 'process raft request' (duration: 291.641972ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:49.859575Z","caller":"traceutil/trace.go:171","msg":"trace[1733620358] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"130.701777ms","start":"2026-04-17T18:44:49.728852Z","end":"2026-04-17T18:44:49.859554Z","steps":["trace[1733620358] 'read index received' (duration: 124.219592ms)","trace[1733620358] 'applied index is now lower than readState.Index' (duration: 6.481324ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:44:49.859607Z","caller":"traceutil/trace.go:171","msg":"trace[2136334135] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"296.535141ms","start":"2026-04-17T18:44:49.56305Z","end":"2026-04-17T18:44:49.859585Z","steps":["trace[2136334135] 'process raft request' (duration: 296.420928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:49.85967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.80801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:44:49.859731Z","caller":"traceutil/trace.go:171","msg":"trace[438022633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"130.895042ms","start":"2026-04-17T18:44:49.728799Z","end":"2026-04-17T18:44:49.859694Z","steps":["trace[438022633] 'agreement among raft nodes before linearized reading' (duration: 130.826351ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:50.344674Z","caller":"traceutil/trace.go:171","msg":"trace[283729656] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"436.861573ms","start":"2026-04-17T18:44:49.907783Z","end":"2026-04-17T18:44:50.344644Z","steps":["trace[283729656] 'process raft request' (duration: 436.712509ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:50.345051Z","caller":"traceutil/trace.go:171","msg":"trace[2079628631] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:604; }","duration":"385.801111ms","start":"2026-04-17T18:44:49.9592Z","end":"2026-04-17T18:44:50.345001Z","steps":["trace[2079628631] 'read index received' (duration: 385.77308ms)","trace[2079628631] 'applied index is now lower than readState.Index' (duration: 24.911µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:44:50.345229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.020077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c5br4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-17T18:44:50.345303Z","caller":"traceutil/trace.go:171","msg":"trace[620749542] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c5br4; range_end:; response_count:1; response_revision:580; }","duration":"386.13053ms","start":"2026-04-17T18:44:49.959154Z","end":"2026-04-17T18:44:50.345284Z","steps":["trace[620749542] 'agreement among raft nodes before linearized reading' (duration: 385.970296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:50.345338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:49.959135Z","time spent":"386.196433ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-c5br4\" "} {"level":"warn","ts":"2026-04-17T18:44:50.34537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:49.907756Z","time spent":"437.016407ms","remote":"127.0.0.1:35734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:46:21.491321Z","caller":"traceutil/trace.go:171","msg":"trace[892764974] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1878; }","duration":"220.974114ms","start":"2026-04-17T18:46:21.270314Z","end":"2026-04-17T18:46:21.491288Z","steps":["trace[892764974] 'read index received' (duration: 148.020239ms)","trace[892764974] 'applied index is now lower than readState.Index' (duration: 72.949015ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:46:21.491593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.236666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:46:21.491634Z","caller":"traceutil/trace.go:171","msg":"trace[40344678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1808; }","duration":"155.340698ms","start":"2026-04-17T18:46:21.336286Z","end":"2026-04-17T18:46:21.491627Z","steps":["trace[40344678] 'agreement among raft nodes before linearized reading' (duration: 155.167584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:21.491606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.269253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-17T18:46:21.491745Z","caller":"traceutil/trace.go:171","msg":"trace[1065773102] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1808; }","duration":"221.464938ms","start":"2026-04-17T18:46:21.27026Z","end":"2026-04-17T18:46:21.491724Z","steps":["trace[1065773102] 'agreement among raft nodes before linearized reading' (duration: 221.134559ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:21.61718Z","caller":"traceutil/trace.go:171","msg":"trace[104243253] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"118.446494ms","start":"2026-04-17T18:46:21.498702Z","end":"2026-04-17T18:46:21.617148Z","steps":["trace[104243253] 'process raft request' (duration: 118.358962ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:21.617224Z","caller":"traceutil/trace.go:171","msg":"trace[620944084] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"120.981017ms","start":"2026-04-17T18:46:21.496209Z","end":"2026-04-17T18:46:21.61719Z","steps":["trace[620944084] 'process raft request' (duration: 120.666298ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:21.912951Z","caller":"traceutil/trace.go:171","msg":"trace[303339341] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"130.17403ms","start":"2026-04-17T18:46:21.78275Z","end":"2026-04-17T18:46:21.912924Z","steps":["trace[303339341] 'process raft request' (duration: 129.976035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:05.919792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.447128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:47:05.919915Z","caller":"traceutil/trace.go:171","msg":"trace[421393830] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"161.964126ms","start":"2026-04-17T18:47:05.757933Z","end":"2026-04-17T18:47:05.919897Z","steps":["trace[421393830] 'read index received' (duration: 50.898681ms)","trace[421393830] 'applied index is now lower than readState.Index' (duration: 111.063765ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:05.920024Z","caller":"traceutil/trace.go:171","msg":"trace[1949994288] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"164.336973ms","start":"2026-04-17T18:47:05.755633Z","end":"2026-04-17T18:47:05.91997Z","steps":["trace[1949994288] 'process raft request' (duration: 53.291008ms)","trace[1949994288] 'compare' (duration: 110.310605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:47:05.920103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.171042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12380"} {"level":"info","ts":"2026-04-17T18:47:05.920138Z","caller":"traceutil/trace.go:171","msg":"trace[1396344851] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2105; }","duration":"162.204643ms","start":"2026-04-17T18:47:05.757922Z","end":"2026-04-17T18:47:05.920126Z","steps":["trace[1396344851] 'agreement among raft nodes before linearized reading' (duration: 162.067459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:05.920413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.595243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:47:05.920527Z","caller":"traceutil/trace.go:171","msg":"trace[1723535094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2105; }","duration":"110.757796ms","start":"2026-04-17T18:47:05.809745Z","end":"2026-04-17T18:47:05.920503Z","steps":["trace[1723535094] 'agreement among raft nodes before linearized reading' (duration: 110.439287ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:11.85586Z","caller":"traceutil/trace.go:171","msg":"trace[1128055343] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2218; }","duration":"128.197043ms","start":"2026-04-17T18:47:11.727634Z","end":"2026-04-17T18:47:11.855831Z","steps":["trace[1128055343] 'read index received' (duration: 75.347917ms)","trace[1128055343] 'applied index is now lower than readState.Index' (duration: 52.847896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:47:11.856092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.442011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:11.856157Z","caller":"traceutil/trace.go:171","msg":"trace[1451167532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2136; }","duration":"128.557054ms","start":"2026-04-17T18:47:11.727577Z","end":"2026-04-17T18:47:11.856134Z","steps":["trace[1451167532] 'agreement among raft nodes before linearized reading' (duration: 128.367568ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:11.856096Z","caller":"traceutil/trace.go:171","msg":"trace[85635544] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"138.183258ms","start":"2026-04-17T18:47:11.717722Z","end":"2026-04-17T18:47:11.855906Z","steps":["trace[85635544] 'process raft request' (duration: 85.25621ms)","trace[85635544] 'compare' (duration: 52.707932ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:49:47.069304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338883269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:49:47.569931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338883269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:49:48.071003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338883269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:49:48.568957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000218166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T18:49:48.569042Z","caller":"traceutil/trace.go:171","msg":"trace[407842094] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00035697s","start":"2026-04-17T18:49:46.568663Z","end":"2026-04-17T18:49:48.56902Z","steps":["trace[407842094] 'agreement among raft nodes before linearized reading' (duration: 2.000242737s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.569076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.568651Z","time spent":"2.000418042s","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:49:48.571383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338883269,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:49:48.57247Z","caller":"traceutil/trace.go:171","msg":"trace[1455474419] transaction","detail":"{read_only:false; response_revision:3165; number_of_response:1; }","duration":"2.004971432s","start":"2026-04-17T18:49:46.567489Z","end":"2026-04-17T18:49:48.572461Z","steps":["trace[1455474419] 'process raft request' (duration: 2.004871789s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.572557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.567473Z","time spent":"2.005018703s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:49:48.585605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.567438Z","time spent":"2.018156807s","remote":"127.0.0.1:35256","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T18:49:48.585734Z","caller":"traceutil/trace.go:171","msg":"trace[529326498] transaction","detail":"{read_only:false; response_revision:3168; number_of_response:1; }","duration":"1.42980036s","start":"2026-04-17T18:49:47.155904Z","end":"2026-04-17T18:49:48.585704Z","steps":["trace[529326498] 'process raft request' (duration: 1.429715918s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:48.58584Z","caller":"traceutil/trace.go:171","msg":"trace[439596375] transaction","detail":"{read_only:false; response_revision:3167; number_of_response:1; }","duration":"1.468521591s","start":"2026-04-17T18:49:47.117272Z","end":"2026-04-17T18:49:48.585794Z","steps":["trace[439596375] 'process raft request' (duration: 1.468299645s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.585907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.155884Z","time spent":"1.429935595s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:49:48.585933Z","caller":"traceutil/trace.go:171","msg":"trace[1338916681] linearizableReadLoop","detail":"{readStateIndex:3293; appliedIndex:3287; }","duration":"2.017204139s","start":"2026-04-17T18:49:46.568712Z","end":"2026-04-17T18:49:48.585916Z","steps":["trace[1338916681] 'read index received' (duration: 2.003717285s)","trace[1338916681] 'applied index is now lower than readState.Index' (duration: 13.485504ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:49:48.586087Z","caller":"traceutil/trace.go:171","msg":"trace[239606407] transaction","detail":"{read_only:false; response_revision:3169; number_of_response:1; }","duration":"814.492668ms","start":"2026-04-17T18:49:47.771581Z","end":"2026-04-17T18:49:48.586073Z","steps":["trace[239606407] 'process raft request' (duration: 814.069736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.11724Z","time spent":"1.468699686s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:49:48.586188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.77155Z","time spent":"814.574731ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:49:48.585755Z","caller":"traceutil/trace.go:171","msg":"trace[1913564453] transaction","detail":"{read_only:false; response_revision:3166; number_of_response:1; }","duration":"2.0157776s","start":"2026-04-17T18:49:46.569949Z","end":"2026-04-17T18:49:48.585727Z","steps":["trace[1913564453] 'process raft request' (duration: 2.015524072s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.56993Z","time spent":"2.016348335s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:49:48.586423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764157252s","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-04-17T18:49:48.586481Z","caller":"traceutil/trace.go:171","msg":"trace[1057080176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3169; }","duration":"1.764276295s","start":"2026-04-17T18:49:46.822192Z","end":"2026-04-17T18:49:48.586468Z","steps":["trace[1057080176] 'agreement among raft nodes before linearized reading' (duration: 1.764114011s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.822154Z","time spent":"1.764355677s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T18:49:48.58662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278729373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:49:48.586663Z","caller":"traceutil/trace.go:171","msg":"trace[1780725845] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3169; }","duration":"1.278822695s","start":"2026-04-17T18:49:47.307829Z","end":"2026-04-17T18:49:48.586652Z","steps":["trace[1780725845] 'agreement among raft nodes before linearized reading' (duration: 1.278741113s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182166967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T18:49:48.586719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632105835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T18:49:48.586747Z","caller":"traceutil/trace.go:171","msg":"trace[1481462314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3169; }","duration":"1.182258939s","start":"2026-04-17T18:49:47.404471Z","end":"2026-04-17T18:49:48.58673Z","steps":["trace[1481462314] 'agreement among raft nodes before linearized reading' (duration: 1.182090084s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:48.586758Z","caller":"traceutil/trace.go:171","msg":"trace[1201545563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3169; }","duration":"1.632193657s","start":"2026-04-17T18:49:46.954552Z","end":"2026-04-17T18:49:48.586746Z","steps":["trace[1201545563] 'agreement among raft nodes before linearized reading' (duration: 1.632068674s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66916995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:49:48.586788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764412249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T18:49:48.586832Z","caller":"traceutil/trace.go:171","msg":"trace[2088655111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3169; }","duration":"1.764505211s","start":"2026-04-17T18:49:46.822318Z","end":"2026-04-17T18:49:48.586823Z","steps":["trace[2088655111] 'agreement among raft nodes before linearized reading' (duration: 1.764402798s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389092293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T18:49:48.586791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.954529Z","time spent":"1.632254559s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:49:48.586872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449189881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T18:49:48.586792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.404455Z","time spent":"1.182325921s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:49:48.586907Z","caller":"traceutil/trace.go:171","msg":"trace[1598673939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3169; }","duration":"1.449267324s","start":"2026-04-17T18:49:47.137631Z","end":"2026-04-17T18:49:48.586898Z","steps":["trace[1598673939] 'agreement among raft nodes before linearized reading' (duration: 1.449179972s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036162884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:49:48.586935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.137612Z","time spent":"1.449317575s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T18:49:48.58695Z","caller":"traceutil/trace.go:171","msg":"trace[775259497] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3169; }","duration":"1.036298037s","start":"2026-04-17T18:49:47.550636Z","end":"2026-04-17T18:49:48.586934Z","steps":["trace[775259497] 'agreement among raft nodes before linearized reading' (duration: 1.036183554s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090105257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"warn","ts":"2026-04-17T18:49:48.58699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.550612Z","time spent":"1.03636504s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-17T18:49:48.587018Z","caller":"traceutil/trace.go:171","msg":"trace[1004063790] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3169; }","duration":"1.090165689s","start":"2026-04-17T18:49:47.496843Z","end":"2026-04-17T18:49:48.587009Z","steps":["trace[1004063790] 'agreement among raft nodes before linearized reading' (duration: 1.090032236s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.586701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.307796Z","time spent":"1.278895528s","remote":"127.0.0.1:35674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:49:48.586865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.822297Z","time spent":"1.764562833s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:49:48.586903Z","caller":"traceutil/trace.go:171","msg":"trace[245046873] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:3169; }","duration":"1.389215226s","start":"2026-04-17T18:49:47.197674Z","end":"2026-04-17T18:49:48.586889Z","steps":["trace[245046873] 'agreement among raft nodes before linearized reading' (duration: 1.38900739s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:48.586848Z","caller":"traceutil/trace.go:171","msg":"trace[1588200711] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3169; }","duration":"1.669326464s","start":"2026-04-17T18:49:46.917492Z","end":"2026-04-17T18:49:48.586819Z","steps":["trace[1588200711] 'agreement among raft nodes before linearized reading' (duration: 1.669143479s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.587433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.917471Z","time spent":"1.66993279s","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:49:48.587485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.338955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-04-17T18:49:48.587639Z","caller":"traceutil/trace.go:171","msg":"trace[1292486454] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3169; }","duration":"280.573441ms","start":"2026-04-17T18:49:48.307043Z","end":"2026-04-17T18:49:48.587617Z","steps":["trace[1292486454] 'agreement among raft nodes before linearized reading' (duration: 279.704896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.587051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.496828Z","time spent":"1.09021703s","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1469,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-04-17T18:49:48.587272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.197657Z","time spent":"1.389551616s","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":59,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-17T18:50:23.492362Z","caller":"traceutil/trace.go:171","msg":"trace[899920540] linearizableReadLoop","detail":"{readStateIndex:3527; appliedIndex:3526; }","duration":"570.674922ms","start":"2026-04-17T18:50:22.921667Z","end":"2026-04-17T18:50:23.492342Z","steps":["trace[899920540] 'read index received' (duration: 25.931436ms)","trace[899920540] 'applied index is now lower than readState.Index' (duration: 544.742866ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:50:23.492386Z","caller":"traceutil/trace.go:171","msg":"trace[1376646571] transaction","detail":"{read_only:false; response_revision:3393; number_of_response:1; }","duration":"570.739563ms","start":"2026-04-17T18:50:22.921618Z","end":"2026-04-17T18:50:23.492357Z","steps":["trace[1376646571] 'process raft request' (duration: 570.429165ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:50:23.492468Z","caller":"traceutil/trace.go:171","msg":"trace[1473862777] transaction","detail":"{read_only:false; response_revision:3394; number_of_response:1; }","duration":"570.764164ms","start":"2026-04-17T18:50:22.921693Z","end":"2026-04-17T18:50:23.492458Z","steps":["trace[1473862777] 'process raft request' (duration: 570.582569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.492556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.92169Z","time spent":"570.798535ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:50:23.492559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.921611Z","time spent":"570.843126ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:50:23.492761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.091625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T18:50:23.492788Z","caller":"traceutil/trace.go:171","msg":"trace[695794125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3394; }","duration":"571.120155ms","start":"2026-04-17T18:50:22.921662Z","end":"2026-04-17T18:50:23.492782Z","steps":["trace[695794125] 'agreement among raft nodes before linearized reading' (duration: 571.054484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.492807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.921641Z","time spent":"571.162817ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:51:00.159419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338884674,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:51:00.169035Z","caller":"traceutil/trace.go:171","msg":"trace[412480311] linearizableReadLoop","detail":"{readStateIndex:3878; appliedIndex:3877; }","duration":"510.193493ms","start":"2026-04-17T18:50:59.658816Z","end":"2026-04-17T18:51:00.16901Z","steps":["trace[412480311] 'read index received' (duration: 507.386453ms)","trace[412480311] 'applied index is now lower than readState.Index' (duration: 2.80597ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:51:00.169169Z","caller":"traceutil/trace.go:171","msg":"trace[1004905305] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"516.000697ms","start":"2026-04-17T18:50:59.653128Z","end":"2026-04-17T18:51:00.169128Z","steps":["trace[1004905305] 'process raft request' (duration: 513.127196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.169324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.954637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T18:51:00.169349Z","caller":"traceutil/trace.go:171","msg":"trace[497085697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3734; }","duration":"497.015238ms","start":"2026-04-17T18:50:59.672326Z","end":"2026-04-17T18:51:00.169341Z","steps":["trace[497085697] 'agreement among raft nodes before linearized reading' (duration: 496.969077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.16937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.672295Z","time spent":"497.06949ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:51:00.169428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.653106Z","time spent":"516.199913ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:51:00.169496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.4853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:51:00.169514Z","caller":"traceutil/trace.go:171","msg":"trace[1501292076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3734; }","duration":"440.563472ms","start":"2026-04-17T18:50:59.728944Z","end":"2026-04-17T18:51:00.169508Z","steps":["trace[1501292076] 'agreement among raft nodes before linearized reading' (duration: 440.536861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.169528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.728883Z","time spent":"440.641093ms","remote":"127.0.0.1:35206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:51:00.169269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.44383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T18:51:00.169663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.020966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"warn","ts":"2026-04-17T18:51:00.169672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.974877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:51:00.169675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.497814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T18:51:00.169681Z","caller":"traceutil/trace.go:171","msg":"trace[1140491438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3734; }","duration":"510.890842ms","start":"2026-04-17T18:50:59.65876Z","end":"2026-04-17T18:51:00.169651Z","steps":["trace[1140491438] 'agreement among raft nodes before linearized reading' (duration: 510.401458ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:00.169705Z","caller":"traceutil/trace.go:171","msg":"trace[683558653] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3734; }","duration":"151.103778ms","start":"2026-04-17T18:51:00.018584Z","end":"2026-04-17T18:51:00.169688Z","steps":["trace[683558653] 'agreement among raft nodes before linearized reading' (duration: 151.004895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.169725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.042037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-17T18:51:00.169731Z","caller":"traceutil/trace.go:171","msg":"trace[2069638753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3734; }","duration":"489.597467ms","start":"2026-04-17T18:50:59.680125Z","end":"2026-04-17T18:51:00.169722Z","steps":["trace[2069638753] 'agreement among raft nodes before linearized reading' (duration: 489.438423ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:00.16975Z","caller":"traceutil/trace.go:171","msg":"trace[262872004] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3734; }","duration":"273.10729ms","start":"2026-04-17T18:50:59.896634Z","end":"2026-04-17T18:51:00.169742Z","steps":["trace[262872004] 'agreement among raft nodes before linearized reading' (duration: 273.039698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.169752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.680113Z","time spent":"489.633458ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T18:51:00.169739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.549531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T18:51:00.169739Z","caller":"traceutil/trace.go:171","msg":"trace[1138062785] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:3734; }","duration":"201.092611ms","start":"2026-04-17T18:50:59.968628Z","end":"2026-04-17T18:51:00.16972Z","steps":["trace[1138062785] 'agreement among raft nodes before linearized reading' (duration: 200.976718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.169759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.658737Z","time spent":"511.001755ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T18:51:00.169791Z","caller":"traceutil/trace.go:171","msg":"trace[1026846011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3734; }","duration":"492.641163ms","start":"2026-04-17T18:50:59.677134Z","end":"2026-04-17T18:51:00.169775Z","steps":["trace[1026846011] 'agreement among raft nodes before linearized reading' (duration: 492.480449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.169833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.677111Z","time spent":"492.709706ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:51:00.169541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.651467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-17T18:51:00.169892Z","caller":"traceutil/trace.go:171","msg":"trace[238644661] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3734; }","duration":"369.029727ms","start":"2026-04-17T18:50:59.800842Z","end":"2026-04-17T18:51:00.169872Z","steps":["trace[238644661] 'agreement among raft nodes before linearized reading' (duration: 368.538463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.16988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.983525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T18:51:00.169937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.80083Z","time spent":"369.09403ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-17T18:51:00.169976Z","caller":"traceutil/trace.go:171","msg":"trace[1843374206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3734; }","duration":"492.125279ms","start":"2026-04-17T18:50:59.677828Z","end":"2026-04-17T18:51:00.169953Z","steps":["trace[1843374206] 'agreement among raft nodes before linearized reading' (duration: 491.726908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.170034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.677814Z","time spent":"492.211161ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:51:07.739928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338884776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:51:08.240837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338884776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:51:08.741533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338884776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:51:09.126584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.887295734s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:51:09.127433Z","caller":"traceutil/trace.go:171","msg":"trace[1705623953] transaction","detail":"{read_only:false; response_revision:3771; number_of_response:1; }","duration":"1.888244822s","start":"2026-04-17T18:51:07.239162Z","end":"2026-04-17T18:51:09.127407Z","steps":["trace[1705623953] 'process raft request' (duration: 1.888059296s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.127629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:07.239136Z","time spent":"1.888372216s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:51:09.133536Z","caller":"traceutil/trace.go:171","msg":"trace[1385093223] linearizableReadLoop","detail":"{readStateIndex:3920; appliedIndex:3916; }","duration":"1.894231452s","start":"2026-04-17T18:51:07.239282Z","end":"2026-04-17T18:51:09.133514Z","steps":["trace[1385093223] 'read index received' (duration: 1.887850549s)","trace[1385093223] 'applied index is now lower than readState.Index' (duration: 6.379993ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:51:09.133619Z","caller":"traceutil/trace.go:171","msg":"trace[1262242785] transaction","detail":"{read_only:false; response_revision:3772; number_of_response:1; }","duration":"1.891480234s","start":"2026-04-17T18:51:07.242099Z","end":"2026-04-17T18:51:09.133579Z","steps":["trace[1262242785] 'process raft request' (duration: 1.891183635s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:09.133686Z","caller":"traceutil/trace.go:171","msg":"trace[985364332] transaction","detail":"{read_only:false; response_revision:3774; number_of_response:1; }","duration":"1.483133078s","start":"2026-04-17T18:51:07.650544Z","end":"2026-04-17T18:51:09.133677Z","steps":["trace[985364332] 'process raft request' (duration: 1.482926353s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.133764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:07.242076Z","time spent":"1.891608977s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:51:09.133814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:07.65052Z","time spent":"1.48319786s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:51:09.133652Z","caller":"traceutil/trace.go:171","msg":"trace[1962520289] transaction","detail":"{read_only:false; response_revision:3773; number_of_response:1; }","duration":"1.671603269s","start":"2026-04-17T18:51:07.462033Z","end":"2026-04-17T18:51:09.133637Z","steps":["trace[1962520289] 'process raft request' (duration: 1.671391923s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.133963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:07.462Z","time spent":"1.671932968s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:51:09.134128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.894839069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T18:51:09.134164Z","caller":"traceutil/trace.go:171","msg":"trace[1291746976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3774; }","duration":"1.89489523s","start":"2026-04-17T18:51:07.239259Z","end":"2026-04-17T18:51:09.134154Z","steps":["trace[1291746976] 'agreement among raft nodes before linearized reading' (duration: 1.894671223s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.134198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:07.239252Z","time spent":"1.894939092s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:51:09.13426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.850854278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:51:09.13433Z","caller":"traceutil/trace.go:171","msg":"trace[1824009215] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3774; }","duration":"1.850971401s","start":"2026-04-17T18:51:07.28334Z","end":"2026-04-17T18:51:09.134311Z","steps":["trace[1824009215] 'agreement among raft nodes before linearized reading' (duration: 1.850863378s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.134378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:07.283316Z","time spent":"1.851052304s","remote":"127.0.0.1:35684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-17T18:51:09.134493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.277364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:51:09.134543Z","caller":"traceutil/trace.go:171","msg":"trace[954776615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3774; }","duration":"922.368596ms","start":"2026-04-17T18:51:08.212161Z","end":"2026-04-17T18:51:09.13453Z","steps":["trace[954776615] 'agreement among raft nodes before linearized reading' (duration: 922.216072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.134589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:08.212141Z","time spent":"922.437269ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T18:51:09.134704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67095521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:51:09.134747Z","caller":"traceutil/trace.go:171","msg":"trace[1103922360] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:3774; }","duration":"1.671037112s","start":"2026-04-17T18:51:07.463699Z","end":"2026-04-17T18:51:09.134736Z","steps":["trace[1103922360] 'agreement among raft nodes before linearized reading' (duration: 1.670912968s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.134694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017676688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:51:09.134825Z","caller":"traceutil/trace.go:171","msg":"trace[69626648] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:3774; }","duration":"1.017860113s","start":"2026-04-17T18:51:08.116949Z","end":"2026-04-17T18:51:09.134809Z","steps":["trace[69626648] 'agreement among raft nodes before linearized reading' (duration: 1.017542774s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.134865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.006515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:51:09.134911Z","caller":"traceutil/trace.go:171","msg":"trace[1631483497] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:3774; }","duration":"383.087147ms","start":"2026-04-17T18:51:08.751813Z","end":"2026-04-17T18:51:09.1349Z","steps":["trace[1631483497] 'agreement among raft nodes before linearized reading' (duration: 383.016775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.134963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:08.751795Z","time spent":"383.157859ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:51:09.135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.408986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:51:09.135037Z","caller":"traceutil/trace.go:171","msg":"trace[145463260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3774; }","duration":"923.480898ms","start":"2026-04-17T18:51:08.211547Z","end":"2026-04-17T18:51:09.135028Z","steps":["trace[145463260] 'agreement among raft nodes before linearized reading' (duration: 923.390606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.135068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:08.211523Z","time spent":"923.537719ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:51:09.134593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404730587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:51:09.135103Z","caller":"traceutil/trace.go:171","msg":"trace[1958610464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3774; }","duration":"1.405239661s","start":"2026-04-17T18:51:07.729853Z","end":"2026-04-17T18:51:09.135093Z","steps":["trace[1958610464] 'agreement among raft nodes before linearized reading' (duration: 1.404694306s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.135107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.828436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-17T18:51:09.135177Z","caller":"traceutil/trace.go:171","msg":"trace[2096198474] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3774; }","duration":"918.898928ms","start":"2026-04-17T18:51:08.216266Z","end":"2026-04-17T18:51:09.135165Z","steps":["trace[2096198474] 'agreement among raft nodes before linearized reading' (duration: 918.721473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.13516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:08.116924Z","time spent":"1.017969275s","remote":"127.0.0.1:33084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:51:09.135313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.71041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T18:51:09.134776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:07.463681Z","time spent":"1.671087104s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-x8dcz\" "} {"level":"warn","ts":"2026-04-17T18:51:09.135142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:07.729801Z","time spent":"1.405332964s","remote":"127.0.0.1:35206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:51:09.13534Z","caller":"traceutil/trace.go:171","msg":"trace[695539447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3774; }","duration":"906.784113ms","start":"2026-04-17T18:51:08.22855Z","end":"2026-04-17T18:51:09.135334Z","steps":["trace[695539447] 'agreement among raft nodes before linearized reading' (duration: 906.69581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.135366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:08.228537Z","time spent":"906.820943ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T18:51:09.13523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:08.216231Z","time spent":"918.96822ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-17T18:52:34.194163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.443636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:52:34.194362Z","caller":"traceutil/trace.go:171","msg":"trace[624009563] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"111.449168ms","start":"2026-04-17T18:52:34.082889Z","end":"2026-04-17T18:52:34.194338Z","steps":["trace[624009563] 'compare' (duration: 105.284251ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:45.570316Z","caller":"traceutil/trace.go:171","msg":"trace[124697926] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"104.87415ms","start":"2026-04-17T18:52:45.465406Z","end":"2026-04-17T18:52:45.57028Z","steps":["trace[124697926] 'process raft request' (duration: 45.312557ms)","trace[124697926] 'compare' (duration: 58.617566ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:52:45.570599Z","caller":"traceutil/trace.go:171","msg":"trace[495641397] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"102.204054ms","start":"2026-04-17T18:52:45.468361Z","end":"2026-04-17T18:52:45.570565Z","steps":["trace[495641397] 'process raft request' (duration: 101.246947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:45.891611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.838739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:52:45.891727Z","caller":"traceutil/trace.go:171","msg":"trace[295278187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5171; }","duration":"162.005304ms","start":"2026-04-17T18:52:45.729681Z","end":"2026-04-17T18:52:45.891686Z","steps":["trace[295278187] 'range keys from in-memory index tree' (duration: 161.736887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:45.891742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.815523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-9psqv\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-17T18:52:45.891809Z","caller":"traceutil/trace.go:171","msg":"trace[1439388783] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-9psqv; range_end:; response_count:1; response_revision:5171; }","duration":"196.956197ms","start":"2026-04-17T18:52:45.69483Z","end":"2026-04-17T18:52:45.891786Z","steps":["trace[1439388783] 'range keys from in-memory index tree' (duration: 196.578576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:45.891945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.309412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:52:45.892024Z","caller":"traceutil/trace.go:171","msg":"trace[1582419951] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:5171; }","duration":"219.440256ms","start":"2026-04-17T18:52:45.672564Z","end":"2026-04-17T18:52:45.892004Z","steps":["trace[1582419951] 'range keys from in-memory index tree' (duration: 219.075335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:45.89214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.671222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-17T18:52:45.892494Z","caller":"traceutil/trace.go:171","msg":"trace[652563657] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5171; }","duration":"220.049923ms","start":"2026-04-17T18:52:45.672422Z","end":"2026-04-17T18:52:45.892472Z","steps":["trace[652563657] 'range keys from in-memory index tree' (duration: 219.376454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:54.286769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.612744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c174a7e-4d28-45f3-83e8-a16a42c8e109\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-17T18:52:54.287057Z","caller":"traceutil/trace.go:171","msg":"trace[1222624100] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4c174a7e-4d28-45f3-83e8-a16a42c8e109; range_end:; response_count:1; response_revision:5344; }","duration":"173.946993ms","start":"2026-04-17T18:52:54.113095Z","end":"2026-04-17T18:52:54.287042Z","steps":["trace[1222624100] 'range keys from in-memory index tree' (duration: 173.436789ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:05.823904Z","caller":"traceutil/trace.go:171","msg":"trace[1146016670] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"112.585889ms","start":"2026-04-17T18:53:05.711277Z","end":"2026-04-17T18:53:05.823863Z","steps":["trace[1146016670] 'process raft request' (duration: 18.505226ms)","trace[1146016670] 'compare' (duration: 93.800755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:53:52.495691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.61167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:53:52.495937Z","caller":"traceutil/trace.go:171","msg":"trace[1429021025] linearizableReadLoop","detail":"{readStateIndex:6134; appliedIndex:6131; }","duration":"208.937935ms","start":"2026-04-17T18:53:52.286967Z","end":"2026-04-17T18:53:52.495905Z","steps":["trace[1429021025] 'read index received' (duration: 71.978695ms)","trace[1429021025] 'applied index is now lower than readState.Index' (duration: 136.95829ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:52.495955Z","caller":"traceutil/trace.go:171","msg":"trace[1986483265] transaction","detail":"{read_only:false; response_revision:5916; number_of_response:1; }","duration":"341.512542ms","start":"2026-04-17T18:53:52.154396Z","end":"2026-04-17T18:53:52.495908Z","steps":["trace[1986483265] 'process raft request' (duration: 204.568242ms)","trace[1986483265] 'compare' (duration: 136.152158ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:52.495977Z","caller":"traceutil/trace.go:171","msg":"trace[1000320089] transaction","detail":"{read_only:false; response_revision:5917; number_of_response:1; }","duration":"341.385329ms","start":"2026-04-17T18:53:52.154543Z","end":"2026-04-17T18:53:52.495928Z","steps":["trace[1000320089] 'process raft request' (duration: 341.291296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:52.496175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.204913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:53:52.496198Z","caller":"traceutil/trace.go:171","msg":"trace[2010600193] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:5918; }","duration":"209.262685ms","start":"2026-04-17T18:53:52.286928Z","end":"2026-04-17T18:53:52.49619Z","steps":["trace[2010600193] 'agreement among raft nodes before linearized reading' (duration: 209.09071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:52.496182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.929924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-17T18:53:52.496182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:53:52.154381Z","time spent":"341.682097ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:53:52.496183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:53:52.154516Z","time spent":"341.558605ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:53:52.496061Z","caller":"traceutil/trace.go:171","msg":"trace[2138106405] transaction","detail":"{read_only:false; response_revision:5918; number_of_response:1; }","duration":"341.40269ms","start":"2026-04-17T18:53:52.154583Z","end":"2026-04-17T18:53:52.495985Z","steps":["trace[2138106405] 'process raft request' (duration: 341.286136ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:52.496252Z","caller":"traceutil/trace.go:171","msg":"trace[664531322] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5918; }","duration":"180.982936ms","start":"2026-04-17T18:53:52.315238Z","end":"2026-04-17T18:53:52.496221Z","steps":["trace[664531322] 'agreement among raft nodes before linearized reading' (duration: 180.841302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:52.496444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.676638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"warn","ts":"2026-04-17T18:53:52.496438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:53:52.154571Z","time spent":"341.703918ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:53:52.496479Z","caller":"traceutil/trace.go:171","msg":"trace[1607030924] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5918; }","duration":"157.730579ms","start":"2026-04-17T18:53:52.338739Z","end":"2026-04-17T18:53:52.496469Z","steps":["trace[1607030924] 'agreement among raft nodes before linearized reading' (duration: 157.618275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:52.49677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.549598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:53:52.496823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.554909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:53:52.496875Z","caller":"traceutil/trace.go:171","msg":"trace[28158447] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5918; }","duration":"160.688262ms","start":"2026-04-17T18:53:52.336154Z","end":"2026-04-17T18:53:52.496842Z","steps":["trace[28158447] 'agreement among raft nodes before linearized reading' (duration: 160.319442ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:52.496937Z","caller":"traceutil/trace.go:171","msg":"trace[1742106165] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5918; }","duration":"160.780475ms","start":"2026-04-17T18:53:52.336138Z","end":"2026-04-17T18:53:52.496919Z","steps":["trace[1742106165] 'agreement among raft nodes before linearized reading' (duration: 160.338993ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:52.666119Z","caller":"traceutil/trace.go:171","msg":"trace[1890282805] transaction","detail":"{read_only:false; response_revision:5919; number_of_response:1; }","duration":"164.742887ms","start":"2026-04-17T18:53:52.501327Z","end":"2026-04-17T18:53:52.66607Z","steps":["trace[1890282805] 'process raft request' (duration: 151.562835ms)","trace[1890282805] 'compare' (duration: 12.947186ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:54.054983Z","caller":"traceutil/trace.go:171","msg":"trace[1857265621] linearizableReadLoop","detail":"{readStateIndex:6138; appliedIndex:6137; }","duration":"103.103225ms","start":"2026-04-17T18:53:53.951857Z","end":"2026-04-17T18:53:54.05496Z","steps":["trace[1857265621] 'read index received' (duration: 102.93228ms)","trace[1857265621] 'applied index is now lower than readState.Index' (duration: 170.045µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:54.055038Z","caller":"traceutil/trace.go:171","msg":"trace[1460938116] transaction","detail":"{read_only:false; response_revision:5922; number_of_response:1; }","duration":"180.292205ms","start":"2026-04-17T18:53:53.874721Z","end":"2026-04-17T18:53:54.055013Z","steps":["trace[1460938116] 'process raft request' (duration: 180.11381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:54.055189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.323391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T18:53:54.055228Z","caller":"traceutil/trace.go:171","msg":"trace[2014704345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5922; }","duration":"103.414593ms","start":"2026-04-17T18:53:53.951802Z","end":"2026-04-17T18:53:54.055217Z","steps":["trace[2014704345] 'agreement among raft nodes before linearized reading' (duration: 103.268139ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:54.792509Z","caller":"traceutil/trace.go:171","msg":"trace[736326993] transaction","detail":"{read_only:false; response_revision:5928; number_of_response:1; }","duration":"117.686207ms","start":"2026-04-17T18:53:54.674751Z","end":"2026-04-17T18:53:54.792437Z","steps":["trace[736326993] 'process raft request' (duration: 99.108622ms)","trace[736326993] 'compare' (duration: 18.41054ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:56.225273Z","caller":"traceutil/trace.go:171","msg":"trace[1620440153] transaction","detail":"{read_only:false; response_revision:5933; number_of_response:1; }","duration":"121.844525ms","start":"2026-04-17T18:53:56.103386Z","end":"2026-04-17T18:53:56.22523Z","steps":["trace[1620440153] 'process raft request' (duration: 94.639705ms)","trace[1620440153] 'compare' (duration: 26.991564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:53:56.468566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.629754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:53:56.46865Z","caller":"traceutil/trace.go:171","msg":"trace[625779013] transaction","detail":"{read_only:false; response_revision:5935; number_of_response:1; }","duration":"140.878333ms","start":"2026-04-17T18:53:56.327758Z","end":"2026-04-17T18:53:56.468636Z","steps":["trace[625779013] 'process raft request' (duration: 28.112995ms)","trace[625779013] 'compare' (duration: 112.533951ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:58.437532Z","caller":"traceutil/trace.go:171","msg":"trace[1424000199] transaction","detail":"{read_only:false; response_revision:5947; number_of_response:1; }","duration":"121.043281ms","start":"2026-04-17T18:53:58.316465Z","end":"2026-04-17T18:53:58.437508Z","steps":["trace[1424000199] 'process raft request' (duration: 118.011795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:58.860465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.203393ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:53:58.860663Z","caller":"traceutil/trace.go:171","msg":"trace[1656906808] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5949; }","duration":"128.44281ms","start":"2026-04-17T18:53:58.732192Z","end":"2026-04-17T18:53:58.860635Z","steps":["trace[1656906808] 'range keys from in-memory index tree' (duration: 128.169232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:58.860793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.634984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:53:58.860922Z","caller":"traceutil/trace.go:171","msg":"trace[1068430423] linearizableReadLoop","detail":"{readStateIndex:6167; appliedIndex:6166; }","duration":"182.058196ms","start":"2026-04-17T18:53:58.678848Z","end":"2026-04-17T18:53:58.860906Z","steps":["trace[1068430423] 'read index received' (duration: 80.098564ms)","trace[1068430423] 'applied index is now lower than readState.Index' (duration: 101.958372ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:58.861027Z","caller":"traceutil/trace.go:171","msg":"trace[421947730] transaction","detail":"{read_only:false; response_revision:5950; number_of_response:1; }","duration":"262.846109ms","start":"2026-04-17T18:53:58.598167Z","end":"2026-04-17T18:53:58.861013Z","steps":["trace[421947730] 'process raft request' (duration: 160.902357ms)","trace[421947730] 'compare' (duration: 101.502819ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:53:58.861179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.326458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T18:53:58.861234Z","caller":"traceutil/trace.go:171","msg":"trace[2081603181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5950; }","duration":"163.384579ms","start":"2026-04-17T18:53:58.697838Z","end":"2026-04-17T18:53:58.861223Z","steps":["trace[2081603181] 'agreement among raft nodes before linearized reading' (duration: 163.240825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:58.861376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.540199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:53:58.861431Z","caller":"traceutil/trace.go:171","msg":"trace[996174528] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:5950; }","duration":"182.653793ms","start":"2026-04-17T18:53:58.678762Z","end":"2026-04-17T18:53:58.861416Z","steps":["trace[996174528] 'agreement among raft nodes before linearized reading' (duration: 182.54848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:58.86145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.985226ms","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-04-17T18:53:58.86148Z","caller":"traceutil/trace.go:171","msg":"trace[1875409185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5950; }","duration":"163.060079ms","start":"2026-04-17T18:53:58.698411Z","end":"2026-04-17T18:53:58.861471Z","steps":["trace[1875409185] 'agreement among raft nodes before linearized reading' (duration: 162.977797ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:59.462314Z","caller":"traceutil/trace.go:171","msg":"trace[1537007897] transaction","detail":"{read_only:false; response_revision:5955; number_of_response:1; }","duration":"103.498305ms","start":"2026-04-17T18:53:59.35879Z","end":"2026-04-17T18:53:59.462288Z","steps":["trace[1537007897] 'process raft request' (duration: 103.342241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:00.382442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.760211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:54:00.382528Z","caller":"traceutil/trace.go:171","msg":"trace[344176073] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5956; }","duration":"369.904665ms","start":"2026-04-17T18:54:00.012604Z","end":"2026-04-17T18:54:00.382509Z","steps":["trace[344176073] 'count revisions from in-memory index tree' (duration: 369.670729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:00.382578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.012583Z","time spent":"369.980798ms","remote":"127.0.0.1:33132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:54:00.382643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.673948ms","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:5"} {"level":"info","ts":"2026-04-17T18:54:00.382848Z","caller":"traceutil/trace.go:171","msg":"trace[1931070409] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:5956; }","duration":"358.941206ms","start":"2026-04-17T18:54:00.023876Z","end":"2026-04-17T18:54:00.382817Z","steps":["trace[1931070409] 'count revisions from in-memory index tree' (duration: 358.397141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:00.382929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.023857Z","time spent":"359.056009ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-04-17T18:54:00.702459Z","caller":"traceutil/trace.go:171","msg":"trace[1439026573] transaction","detail":"{read_only:false; response_revision:5957; number_of_response:1; }","duration":"674.191217ms","start":"2026-04-17T18:54:00.028242Z","end":"2026-04-17T18:54:00.702433Z","steps":["trace[1439026573] 'process raft request' (duration: 674.004952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:00.702633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.028222Z","time spent":"674.32029ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:54:01.118709Z","caller":"traceutil/trace.go:171","msg":"trace[403420175] transaction","detail":"{read_only:false; response_revision:5958; number_of_response:1; }","duration":"1.004120012s","start":"2026-04-17T18:54:00.114567Z","end":"2026-04-17T18:54:01.118687Z","steps":["trace[403420175] 'process raft request' (duration: 1.003651878s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.11886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.114538Z","time spent":"1.004247486s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:54:01.118991Z","caller":"traceutil/trace.go:171","msg":"trace[1882538842] transaction","detail":"{read_only:false; response_revision:5959; number_of_response:1; }","duration":"644.283781ms","start":"2026-04-17T18:54:00.474694Z","end":"2026-04-17T18:54:01.118978Z","steps":["trace[1882538842] 'process raft request' (duration: 643.892989ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:01.118981Z","caller":"traceutil/trace.go:171","msg":"trace[29967878] linearizableReadLoop","detail":"{readStateIndex:6175; appliedIndex:6173; }","duration":"824.30697ms","start":"2026-04-17T18:54:00.294639Z","end":"2026-04-17T18:54:01.118946Z","steps":["trace[29967878] 'read index received' (duration: 407.435607ms)","trace[29967878] 'applied index is now lower than readState.Index' (duration: 416.869523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:54:01.119048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.474665Z","time spent":"644.354693ms","remote":"127.0.0.1:35456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:54:01.119414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.362072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-17T18:54:01.119396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.733072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-17T18:54:01.11946Z","caller":"traceutil/trace.go:171","msg":"trace[355503617] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:5959; }","duration":"247.452365ms","start":"2026-04-17T18:54:00.871997Z","end":"2026-04-17T18:54:01.11945Z","steps":["trace[355503617] 'agreement among raft nodes before linearized reading' (duration: 247.305521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.1195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.616199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T18:54:01.119498Z","caller":"traceutil/trace.go:171","msg":"trace[1496880832] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5959; }","duration":"824.904777ms","start":"2026-04-17T18:54:00.294572Z","end":"2026-04-17T18:54:01.119477Z","steps":["trace[1496880832] 'agreement among raft nodes before linearized reading' (duration: 824.586228ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:01.119552Z","caller":"traceutil/trace.go:171","msg":"trace[465696241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5959; }","duration":"253.686931ms","start":"2026-04-17T18:54:00.865851Z","end":"2026-04-17T18:54:01.119538Z","steps":["trace[465696241] 'agreement among raft nodes before linearized reading' (duration: 253.591468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.119523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.780571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-17T18:54:01.119577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.294549Z","time spent":"825.008439ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-17T18:54:01.119641Z","caller":"traceutil/trace.go:171","msg":"trace[1191230562] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5959; }","duration":"214.950486ms","start":"2026-04-17T18:54:00.904659Z","end":"2026-04-17T18:54:01.119609Z","steps":["trace[1191230562] 'agreement among raft nodes before linearized reading' (duration: 214.660568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.11969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.002417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T18:54:01.119763Z","caller":"traceutil/trace.go:171","msg":"trace[2040816509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5959; }","duration":"215.07757ms","start":"2026-04-17T18:54:00.904639Z","end":"2026-04-17T18:54:01.119716Z","steps":["trace[2040816509] 'agreement among raft nodes before linearized reading' (duration: 214.983177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.119814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.155178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:54:01.119881Z","caller":"traceutil/trace.go:171","msg":"trace[644641147] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5959; }","duration":"603.251401ms","start":"2026-04-17T18:54:00.516615Z","end":"2026-04-17T18:54:01.119866Z","steps":["trace[644641147] 'agreement among raft nodes before linearized reading' (duration: 603.108287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.119906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.978439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T18:54:01.119566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.770237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:54:01.119927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.516598Z","time spent":"603.316182ms","remote":"127.0.0.1:35456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"info","ts":"2026-04-17T18:54:01.119963Z","caller":"traceutil/trace.go:171","msg":"trace[1712270679] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5959; }","duration":"603.22531ms","start":"2026-04-17T18:54:00.516722Z","end":"2026-04-17T18:54:01.119948Z","steps":["trace[1712270679] 'agreement among raft nodes before linearized reading' (duration: 602.802858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.120013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.516676Z","time spent":"603.322943ms","remote":"127.0.0.1:35456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-04-17T18:54:01.120068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.755657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:54:01.120111Z","caller":"traceutil/trace.go:171","msg":"trace[699566146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5959; }","duration":"677.851829ms","start":"2026-04-17T18:54:00.442247Z","end":"2026-04-17T18:54:01.120099Z","steps":["trace[699566146] 'agreement among raft nodes before linearized reading' (duration: 677.734026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.120155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.442219Z","time spent":"677.929132ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:54:01.119943Z","caller":"traceutil/trace.go:171","msg":"trace[1187439273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5959; }","duration":"225.075042ms","start":"2026-04-17T18:54:00.894857Z","end":"2026-04-17T18:54:01.119932Z","steps":["trace[1187439273] 'agreement among raft nodes before linearized reading' (duration: 224.986269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.120247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.220089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:54:01.119416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.31598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:54:01.120293Z","caller":"traceutil/trace.go:171","msg":"trace[251363010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5959; }","duration":"390.291401ms","start":"2026-04-17T18:54:00.729988Z","end":"2026-04-17T18:54:01.12028Z","steps":["trace[251363010] 'agreement among raft nodes before linearized reading' (duration: 390.23924ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:01.120301Z","caller":"traceutil/trace.go:171","msg":"trace[741117152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5959; }","duration":"750.266147ms","start":"2026-04-17T18:54:00.370022Z","end":"2026-04-17T18:54:01.120288Z","steps":["trace[741117152] 'agreement among raft nodes before linearized reading' (duration: 749.210587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.12033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.729968Z","time spent":"390.356283ms","remote":"127.0.0.1:35206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:54:01.120342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.370001Z","time spent":"750.330198ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:54:01.120203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.770873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-17T18:54:01.12049Z","caller":"traceutil/trace.go:171","msg":"trace[254136562] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5959; }","duration":"317.087872ms","start":"2026-04-17T18:54:00.803385Z","end":"2026-04-17T18:54:01.120473Z","steps":["trace[254136562] 'agreement among raft nodes before linearized reading' (duration: 316.212657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:01.120546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:54:00.803358Z","time spent":"317.169504ms","remote":"127.0.0.1:35748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":676,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-17T18:54:06.741959Z","caller":"traceutil/trace.go:171","msg":"trace[1815963753] transaction","detail":"{read_only:false; response_revision:6007; number_of_response:1; }","duration":"136.628982ms","start":"2026-04-17T18:54:06.605293Z","end":"2026-04-17T18:54:06.741922Z","steps":["trace[1815963753] 'process raft request' (duration: 55.818088ms)","trace[1815963753] 'compare' (duration: 80.564758ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:54:06.741989Z","caller":"traceutil/trace.go:171","msg":"trace[474940291] linearizableReadLoop","detail":"{readStateIndex:6226; appliedIndex:6225; }","duration":"110.130333ms","start":"2026-04-17T18:54:06.631832Z","end":"2026-04-17T18:54:06.741962Z","steps":["trace[474940291] 'read index received' (duration: 29.177144ms)","trace[474940291] 'applied index is now lower than readState.Index' (duration: 80.951258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:54:06.742329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.487262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-17T18:54:06.742496Z","caller":"traceutil/trace.go:171","msg":"trace[893139556] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:6007; }","duration":"110.678497ms","start":"2026-04-17T18:54:06.631783Z","end":"2026-04-17T18:54:06.742461Z","steps":["trace[893139556] 'agreement among raft nodes before linearized reading' (duration: 110.435951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:07.420004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.363626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:54:07.420245Z","caller":"traceutil/trace.go:171","msg":"trace[2041273836] linearizableReadLoop","detail":"{readStateIndex:6230; appliedIndex:6229; }","duration":"270.913848ms","start":"2026-04-17T18:54:07.149274Z","end":"2026-04-17T18:54:07.420187Z","steps":["trace[2041273836] 'read index received' (duration: 83.242723ms)","trace[2041273836] 'applied index is now lower than readState.Index' (duration: 187.669155ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:54:07.420255Z","caller":"traceutil/trace.go:171","msg":"trace[667575840] transaction","detail":"{read_only:false; response_revision:6011; number_of_response:1; }","duration":"280.426456ms","start":"2026-04-17T18:54:07.139787Z","end":"2026-04-17T18:54:07.420214Z","steps":["trace[667575840] 'process raft request' (duration: 92.739022ms)","trace[667575840] 'compare' (duration: 187.16662ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:54:07.420483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.122881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-17T18:54:07.420478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.182965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-17T18:54:07.420502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.024894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T18:54:07.42051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.955127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:54:07.420538Z","caller":"traceutil/trace.go:171","msg":"trace[632078471] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6011; }","duration":"255.173302ms","start":"2026-04-17T18:54:07.165346Z","end":"2026-04-17T18:54:07.420519Z","steps":["trace[632078471] 'agreement among raft nodes before linearized reading' (duration: 255.008487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:07.420548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.994839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T18:54:07.420556Z","caller":"traceutil/trace.go:171","msg":"trace[722833779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6011; }","duration":"211.122467ms","start":"2026-04-17T18:54:07.209419Z","end":"2026-04-17T18:54:07.420541Z","steps":["trace[722833779] 'agreement among raft nodes before linearized reading' (duration: 210.981913ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.420562Z","caller":"traceutil/trace.go:171","msg":"trace[1862940646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6011; }","duration":"208.008779ms","start":"2026-04-17T18:54:07.212538Z","end":"2026-04-17T18:54:07.420547Z","steps":["trace[1862940646] 'agreement among raft nodes before linearized reading' (duration: 207.865635ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.420592Z","caller":"traceutil/trace.go:171","msg":"trace[986673623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6011; }","duration":"208.073501ms","start":"2026-04-17T18:54:07.212508Z","end":"2026-04-17T18:54:07.420581Z","steps":["trace[986673623] 'agreement among raft nodes before linearized reading' (duration: 207.876875ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.42067Z","caller":"traceutil/trace.go:171","msg":"trace[2004957688] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:6011; }","duration":"271.410122ms","start":"2026-04-17T18:54:07.149241Z","end":"2026-04-17T18:54:07.420651Z","steps":["trace[2004957688] 'agreement among raft nodes before linearized reading' (duration: 271.038851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:07.420478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.808104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:54:07.420726Z","caller":"traceutil/trace.go:171","msg":"trace[775941441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6011; }","duration":"208.111472ms","start":"2026-04-17T18:54:07.212597Z","end":"2026-04-17T18:54:07.420709Z","steps":["trace[775941441] 'agreement among raft nodes before linearized reading' (duration: 207.760532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:07.420767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.58336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:54:07.420869Z","caller":"traceutil/trace.go:171","msg":"trace[1036478446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6011; }","duration":"206.730743ms","start":"2026-04-17T18:54:07.214119Z","end":"2026-04-17T18:54:07.42085Z","steps":["trace[1036478446] 'agreement among raft nodes before linearized reading' (duration: 206.428605ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.610945Z","caller":"traceutil/trace.go:171","msg":"trace[612197010] transaction","detail":"{read_only:false; response_revision:6013; number_of_response:1; }","duration":"185.164844ms","start":"2026-04-17T18:54:07.425753Z","end":"2026-04-17T18:54:07.610918Z","steps":["trace[612197010] 'process raft request' (duration: 184.933547ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.611275Z","caller":"traceutil/trace.go:171","msg":"trace[1380282133] transaction","detail":"{read_only:false; response_revision:6014; number_of_response:1; }","duration":"185.427601ms","start":"2026-04-17T18:54:07.425828Z","end":"2026-04-17T18:54:07.611256Z","steps":["trace[1380282133] 'process raft request' (duration: 185.046731ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.611439Z","caller":"traceutil/trace.go:171","msg":"trace[1470346140] transaction","detail":"{read_only:false; response_revision:6015; number_of_response:1; }","duration":"185.457362ms","start":"2026-04-17T18:54:07.425965Z","end":"2026-04-17T18:54:07.611422Z","steps":["trace[1470346140] 'process raft request' (duration: 185.165754ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.611532Z","caller":"traceutil/trace.go:171","msg":"trace[485207880] transaction","detail":"{read_only:false; response_revision:6018; number_of_response:1; }","duration":"182.543209ms","start":"2026-04-17T18:54:07.428958Z","end":"2026-04-17T18:54:07.611502Z","steps":["trace[485207880] 'process raft request' (duration: 182.466257ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.611554Z","caller":"traceutil/trace.go:171","msg":"trace[974362641] transaction","detail":"{read_only:false; response_revision:6016; number_of_response:1; }","duration":"185.41709ms","start":"2026-04-17T18:54:07.426094Z","end":"2026-04-17T18:54:07.611511Z","steps":["trace[974362641] 'process raft request' (duration: 185.248356ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:07.611699Z","caller":"traceutil/trace.go:171","msg":"trace[1263786623] transaction","detail":"{read_only:false; response_revision:6017; number_of_response:1; }","duration":"185.589305ms","start":"2026-04-17T18:54:07.426093Z","end":"2026-04-17T18:54:07.611682Z","steps":["trace[1263786623] 'process raft request' (duration: 185.290537ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:08.924514Z","caller":"traceutil/trace.go:171","msg":"trace[129553258] transaction","detail":"{read_only:false; response_revision:6023; number_of_response:1; }","duration":"107.341384ms","start":"2026-04-17T18:54:08.817143Z","end":"2026-04-17T18:54:08.924484Z","steps":["trace[129553258] 'process raft request' (duration: 107.167799ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:12.989729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2990} {"level":"info","ts":"2026-04-17T18:54:13.060882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2990,"took":"69.571166ms","hash":2216716736,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":33636352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T18:54:13.060949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2216716736,"revision":2990,"compact-revision":-1} {"level":"info","ts":"2026-04-17T18:58:51.326609Z","caller":"traceutil/trace.go:171","msg":"trace[942424233] linearizableReadLoop","detail":"{readStateIndex:8414; appliedIndex:8413; }","duration":"252.733743ms","start":"2026-04-17T18:58:51.07384Z","end":"2026-04-17T18:58:51.326574Z","steps":["trace[942424233] 'read index received' (duration: 252.61564ms)","trace[942424233] 'applied index is now lower than readState.Index' (duration: 116.643µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:58:51.326695Z","caller":"traceutil/trace.go:171","msg":"trace[224562764] transaction","detail":"{read_only:false; response_revision:8125; number_of_response:1; }","duration":"350.819361ms","start":"2026-04-17T18:58:50.975843Z","end":"2026-04-17T18:58:51.326662Z","steps":["trace[224562764] 'process raft request' (duration: 350.487692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:51.326782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.910388ms","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-04-17T18:58:51.326842Z","caller":"traceutil/trace.go:171","msg":"trace[617287777] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8125; }","duration":"253.024141ms","start":"2026-04-17T18:58:51.073801Z","end":"2026-04-17T18:58:51.326825Z","steps":["trace[617287777] 'agreement among raft nodes before linearized reading' (duration: 252.903798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:51.326912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:50.975818Z","time spent":"350.967064ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:58:51.518353Z","caller":"traceutil/trace.go:171","msg":"trace[1183822447] linearizableReadLoop","detail":"{readStateIndex:8415; appliedIndex:8414; }","duration":"191.620905ms","start":"2026-04-17T18:58:51.326704Z","end":"2026-04-17T18:58:51.518325Z","steps":["trace[1183822447] 'read index received' (duration: 190.796831ms)","trace[1183822447] 'applied index is now lower than readState.Index' (duration: 822.464µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:58:51.518371Z","caller":"traceutil/trace.go:171","msg":"trace[1733631330] transaction","detail":"{read_only:false; response_revision:8126; number_of_response:1; }","duration":"317.362393ms","start":"2026-04-17T18:58:51.200976Z","end":"2026-04-17T18:58:51.518338Z","steps":["trace[1733631330] 'process raft request' (duration: 316.54812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:51.518528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:51.200948Z","time spent":"317.496387ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:58:51.518499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.181314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:58:51.518629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.256508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T18:58:51.518662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.472792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-17T18:58:51.51868Z","caller":"traceutil/trace.go:171","msg":"trace[1122879831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8126; }","duration":"362.363221ms","start":"2026-04-17T18:58:51.156304Z","end":"2026-04-17T18:58:51.518667Z","steps":["trace[1122879831] 'agreement among raft nodes before linearized reading' (duration: 362.176686ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:51.518709Z","caller":"traceutil/trace.go:171","msg":"trace[2067369896] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8126; }","duration":"233.561285ms","start":"2026-04-17T18:58:51.285134Z","end":"2026-04-17T18:58:51.518696Z","steps":["trace[2067369896] 'agreement among raft nodes before linearized reading' (duration: 233.461312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:51.518723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:51.156284Z","time spent":"362.430364ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:58:51.518633Z","caller":"traceutil/trace.go:171","msg":"trace[875656152] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8126; }","duration":"226.379229ms","start":"2026-04-17T18:58:51.292225Z","end":"2026-04-17T18:58:51.518604Z","steps":["trace[875656152] 'agreement among raft nodes before linearized reading' (duration: 226.123242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:51.518926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.68127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T18:58:51.518976Z","caller":"traceutil/trace.go:171","msg":"trace[1157704116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8126; }","duration":"224.755043ms","start":"2026-04-17T18:58:51.294209Z","end":"2026-04-17T18:58:51.518964Z","steps":["trace[1157704116] 'agreement among raft nodes before linearized reading' (duration: 224.65192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:51.518561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.41097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T18:58:51.519071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.286927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:58:51.519129Z","caller":"traceutil/trace.go:171","msg":"trace[1624171004] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:8126; }","duration":"407.3759ms","start":"2026-04-17T18:58:51.111741Z","end":"2026-04-17T18:58:51.519116Z","steps":["trace[1624171004] 'agreement among raft nodes before linearized reading' (duration: 407.225316ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:51.519069Z","caller":"traceutil/trace.go:171","msg":"trace[1709893791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8126; }","duration":"255.967016ms","start":"2026-04-17T18:58:51.263083Z","end":"2026-04-17T18:58:51.51905Z","steps":["trace[1709893791] 'agreement among raft nodes before linearized reading' (duration: 255.366289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:51.519178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:51.1117Z","time spent":"407.463283ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-x8dcz\" "} {"level":"warn","ts":"2026-04-17T18:58:51.51883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.854357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T18:58:51.51931Z","caller":"traceutil/trace.go:171","msg":"trace[1187730367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8126; }","duration":"224.362483ms","start":"2026-04-17T18:58:51.294921Z","end":"2026-04-17T18:58:51.519283Z","steps":["trace[1187730367] 'agreement among raft nodes before linearized reading' (duration: 223.745644ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:02.8761Z","caller":"traceutil/trace.go:171","msg":"trace[934571604] linearizableReadLoop","detail":"{readStateIndex:8481; appliedIndex:8480; }","duration":"146.426772ms","start":"2026-04-17T18:59:02.72965Z","end":"2026-04-17T18:59:02.876077Z","steps":["trace[934571604] 'read index received' (duration: 146.159753ms)","trace[934571604] 'applied index is now lower than readState.Index' (duration: 266.169µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:02.876243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.568836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:59:02.876274Z","caller":"traceutil/trace.go:171","msg":"trace[756720624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8188; }","duration":"146.618617ms","start":"2026-04-17T18:59:02.729645Z","end":"2026-04-17T18:59:02.876263Z","steps":["trace[756720624] 'agreement among raft nodes before linearized reading' (duration: 146.522824ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:02.87661Z","caller":"traceutil/trace.go:171","msg":"trace[1558746803] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"192.173131ms","start":"2026-04-17T18:59:02.684425Z","end":"2026-04-17T18:59:02.876599Z","steps":["trace[1558746803] 'process raft request' (duration: 191.449579ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:03.845817Z","caller":"traceutil/trace.go:171","msg":"trace[758629201] linearizableReadLoop","detail":"{readStateIndex:8484; appliedIndex:8483; }","duration":"268.199367ms","start":"2026-04-17T18:59:03.577579Z","end":"2026-04-17T18:59:03.845779Z","steps":["trace[758629201] 'read index received' (duration: 267.873197ms)","trace[758629201] 'applied index is now lower than readState.Index' (duration: 319.589µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:59:03.846014Z","caller":"traceutil/trace.go:171","msg":"trace[1417911605] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"280.147509ms","start":"2026-04-17T18:59:03.565853Z","end":"2026-04-17T18:59:03.846001Z","steps":["trace[1417911605] 'process raft request' (duration: 279.638714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:03.846212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.335333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-17T18:59:03.84627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.065673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:59:03.846316Z","caller":"traceutil/trace.go:171","msg":"trace[1187103227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8191; }","duration":"259.480307ms","start":"2026-04-17T18:59:03.586809Z","end":"2026-04-17T18:59:03.846289Z","steps":["trace[1187103227] 'agreement among raft nodes before linearized reading' (duration: 259.22231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:03.846352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.913172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T18:59:03.846406Z","caller":"traceutil/trace.go:171","msg":"trace[324671663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8191; }","duration":"258.990894ms","start":"2026-04-17T18:59:03.587393Z","end":"2026-04-17T18:59:03.846384Z","steps":["trace[324671663] 'agreement among raft nodes before linearized reading' (duration: 258.889601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:03.846211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.626419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T18:59:03.846341Z","caller":"traceutil/trace.go:171","msg":"trace[468797498] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:8191; }","duration":"253.149116ms","start":"2026-04-17T18:59:03.593168Z","end":"2026-04-17T18:59:03.846317Z","steps":["trace[468797498] 'agreement among raft nodes before linearized reading' (duration: 252.93658ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:03.846483Z","caller":"traceutil/trace.go:171","msg":"trace[2129113621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8191; }","duration":"268.930057ms","start":"2026-04-17T18:59:03.577514Z","end":"2026-04-17T18:59:03.846444Z","steps":["trace[2129113621] 'agreement among raft nodes before linearized reading' (duration: 268.514146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:03.846512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.778097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T18:59:03.846565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.327047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-17T18:59:03.846604Z","caller":"traceutil/trace.go:171","msg":"trace[1305941251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8191; }","duration":"258.89903ms","start":"2026-04-17T18:59:03.58768Z","end":"2026-04-17T18:59:03.846579Z","steps":["trace[1305941251] 'agreement among raft nodes before linearized reading' (duration: 258.614642ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:03.84697Z","caller":"traceutil/trace.go:171","msg":"trace[1254323714] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8191; }","duration":"255.360648ms","start":"2026-04-17T18:59:03.591226Z","end":"2026-04-17T18:59:03.846587Z","steps":["trace[1254323714] 'agreement among raft nodes before linearized reading' (duration: 255.275986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:03.979392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.321745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19407"} {"level":"info","ts":"2026-04-17T18:59:03.979488Z","caller":"traceutil/trace.go:171","msg":"trace[873041246] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8197; }","duration":"126.447609ms","start":"2026-04-17T18:59:03.853019Z","end":"2026-04-17T18:59:03.979467Z","steps":["trace[873041246] 'agreement among raft nodes before linearized reading' (duration: 126.139721ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:12.996876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6050} {"level":"info","ts":"2026-04-17T18:59:13.094892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6050,"took":"96.43541ms","hash":3411342635,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":23400448,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-17T18:59:13.09498Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3411342635,"revision":6050,"compact-revision":2990} {"level":"info","ts":"2026-04-17T18:59:32.632715Z","caller":"traceutil/trace.go:171","msg":"trace[2085071170] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"535.431855ms","start":"2026-04-17T18:59:32.097255Z","end":"2026-04-17T18:59:32.632687Z","steps":["trace[2085071170] 'process raft request' (duration: 444.902314ms)","trace[2085071170] 'compare' (duration: 90.421288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:32.632869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:32.097235Z","time spent":"535.555239ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:59:32.905105Z","caller":"traceutil/trace.go:171","msg":"trace[263058146] linearizableReadLoop","detail":"{readStateIndex:8702; appliedIndex:8700; }","duration":"802.498729ms","start":"2026-04-17T18:59:32.102578Z","end":"2026-04-17T18:59:32.905077Z","steps":["trace[263058146] 'read index received' (duration: 439.594942ms)","trace[263058146] 'applied index is now lower than readState.Index' (duration: 362.902627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:32.905517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.851267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-17T18:59:32.905578Z","caller":"traceutil/trace.go:171","msg":"trace[856353154] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8400; }","duration":"796.96124ms","start":"2026-04-17T18:59:32.1086Z","end":"2026-04-17T18:59:32.905562Z","steps":["trace[856353154] 'agreement among raft nodes before linearized reading' (duration: 796.770365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:32.905683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.445076ms","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:7"} {"level":"info","ts":"2026-04-17T18:59:32.905782Z","caller":"traceutil/trace.go:171","msg":"trace[24356838] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8400; }","duration":"368.5929ms","start":"2026-04-17T18:59:32.537161Z","end":"2026-04-17T18:59:32.905754Z","steps":["trace[24356838] 'agreement among raft nodes before linearized reading' (duration: 368.411334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:32.905866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:32.537138Z","time spent":"368.707872ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:59:32.905853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.418009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:59:32.905984Z","caller":"traceutil/trace.go:171","msg":"trace[610687797] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8400; }","duration":"271.599353ms","start":"2026-04-17T18:59:32.634353Z","end":"2026-04-17T18:59:32.905952Z","steps":["trace[610687797] 'agreement among raft nodes before linearized reading' (duration: 271.381467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:32.906005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.423135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:59:32.906118Z","caller":"traceutil/trace.go:171","msg":"trace[928215417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8400; }","duration":"803.560859ms","start":"2026-04-17T18:59:32.102541Z","end":"2026-04-17T18:59:32.906102Z","steps":["trace[928215417] 'agreement among raft nodes before linearized reading' (duration: 803.216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:32.906191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:32.102532Z","time spent":"803.646792ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:59:32.906246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.963649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:59:32.906304Z","caller":"traceutil/trace.go:171","msg":"trace[1627611590] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:8400; }","duration":"784.056782ms","start":"2026-04-17T18:59:32.122231Z","end":"2026-04-17T18:59:32.906288Z","steps":["trace[1627611590] 'agreement among raft nodes before linearized reading' (duration: 783.911958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:32.90634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:32.122212Z","time spent":"784.120323ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-x8dcz\" "} {"level":"warn","ts":"2026-04-17T18:59:32.906419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.534969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:59:32.906471Z","caller":"traceutil/trace.go:171","msg":"trace[753508879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8400; }","duration":"788.630122ms","start":"2026-04-17T18:59:32.11783Z","end":"2026-04-17T18:59:32.90646Z","steps":["trace[753508879] 'agreement among raft nodes before linearized reading' (duration: 788.449016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:32.906552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:32.117808Z","time spent":"788.731615ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T18:59:32.906476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.919933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19383"} {"level":"info","ts":"2026-04-17T18:59:32.906679Z","caller":"traceutil/trace.go:171","msg":"trace[333758582] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8400; }","duration":"728.162631ms","start":"2026-04-17T18:59:32.178498Z","end":"2026-04-17T18:59:32.906661Z","steps":["trace[333758582] 'agreement among raft nodes before linearized reading' (duration: 727.816041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:32.906679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:32.108584Z","time spent":"798.077631ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-17T18:59:32.906717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:32.178482Z","time spent":"728.227644ms","remote":"127.0.0.1:35388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19405,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-17T18:59:32.906882Z","caller":"traceutil/trace.go:171","msg":"trace[896667723] transaction","detail":"{read_only:false; response_revision:8400; number_of_response:1; }","duration":"804.941949ms","start":"2026-04-17T18:59:32.101926Z","end":"2026-04-17T18:59:32.906868Z","steps":["trace[896667723] 'process raft request' (duration: 800.130422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:32.906981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:32.101854Z","time spent":"805.064383ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:59:46.933889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.941603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T18:59:46.934426Z","caller":"traceutil/trace.go:171","msg":"trace[133652024] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8514; }","duration":"304.177386ms","start":"2026-04-17T18:59:46.630207Z","end":"2026-04-17T18:59:46.934384Z","steps":["trace[133652024] 'process raft request' (duration: 139.596396ms)","trace[133652024] 'compare' (duration: 163.655294ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:46.934508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:46.630183Z","time spent":"304.28736ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":40,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-04-17T18:59:46.940627Z","caller":"traceutil/trace.go:171","msg":"trace[723775753] linearizableReadLoop","detail":"{readStateIndex:8823; appliedIndex:8821; }","duration":"235.861631ms","start":"2026-04-17T18:59:46.704739Z","end":"2026-04-17T18:59:46.9406Z","steps":["trace[723775753] 'read index received' (duration: 65.203697ms)","trace[723775753] 'applied index is now lower than readState.Index' (duration: 170.657154ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:59:46.940786Z","caller":"traceutil/trace.go:171","msg":"trace[2066354268] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"308.540221ms","start":"2026-04-17T18:59:46.632228Z","end":"2026-04-17T18:59:46.940768Z","steps":["trace[2066354268] 'process raft request' (duration: 308.221692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:46.940919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:46.631609Z","time spent":"309.22062ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4630,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:59:46.941021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.535165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:59:46.941072Z","caller":"traceutil/trace.go:171","msg":"trace[1581403844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8515; }","duration":"210.638208ms","start":"2026-04-17T18:59:46.730421Z","end":"2026-04-17T18:59:46.941059Z","steps":["trace[1581403844] 'agreement among raft nodes before linearized reading' (duration: 210.560776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:46.941317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.583941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T18:59:46.941355Z","caller":"traceutil/trace.go:171","msg":"trace[1082511931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8515; }","duration":"236.667073ms","start":"2026-04-17T18:59:46.704679Z","end":"2026-04-17T18:59:46.941346Z","steps":["trace[1082511931] 'agreement among raft nodes before linearized reading' (duration: 236.539329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:47.479356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.974345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w8nfc\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-17T18:59:47.479464Z","caller":"traceutil/trace.go:171","msg":"trace[403551062] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-w8nfc; range_end:; response_count:1; response_revision:8521; }","duration":"293.138299ms","start":"2026-04-17T18:59:47.186303Z","end":"2026-04-17T18:59:47.479441Z","steps":["trace[403551062] 'range keys from in-memory index tree' (duration: 292.719257ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:47.479439Z","caller":"traceutil/trace.go:171","msg":"trace[1876741091] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"292.001507ms","start":"2026-04-17T18:59:47.187399Z","end":"2026-04-17T18:59:47.4794Z","steps":["trace[1876741091] 'process raft request' (duration: 193.066156ms)","trace[1876741091] 'compare' (duration: 98.771666ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:47.831454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.671261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:59:47.831688Z","caller":"traceutil/trace.go:171","msg":"trace[796755816] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8830; }","duration":"200.022945ms","start":"2026-04-17T18:59:47.631646Z","end":"2026-04-17T18:59:47.831669Z","steps":["trace[796755816] 'read index received' (duration: 62.022305ms)","trace[796755816] 'applied index is now lower than readState.Index' (duration: 137.99971ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:47.831914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.275492ms","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-04-17T18:59:47.831883Z","caller":"traceutil/trace.go:171","msg":"trace[1297571480] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"234.342777ms","start":"2026-04-17T18:59:47.597508Z","end":"2026-04-17T18:59:47.83185Z","steps":["trace[1297571480] 'process raft request' (duration: 234.065179ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:47.831954Z","caller":"traceutil/trace.go:171","msg":"trace[459216096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8524; }","duration":"200.370265ms","start":"2026-04-17T18:59:47.631573Z","end":"2026-04-17T18:59:47.831943Z","steps":["trace[459216096] 'agreement among raft nodes before linearized reading' (duration: 200.237211ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:47.831942Z","caller":"traceutil/trace.go:171","msg":"trace[1229542732] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"342.642997ms","start":"2026-04-17T18:59:47.489222Z","end":"2026-04-17T18:59:47.831865Z","steps":["trace[1229542732] 'process raft request' (duration: 204.450441ms)","trace[1229542732] 'compare' (duration: 137.440664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:47.832002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.208557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:59:47.832407Z","caller":"traceutil/trace.go:171","msg":"trace[766505198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8524; }","duration":"101.647179ms","start":"2026-04-17T18:59:47.73073Z","end":"2026-04-17T18:59:47.832377Z","steps":["trace[766505198] 'agreement among raft nodes before linearized reading' (duration: 101.172626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:47.832159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:47.48919Z","time spent":"342.856923ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:59:48.229588Z","caller":"traceutil/trace.go:171","msg":"trace[1876670572] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"196.01334ms","start":"2026-04-17T18:59:48.033545Z","end":"2026-04-17T18:59:48.229559Z","steps":["trace[1876670572] 'process raft request' (duration: 195.63716ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:48.371493Z","caller":"traceutil/trace.go:171","msg":"trace[1624741354] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"134.997944ms","start":"2026-04-17T18:59:48.236259Z","end":"2026-04-17T18:59:48.371257Z","steps":["trace[1624741354] 'process raft request' (duration: 134.604962ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:49.203436Z","caller":"traceutil/trace.go:171","msg":"trace[259744288] linearizableReadLoop","detail":"{readStateIndex:8841; appliedIndex:8840; }","duration":"188.849956ms","start":"2026-04-17T18:59:49.014568Z","end":"2026-04-17T18:59:49.203418Z","steps":["trace[259744288] 'read index received' (duration: 120.600231ms)","trace[259744288] 'applied index is now lower than readState.Index' (duration: 68.248385ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:49.203665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.089463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T18:59:49.203731Z","caller":"traceutil/trace.go:171","msg":"trace[435002380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8534; }","duration":"189.169785ms","start":"2026-04-17T18:59:49.014551Z","end":"2026-04-17T18:59:49.203721Z","steps":["trace[435002380] 'agreement among raft nodes before linearized reading' (duration: 188.99052ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:49.203425Z","caller":"traceutil/trace.go:171","msg":"trace[609550715] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"216.232909ms","start":"2026-04-17T18:59:48.98712Z","end":"2026-04-17T18:59:49.203353Z","steps":["trace[609550715] 'process raft request' (duration: 147.839361ms)","trace[609550715] 'compare' (duration: 68.160881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:49.204014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.936687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-17T18:59:49.203932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.540554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:59:49.204086Z","caller":"traceutil/trace.go:171","msg":"trace[762819504] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:8534; }","duration":"139.017109ms","start":"2026-04-17T18:59:49.065037Z","end":"2026-04-17T18:59:49.204054Z","steps":["trace[762819504] 'agreement among raft nodes before linearized reading' (duration: 138.898136ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:49.204138Z","caller":"traceutil/trace.go:171","msg":"trace[1219837246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8534; }","duration":"146.693388ms","start":"2026-04-17T18:59:49.057378Z","end":"2026-04-17T18:59:49.204071Z","steps":["trace[1219837246] 'agreement among raft nodes before linearized reading' (duration: 146.454821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:49.204145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.348819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-17T18:59:49.205204Z","caller":"traceutil/trace.go:171","msg":"trace[1849883641] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8534; }","duration":"147.44815ms","start":"2026-04-17T18:59:49.057741Z","end":"2026-04-17T18:59:49.205189Z","steps":["trace[1849883641] 'agreement among raft nodes before linearized reading' (duration: 146.215115ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:49.452002Z","caller":"traceutil/trace.go:171","msg":"trace[1243224498] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"244.155899ms","start":"2026-04-17T18:59:49.207807Z","end":"2026-04-17T18:59:49.451963Z","steps":["trace[1243224498] 'process raft request' (duration: 243.782477ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:49.45202Z","caller":"traceutil/trace.go:171","msg":"trace[190855235] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"244.132809ms","start":"2026-04-17T18:59:49.207862Z","end":"2026-04-17T18:59:49.451995Z","steps":["trace[190855235] 'process raft request' (duration: 243.936652ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:49.471789Z","caller":"traceutil/trace.go:171","msg":"trace[695880889] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"117.692709ms","start":"2026-04-17T18:59:49.354057Z","end":"2026-04-17T18:59:49.471749Z","steps":["trace[695880889] 'process raft request' (duration: 117.479643ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:59.590158Z","caller":"traceutil/trace.go:171","msg":"trace[498644649] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"156.982632ms","start":"2026-04-17T18:59:59.433141Z","end":"2026-04-17T18:59:59.590124Z","steps":["trace[498644649] 'process raft request' (duration: 156.663491ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:00:21.845607Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d3.wal"} {"level":"info","ts":"2026-04-17T19:00:21.846161Z","caller":"traceutil/trace.go:171","msg":"trace[168997168] transaction","detail":"{read_only:false; response_revision:8852; number_of_response:1; }","duration":"445.36164ms","start":"2026-04-17T19:00:21.400763Z","end":"2026-04-17T19:00:21.846125Z","steps":["trace[168997168] 'process raft request' (duration: 445.154274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:00:21.846353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:00:21.400655Z","time spent":"445.600527ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T19:00:21.850959Z","caller":"traceutil/trace.go:171","msg":"trace[206495401] linearizableReadLoop","detail":"{readStateIndex:9171; appliedIndex:9169; }","duration":"366.356041ms","start":"2026-04-17T19:00:21.484574Z","end":"2026-04-17T19:00:21.85093Z","steps":["trace[206495401] 'read index received' (duration: 361.374058ms)","trace[206495401] 'applied index is now lower than readState.Index' (duration: 4.980973ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:00:21.85111Z","caller":"traceutil/trace.go:171","msg":"trace[424317234] transaction","detail":"{read_only:false; response_revision:8853; number_of_response:1; }","duration":"414.387195ms","start":"2026-04-17T19:00:21.436698Z","end":"2026-04-17T19:00:21.851085Z","steps":["trace[424317234] 'process raft request' (duration: 414.027465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:00:21.851231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:00:21.436664Z","time spent":"414.510419ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T19:00:21.851253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.772153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T19:00:21.851293Z","caller":"traceutil/trace.go:171","msg":"trace[1700504886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8853; }","duration":"330.847855ms","start":"2026-04-17T19:00:21.520435Z","end":"2026-04-17T19:00:21.851283Z","steps":["trace[1700504886] 'agreement among raft nodes before linearized reading' (duration: 330.708931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:00:21.85132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:00:21.520416Z","time spent":"330.898236ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T19:00:21.85137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.410877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-17T19:00:21.851356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.394494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:00:21.851475Z","caller":"traceutil/trace.go:171","msg":"trace[1116505178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8853; }","duration":"104.55023ms","start":"2026-04-17T19:00:21.746897Z","end":"2026-04-17T19:00:21.851447Z","steps":["trace[1116505178] 'agreement among raft nodes before linearized reading' (duration: 104.284273ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:00:21.851534Z","caller":"traceutil/trace.go:171","msg":"trace[201602782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8853; }","duration":"120.636401ms","start":"2026-04-17T19:00:21.730882Z","end":"2026-04-17T19:00:21.851518Z","steps":["trace[201602782] 'agreement among raft nodes before linearized reading' (duration: 120.399445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:00:21.851542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.101583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T19:00:21.851631Z","caller":"traceutil/trace.go:171","msg":"trace[1963084452] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:8853; }","duration":"315.247068ms","start":"2026-04-17T19:00:21.536362Z","end":"2026-04-17T19:00:21.851609Z","steps":["trace[1963084452] 'agreement among raft nodes before linearized reading' (duration: 314.986741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:00:21.851688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:00:21.536336Z","time spent":"315.337641ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-x8dcz\" "} {"level":"warn","ts":"2026-04-17T19:00:21.851224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.573916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T19:00:21.853545Z","caller":"traceutil/trace.go:171","msg":"trace[369231021] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8853; }","duration":"369.018957ms","start":"2026-04-17T19:00:21.484513Z","end":"2026-04-17T19:00:21.853532Z","steps":["trace[369231021] 'agreement among raft nodes before linearized reading' (duration: 366.584787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:00:21.853622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:00:21.484492Z","time spent":"369.117839ms","remote":"127.0.0.1:35496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T19:00:22.009943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.281799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T19:00:22.010283Z","caller":"traceutil/trace.go:171","msg":"trace[801220195] transaction","detail":"{read_only:false; response_revision:8854; number_of_response:1; }","duration":"153.835041ms","start":"2026-04-17T19:00:21.85637Z","end":"2026-04-17T19:00:22.010205Z","steps":["trace[801220195] 'process raft request' (duration: 52.187862ms)","trace[801220195] 'compare' (duration: 101.130254ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:00:22.010324Z","caller":"traceutil/trace.go:171","msg":"trace[17780465] linearizableReadLoop","detail":"{readStateIndex:9172; appliedIndex:9171; }","duration":"153.703847ms","start":"2026-04-17T19:00:21.856584Z","end":"2026-04-17T19:00:22.010288Z","steps":["trace[17780465] 'read index received' (duration: 51.986987ms)","trace[17780465] 'applied index is now lower than readState.Index' (duration: 101.71323ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:00:22.010625Z","caller":"traceutil/trace.go:171","msg":"trace[1384879322] transaction","detail":"{read_only:false; response_revision:8855; number_of_response:1; }","duration":"154.057817ms","start":"2026-04-17T19:00:21.856545Z","end":"2026-04-17T19:00:22.010603Z","steps":["trace[1384879322] 'process raft request' (duration: 153.586824ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:00:22.01091Z","caller":"traceutil/trace.go:171","msg":"trace[1455639101] transaction","detail":"{read_only:false; response_revision:8856; number_of_response:1; }","duration":"154.198141ms","start":"2026-04-17T19:00:21.856683Z","end":"2026-04-17T19:00:22.010881Z","steps":["trace[1455639101] 'process raft request' (duration: 153.878932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:00:22.010993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.388997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:234646"} {"level":"info","ts":"2026-04-17T19:00:22.011091Z","caller":"traceutil/trace.go:171","msg":"trace[2018778226] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8855; }","duration":"154.49264ms","start":"2026-04-17T19:00:21.85658Z","end":"2026-04-17T19:00:22.011073Z","steps":["trace[2018778226] 'agreement among raft nodes before linearized reading' (duration: 153.80099ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:04.891566Z","caller":"traceutil/trace.go:171","msg":"trace[1541083872] transaction","detail":"{read_only:false; response_revision:9269; number_of_response:1; }","duration":"223.414712ms","start":"2026-04-17T19:01:04.66812Z","end":"2026-04-17T19:01:04.891535Z","steps":["trace[1541083872] 'process raft request' (duration: 187.923296ms)","trace[1541083872] 'compare' (duration: 35.367942ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:01:04.947445Z","caller":"traceutil/trace.go:171","msg":"trace[349740835] linearizableReadLoop","detail":"{readStateIndex:9598; appliedIndex:9596; }","duration":"279.045933ms","start":"2026-04-17T19:01:04.66836Z","end":"2026-04-17T19:01:04.947406Z","steps":["trace[349740835] 'read index received' (duration: 187.71327ms)","trace[349740835] 'applied index is now lower than readState.Index' (duration: 91.331393ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:01:04.947601Z","caller":"traceutil/trace.go:171","msg":"trace[1594156787] transaction","detail":"{read_only:false; response_revision:9271; number_of_response:1; }","duration":"258.508445ms","start":"2026-04-17T19:01:04.689043Z","end":"2026-04-17T19:01:04.947551Z","steps":["trace[1594156787] 'process raft request' (duration: 258.280519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:04.947962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.097655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-744768d96d-rbjw8.18a739fa2f202de2\" ","response":"range_response_count:1 size:1135"} {"level":"warn","ts":"2026-04-17T19:01:04.947953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.995157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:01:04.948031Z","caller":"traceutil/trace.go:171","msg":"trace[2036734726] range","detail":"{range_begin:/registry/events/openstack/barbican-api-744768d96d-rbjw8.18a739fa2f202de2; range_end:; response_count:1; response_revision:9271; }","duration":"228.239048ms","start":"2026-04-17T19:01:04.719773Z","end":"2026-04-17T19:01:04.948012Z","steps":["trace[2036734726] 'agreement among raft nodes before linearized reading' (duration: 227.886768ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:04.948098Z","caller":"traceutil/trace.go:171","msg":"trace[1182808470] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:9271; }","duration":"117.189863ms","start":"2026-04-17T19:01:04.83086Z","end":"2026-04-17T19:01:04.94805Z","steps":["trace[1182808470] 'agreement among raft nodes before linearized reading' (duration: 116.968267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:04.948214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.855136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291236"} {"level":"info","ts":"2026-04-17T19:01:04.948269Z","caller":"traceutil/trace.go:171","msg":"trace[958457996] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9271; }","duration":"279.966509ms","start":"2026-04-17T19:01:04.668291Z","end":"2026-04-17T19:01:04.948257Z","steps":["trace[958457996] 'agreement among raft nodes before linearized reading' (duration: 279.308051ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:04.947494Z","caller":"traceutil/trace.go:171","msg":"trace[486137889] transaction","detail":"{read_only:false; response_revision:9270; number_of_response:1; }","duration":"279.318911ms","start":"2026-04-17T19:01:04.66812Z","end":"2026-04-17T19:01:04.947439Z","steps":["trace[486137889] 'process raft request' (duration: 278.997822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:08.810107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225568338899065,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T19:01:09.209523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.738598ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:01:09.209703Z","caller":"traceutil/trace.go:171","msg":"trace[869597988] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:9286; }","duration":"476.927903ms","start":"2026-04-17T19:01:08.73275Z","end":"2026-04-17T19:01:09.209678Z","steps":["trace[869597988] 'range keys from in-memory index tree' (duration: 476.717747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.209852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.351996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T19:01:09.209996Z","caller":"traceutil/trace.go:171","msg":"trace[1201185568] transaction","detail":"{read_only:false; response_revision:9287; number_of_response:1; }","duration":"1.151361676s","start":"2026-04-17T19:01:08.058615Z","end":"2026-04-17T19:01:09.209977Z","steps":["trace[1201185568] 'process raft request' (duration: 760.808244ms)","trace[1201185568] 'compare' (duration: 390.018277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:01:09.210065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:08.058586Z","time spent":"1.151456569s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T19:01:09.697456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.517415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T19:01:09.697881Z","caller":"traceutil/trace.go:171","msg":"trace[1737007512] transaction","detail":"{read_only:false; response_revision:9288; number_of_response:1; }","duration":"1.609144762s","start":"2026-04-17T19:01:08.088672Z","end":"2026-04-17T19:01:09.697816Z","steps":["trace[1737007512] 'process raft request' (duration: 1.314145703s)","trace[1737007512] 'compare' (duration: 294.34434ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:01:09.698028Z","caller":"traceutil/trace.go:171","msg":"trace[1425323734] transaction","detail":"{read_only:false; response_revision:9290; number_of_response:1; }","duration":"690.968327ms","start":"2026-04-17T19:01:09.007033Z","end":"2026-04-17T19:01:09.698001Z","steps":["trace[1425323734] 'process raft request' (duration: 690.906735ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:09.698058Z","caller":"traceutil/trace.go:171","msg":"trace[1290493491] transaction","detail":"{read_only:false; response_revision:9289; number_of_response:1; }","duration":"1.467437278s","start":"2026-04-17T19:01:08.230583Z","end":"2026-04-17T19:01:09.69802Z","steps":["trace[1290493491] 'process raft request' (duration: 1.467021456s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.698086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:08.088626Z","time spent":"1.609349248s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T19:01:09.698235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:08.230541Z","time spent":"1.467606834s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T19:01:09.69831Z","caller":"traceutil/trace.go:171","msg":"trace[1692798481] linearizableReadLoop","detail":"{readStateIndex:9619; appliedIndex:9616; }","duration":"1.388706336s","start":"2026-04-17T19:01:08.309566Z","end":"2026-04-17T19:01:09.698273Z","steps":["trace[1692798481] 'read index received' (duration: 509.979948ms)","trace[1692798481] 'applied index is now lower than readState.Index' (duration: 878.722558ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:01:09.698527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388940403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T19:01:09.698572Z","caller":"traceutil/trace.go:171","msg":"trace[100040591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9290; }","duration":"1.389074586s","start":"2026-04-17T19:01:08.309486Z","end":"2026-04-17T19:01:09.69856Z","steps":["trace[100040591] 'agreement among raft nodes before linearized reading' (duration: 1.388885641s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.698601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:08.30946Z","time spent":"1.389135038s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T19:01:09.698509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.007016Z","time spent":"691.079869ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T19:01:09.698686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.823999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x8dcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T19:01:09.698778Z","caller":"traceutil/trace.go:171","msg":"trace[386648480] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x8dcz; range_end:; response_count:1; response_revision:9290; }","duration":"685.930442ms","start":"2026-04-17T19:01:09.01283Z","end":"2026-04-17T19:01:09.698761Z","steps":["trace[386648480] 'agreement among raft nodes before linearized reading' (duration: 685.655254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.698709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298353812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-849cdd696f-j6pl9.18a73a1444456842\" ","response":"range_response_count:1 size:787"} {"level":"warn","ts":"2026-04-17T19:01:09.698752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.031401ms","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:5"} {"level":"warn","ts":"2026-04-17T19:01:09.698836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.012821Z","time spent":"686.001894ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-x8dcz\" "} {"level":"info","ts":"2026-04-17T19:01:09.698871Z","caller":"traceutil/trace.go:171","msg":"trace[788559862] range","detail":"{range_begin:/registry/events/openstack/glance-api-849cdd696f-j6pl9.18a73a1444456842; range_end:; response_count:1; response_revision:9290; }","duration":"1.298552727s","start":"2026-04-17T19:01:08.400288Z","end":"2026-04-17T19:01:09.69884Z","steps":["trace[788559862] 'agreement among raft nodes before linearized reading' (duration: 1.298171157s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.698933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:08.400264Z","time spent":"1.29865118s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":809,"request content":"key:\"/registry/events/openstack/glance-api-849cdd696f-j6pl9.18a73a1444456842\" "} {"level":"info","ts":"2026-04-17T19:01:09.698943Z","caller":"traceutil/trace.go:171","msg":"trace[694728824] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:9290; }","duration":"308.198157ms","start":"2026-04-17T19:01:09.390694Z","end":"2026-04-17T19:01:09.698893Z","steps":["trace[694728824] 'agreement among raft nodes before linearized reading' (duration: 307.94459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.698944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.413138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T19:01:09.698762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.170015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:01:09.69903Z","caller":"traceutil/trace.go:171","msg":"trace[1473722919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9290; }","duration":"770.558043ms","start":"2026-04-17T19:01:08.928451Z","end":"2026-04-17T19:01:09.699009Z","steps":["trace[1473722919] 'agreement among raft nodes before linearized reading' (duration: 770.240874ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:09.699062Z","caller":"traceutil/trace.go:171","msg":"trace[277667658] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:9290; }","duration":"746.533266ms","start":"2026-04-17T19:01:08.952497Z","end":"2026-04-17T19:01:09.69903Z","steps":["trace[277667658] 'agreement among raft nodes before linearized reading' (duration: 746.138545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.699084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:08.928425Z","time spent":"770.649245ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T19:01:09.699138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:08.952474Z","time spent":"746.642179ms","remote":"127.0.0.1:35236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T19:01:09.699164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.390623Z","time spent":"308.360551ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-17T19:01:09.699454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237314895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T19:01:09.699522Z","caller":"traceutil/trace.go:171","msg":"trace[273145430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9290; }","duration":"1.237423858s","start":"2026-04-17T19:01:08.462086Z","end":"2026-04-17T19:01:09.699509Z","steps":["trace[273145430] 'agreement among raft nodes before linearized reading' (duration: 1.236659267s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.699609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:08.462066Z","time spent":"1.237533352s","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T19:01:09.699447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.33963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T19:01:09.699865Z","caller":"traceutil/trace.go:171","msg":"trace[1913217964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9290; }","duration":"617.810943ms","start":"2026-04-17T19:01:09.082039Z","end":"2026-04-17T19:01:09.69985Z","steps":["trace[1913217964] 'agreement among raft nodes before linearized reading' (duration: 616.675221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.699917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.082014Z","time spent":"617.889255ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T19:01:09.700143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.777505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-17T19:01:09.70025Z","caller":"traceutil/trace.go:171","msg":"trace[586424654] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9290; }","duration":"694.9389ms","start":"2026-04-17T19:01:09.005299Z","end":"2026-04-17T19:01:09.700238Z","steps":["trace[586424654] 'agreement among raft nodes before linearized reading' (duration: 693.406907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:09.700341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.005278Z","time spent":"695.014451ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-17T19:01:09.945079Z","caller":"traceutil/trace.go:171","msg":"trace[1482883520] transaction","detail":"{read_only:false; response_revision:9291; number_of_response:1; }","duration":"242.771956ms","start":"2026-04-17T19:01:09.702106Z","end":"2026-04-17T19:01:09.944878Z","steps":["trace[1482883520] 'process raft request' (duration: 145.173614ms)","trace[1482883520] 'compare' (duration: 97.452008ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:01:10.040152Z","caller":"traceutil/trace.go:171","msg":"trace[494735820] transaction","detail":"{read_only:false; response_revision:9292; number_of_response:1; }","duration":"337.070403ms","start":"2026-04-17T19:01:09.703039Z","end":"2026-04-17T19:01:10.04011Z","steps":["trace[494735820] 'process raft request' (duration: 336.699083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:10.040497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.703001Z","time spent":"337.250718ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T19:01:10.040781Z","caller":"traceutil/trace.go:171","msg":"trace[282794686] transaction","detail":"{read_only:false; response_revision:9293; number_of_response:1; }","duration":"336.728413ms","start":"2026-04-17T19:01:09.704034Z","end":"2026-04-17T19:01:10.040763Z","steps":["trace[282794686] 'process raft request' (duration: 335.92979ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:10.040846Z","caller":"traceutil/trace.go:171","msg":"trace[1811231392] linearizableReadLoop","detail":"{readStateIndex:9622; appliedIndex:9620; }","duration":"336.973979ms","start":"2026-04-17T19:01:09.70385Z","end":"2026-04-17T19:01:10.040824Z","steps":["trace[1811231392] 'read index received' (duration: 143.443883ms)","trace[1811231392] 'applied index is now lower than readState.Index' (duration: 193.528956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:01:10.040977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.704012Z","time spent":"336.819026ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T19:01:10.040961Z","caller":"traceutil/trace.go:171","msg":"trace[1393428905] transaction","detail":"{read_only:false; response_revision:9294; number_of_response:1; }","duration":"336.676721ms","start":"2026-04-17T19:01:09.704244Z","end":"2026-04-17T19:01:10.04092Z","steps":["trace[1393428905] 'process raft request' (duration: 336.388573ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:10.041016Z","caller":"traceutil/trace.go:171","msg":"trace[214122944] transaction","detail":"{read_only:false; response_revision:9296; number_of_response:1; }","duration":"336.145246ms","start":"2026-04-17T19:01:09.704844Z","end":"2026-04-17T19:01:10.04099Z","steps":["trace[214122944] 'process raft request' (duration: 335.93963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:10.041126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.704829Z","time spent":"336.239229ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T19:01:10.041148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.704229Z","time spent":"336.816836ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T19:01:10.041134Z","caller":"traceutil/trace.go:171","msg":"trace[724959535] transaction","detail":"{read_only:false; response_revision:9295; number_of_response:1; }","duration":"336.257879ms","start":"2026-04-17T19:01:09.704837Z","end":"2026-04-17T19:01:10.041095Z","steps":["trace[724959535] 'process raft request' (duration: 335.903099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:10.041262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.852442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T19:01:10.041289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.258265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T19:01:10.041312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.704822Z","time spent":"336.393153ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T19:01:10.041324Z","caller":"traceutil/trace.go:171","msg":"trace[1401635396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9296; }","duration":"310.964686ms","start":"2026-04-17T19:01:09.730339Z","end":"2026-04-17T19:01:10.041304Z","steps":["trace[1401635396] 'agreement among raft nodes before linearized reading' (duration: 310.854593ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:10.041348Z","caller":"traceutil/trace.go:171","msg":"trace[495648551] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:9296; }","duration":"138.317107ms","start":"2026-04-17T19:01:09.903013Z","end":"2026-04-17T19:01:10.04133Z","steps":["trace[495648551] 'agreement among raft nodes before linearized reading' (duration: 138.224644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:10.041329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.385884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T19:01:10.041371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.730316Z","time spent":"311.043419ms","remote":"127.0.0.1:35206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T19:01:10.041428Z","caller":"traceutil/trace.go:171","msg":"trace[726336546] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:9296; }","duration":"120.544848ms","start":"2026-04-17T19:01:09.92086Z","end":"2026-04-17T19:01:10.041404Z","steps":["trace[726336546] 'agreement among raft nodes before linearized reading' (duration: 120.395144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:10.041428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.563946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291236"} {"level":"warn","ts":"2026-04-17T19:01:10.041353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.254359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:01:10.041485Z","caller":"traceutil/trace.go:171","msg":"trace[98658249] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9296; }","duration":"337.647969ms","start":"2026-04-17T19:01:09.703821Z","end":"2026-04-17T19:01:10.041469Z","steps":["trace[98658249] 'agreement among raft nodes before linearized reading' (duration: 337.133754ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:10.041508Z","caller":"traceutil/trace.go:171","msg":"trace[594869421] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:9296; }","duration":"149.450535ms","start":"2026-04-17T19:01:09.892034Z","end":"2026-04-17T19:01:10.041485Z","steps":["trace[594869421] 'agreement among raft nodes before linearized reading' (duration: 149.27785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:10.041549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:01:09.703805Z","time spent":"337.730191ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":31,"response size":291258,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-04-17T19:01:10.042498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.835708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T19:01:10.042571Z","caller":"traceutil/trace.go:171","msg":"trace[851424003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9296; }","duration":"177.963411ms","start":"2026-04-17T19:01:09.864593Z","end":"2026-04-17T19:01:10.042557Z","steps":["trace[851424003] 'agreement among raft nodes before linearized reading' (duration: 176.793457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:30.5196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.060461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T19:01:30.519922Z","caller":"traceutil/trace.go:171","msg":"trace[184742949] transaction","detail":"{read_only:false; response_revision:9456; number_of_response:1; }","duration":"271.596949ms","start":"2026-04-17T19:01:30.248281Z","end":"2026-04-17T19:01:30.519878Z","steps":["trace[184742949] 'process raft request' (duration: 63.120776ms)","trace[184742949] 'compare' (duration: 207.740442ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:01:30.520079Z","caller":"traceutil/trace.go:171","msg":"trace[1888045416] transaction","detail":"{read_only:false; response_revision:9459; number_of_response:1; }","duration":"241.760946ms","start":"2026-04-17T19:01:30.278287Z","end":"2026-04-17T19:01:30.520048Z","steps":["trace[1888045416] 'process raft request' (duration: 241.699444ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:30.520104Z","caller":"traceutil/trace.go:171","msg":"trace[2002208771] transaction","detail":"{read_only:false; response_revision:9457; number_of_response:1; }","duration":"271.791916ms","start":"2026-04-17T19:01:30.24828Z","end":"2026-04-17T19:01:30.520072Z","steps":["trace[2002208771] 'process raft request' (duration: 271.485917ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:30.520208Z","caller":"traceutil/trace.go:171","msg":"trace[1405126214] linearizableReadLoop","detail":"{readStateIndex:9791; appliedIndex:9789; }","duration":"268.744748ms","start":"2026-04-17T19:01:30.251421Z","end":"2026-04-17T19:01:30.520165Z","steps":["trace[1405126214] 'read index received' (duration: 60.040408ms)","trace[1405126214] 'applied index is now lower than readState.Index' (duration: 208.69537ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:01:30.520182Z","caller":"traceutil/trace.go:171","msg":"trace[1645252570] transaction","detail":"{read_only:false; response_revision:9458; number_of_response:1; }","duration":"268.669395ms","start":"2026-04-17T19:01:30.251499Z","end":"2026-04-17T19:01:30.520168Z","steps":["trace[1645252570] 'process raft request' (duration: 268.427709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:30.520724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.22182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T19:01:30.520768Z","caller":"traceutil/trace.go:171","msg":"trace[1336164624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9459; }","duration":"263.306182ms","start":"2026-04-17T19:01:30.25745Z","end":"2026-04-17T19:01:30.520756Z","steps":["trace[1336164624] 'agreement among raft nodes before linearized reading' (duration: 263.145297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:01:30.520891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.445909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:33 size:306005"} {"level":"info","ts":"2026-04-17T19:01:30.520941Z","caller":"traceutil/trace.go:171","msg":"trace[1748156856] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:33; response_revision:9459; }","duration":"269.515451ms","start":"2026-04-17T19:01:30.251416Z","end":"2026-04-17T19:01:30.520932Z","steps":["trace[1748156856] 'agreement among raft nodes before linearized reading' (duration: 269.081939ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:35.988122Z","caller":"traceutil/trace.go:171","msg":"trace[779124678] linearizableReadLoop","detail":"{readStateIndex:9819; appliedIndex:9818; }","duration":"131.42666ms","start":"2026-04-17T19:01:35.85667Z","end":"2026-04-17T19:01:35.988097Z","steps":["trace[779124678] 'read index received' (duration: 90.307104ms)","trace[779124678] 'applied index is now lower than readState.Index' (duration: 41.118626ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:01:35.98829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.590614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T19:01:35.988272Z","caller":"traceutil/trace.go:171","msg":"trace[662744814] transaction","detail":"{read_only:false; response_revision:9484; number_of_response:1; }","duration":"168.936432ms","start":"2026-04-17T19:01:35.819293Z","end":"2026-04-17T19:01:35.988229Z","steps":["trace[662744814] 'process raft request' (duration: 127.760355ms)","trace[662744814] 'compare' (duration: 40.92661ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:01:35.988333Z","caller":"traceutil/trace.go:171","msg":"trace[305264520] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:9484; }","duration":"131.706757ms","start":"2026-04-17T19:01:35.856614Z","end":"2026-04-17T19:01:35.988321Z","steps":["trace[305264520] 'agreement among raft nodes before linearized reading' (duration: 131.586574ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:02:00.514604Z","caller":"traceutil/trace.go:171","msg":"trace[79336036] transaction","detail":"{read_only:false; response_revision:9648; number_of_response:1; }","duration":"103.883912ms","start":"2026-04-17T19:02:00.410674Z","end":"2026-04-17T19:02:00.514558Z","steps":["trace[79336036] 'process raft request' (duration: 73.052109ms)","trace[79336036] 'compare' (duration: 30.524844ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:02:00.876808Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"warn","ts":"2026-04-17T19:02:00.932566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.360598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:35 size:329056"} {"level":"info","ts":"2026-04-17T19:02:00.932713Z","caller":"traceutil/trace.go:171","msg":"trace[165629797] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:35; response_revision:9662; }","duration":"124.550593ms","start":"2026-04-17T19:02:00.808137Z","end":"2026-04-17T19:02:00.932687Z","steps":["trace[165629797] 'agreement among raft nodes before linearized reading' (duration: 69.078307ms)","trace[165629797] 'range keys from in-memory index tree' (duration: 54.635522ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:02:01.017771Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-04-17T19:02:01.017936Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-04-17T19:02:54.449985Z","caller":"traceutil/trace.go:171","msg":"trace[646706255] transaction","detail":"{read_only:false; response_revision:10046; number_of_response:1; }","duration":"132.334889ms","start":"2026-04-17T19:02:54.3176Z","end":"2026-04-17T19:02:54.449935Z","steps":["trace[646706255] 'process raft request' (duration: 131.881276ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:02:58.556943Z","caller":"traceutil/trace.go:171","msg":"trace[1466128842] transaction","detail":"{read_only:false; response_revision:10068; number_of_response:1; }","duration":"189.86085ms","start":"2026-04-17T19:02:58.367046Z","end":"2026-04-17T19:02:58.556907Z","steps":["trace[1466128842] 'process raft request' (duration: 102.180729ms)","trace[1466128842] 'compare' (duration: 87.162105ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:03:11.992936Z","caller":"traceutil/trace.go:171","msg":"trace[710564650] transaction","detail":"{read_only:false; response_revision:10137; number_of_response:1; }","duration":"106.077432ms","start":"2026-04-17T19:03:11.886814Z","end":"2026-04-17T19:03:11.992891Z","steps":["trace[710564650] 'process raft request' (duration: 105.840206ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:03:51.764796Z","caller":"traceutil/trace.go:171","msg":"trace[543367598] transaction","detail":"{read_only:false; response_revision:10341; number_of_response:1; }","duration":"162.950443ms","start":"2026-04-17T19:03:51.601805Z","end":"2026-04-17T19:03:51.764755Z","steps":["trace[543367598] 'process raft request' (duration: 144.735247ms)","trace[543367598] 'compare' (duration: 18.033881ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:04:13.004966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8247} {"level":"info","ts":"2026-04-17T19:04:13.103001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8247,"took":"95.898689ms","hash":3999939564,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":23781376,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-17T19:04:13.103094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3999939564,"revision":8247,"compact-revision":6050} {"level":"info","ts":"2026-04-17T19:05:01.962096Z","caller":"traceutil/trace.go:171","msg":"trace[207906146] transaction","detail":"{read_only:false; response_revision:10723; number_of_response:1; }","duration":"181.293557ms","start":"2026-04-17T19:05:01.780765Z","end":"2026-04-17T19:05:01.962058Z","steps":["trace[207906146] 'process raft request' (duration: 172.648748ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:05:02.008086Z","caller":"traceutil/trace.go:171","msg":"trace[1158108841] linearizableReadLoop","detail":"{readStateIndex:11105; appliedIndex:11103; }","duration":"160.845288ms","start":"2026-04-17T19:05:01.847206Z","end":"2026-04-17T19:05:02.008051Z","steps":["trace[1158108841] 'read index received' (duration: 106.230917ms)","trace[1158108841] 'applied index is now lower than readState.Index' (duration: 54.612711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:05:02.008513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.299401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11836"} {"level":"warn","ts":"2026-04-17T19:05:02.008517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.61122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T19:05:02.008371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.531888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:05:02.008596Z","caller":"traceutil/trace.go:171","msg":"trace[1483420678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10723; }","duration":"136.748355ms","start":"2026-04-17T19:05:01.871828Z","end":"2026-04-17T19:05:02.008577Z","steps":["trace[1483420678] 'agreement among raft nodes before linearized reading' (duration: 136.499037ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:05:02.008598Z","caller":"traceutil/trace.go:171","msg":"trace[1731432639] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:10723; }","duration":"161.419735ms","start":"2026-04-17T19:05:01.847156Z","end":"2026-04-17T19:05:02.008575Z","steps":["trace[1731432639] 'agreement among raft nodes before linearized reading' (duration: 161.157976ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:05:02.008636Z","caller":"traceutil/trace.go:171","msg":"trace[831346682] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:10723; }","duration":"144.91371ms","start":"2026-04-17T19:05:01.863701Z","end":"2026-04-17T19:05:02.008615Z","steps":["trace[831346682] 'agreement among raft nodes before linearized reading' (duration: 144.555189ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:05:02.704543Z","caller":"traceutil/trace.go:171","msg":"trace[1309317582] transaction","detail":"{read_only:false; response_revision:10729; number_of_response:1; }","duration":"104.715103ms","start":"2026-04-17T19:05:02.599802Z","end":"2026-04-17T19:05:02.704518Z","steps":["trace[1309317582] 'process raft request' (duration: 104.551618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:05:02.879402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.843103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:05:02.879494Z","caller":"traceutil/trace.go:171","msg":"trace[1143342686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10729; }","duration":"148.003607ms","start":"2026-04-17T19:05:02.731469Z","end":"2026-04-17T19:05:02.879472Z","steps":["trace[1143342686] 'range keys from in-memory index tree' (duration: 147.73632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:05:02.879835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.885404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:05:02.879905Z","caller":"traceutil/trace.go:171","msg":"trace[1271505578] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:10729; }","duration":"116.541154ms","start":"2026-04-17T19:05:02.763344Z","end":"2026-04-17T19:05:02.879885Z","steps":["trace[1271505578] 'count revisions from in-memory index tree' (duration: 115.778391ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:05:12.371036Z","caller":"traceutil/trace.go:171","msg":"trace[1125576044] transaction","detail":"{read_only:false; response_revision:10853; number_of_response:1; }","duration":"172.301628ms","start":"2026-04-17T19:05:12.198699Z","end":"2026-04-17T19:05:12.371001Z","steps":["trace[1125576044] 'process raft request' (duration: 171.980948ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:05:31.500252Z","caller":"traceutil/trace.go:171","msg":"trace[417622794] transaction","detail":"{read_only:false; response_revision:11004; number_of_response:1; }","duration":"165.725088ms","start":"2026-04-17T19:05:31.3345Z","end":"2026-04-17T19:05:31.500225Z","steps":["trace[417622794] 'process raft request' (duration: 165.542673ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:05:31.513153Z","caller":"traceutil/trace.go:171","msg":"trace[246525885] transaction","detail":"{read_only:false; response_revision:11005; number_of_response:1; }","duration":"138.107985ms","start":"2026-04-17T19:05:31.375014Z","end":"2026-04-17T19:05:31.513122Z","steps":["trace[246525885] 'process raft request' (duration: 137.873398ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:06:31.958146Z","caller":"traceutil/trace.go:171","msg":"trace[1067213142] transaction","detail":"{read_only:false; response_revision:11335; number_of_response:1; }","duration":"109.898492ms","start":"2026-04-17T19:06:31.848222Z","end":"2026-04-17T19:06:31.958121Z","steps":["trace[1067213142] 'process raft request' (duration: 109.746147ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:06:32.028493Z","caller":"traceutil/trace.go:171","msg":"trace[1641957903] transaction","detail":"{read_only:false; response_revision:11336; number_of_response:1; }","duration":"172.078685ms","start":"2026-04-17T19:06:31.856354Z","end":"2026-04-17T19:06:32.028432Z","steps":["trace[1641957903] 'process raft request' (duration: 171.830607ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:06:32.123882Z","caller":"traceutil/trace.go:171","msg":"trace[1841875707] linearizableReadLoop","detail":"{readStateIndex:11737; appliedIndex:11736; }","duration":"260.341195ms","start":"2026-04-17T19:06:31.863487Z","end":"2026-04-17T19:06:32.123829Z","steps":["trace[1841875707] 'read index received' (duration: 94.630993ms)","trace[1841875707] 'applied index is now lower than readState.Index' (duration: 165.706842ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:06:32.123923Z","caller":"traceutil/trace.go:171","msg":"trace[463925318] transaction","detail":"{read_only:false; response_revision:11337; number_of_response:1; }","duration":"178.974302ms","start":"2026-04-17T19:06:31.944883Z","end":"2026-04-17T19:06:32.123857Z","steps":["trace[463925318] 'process raft request' (duration: 178.657582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:06:32.140095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.794947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"warn","ts":"2026-04-17T19:06:32.140137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.60222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T19:06:32.140188Z","caller":"traceutil/trace.go:171","msg":"trace[218931102] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11337; }","duration":"254.940121ms","start":"2026-04-17T19:06:31.885224Z","end":"2026-04-17T19:06:32.140164Z","steps":["trace[218931102] 'agreement among raft nodes before linearized reading' (duration: 238.83537ms)","trace[218931102] 'range keys from in-memory index tree' (duration: 15.873945ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:06:32.140232Z","caller":"traceutil/trace.go:171","msg":"trace[745195555] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:11337; }","duration":"276.755535ms","start":"2026-04-17T19:06:31.863454Z","end":"2026-04-17T19:06:32.140209Z","steps":["trace[745195555] 'agreement among raft nodes before linearized reading' (duration: 260.51774ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:09:13.012166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10447} {"level":"info","ts":"2026-04-17T19:09:13.093259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10447,"took":"78.691077ms","hash":892483054,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":20594688,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-17T19:09:13.093357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":892483054,"revision":10447,"compact-revision":8247}