{"level":"warn","ts":"2026-08-13T18:29:04.147886Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T18:29:04.147982Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-08-13T18:29:04.148733Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T18:29:04.148756Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-13T18:29:04.148782Z","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-08-13T18:29:04.149714Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-08-13T18:29:04.149832Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-08-13T18:29:04.154103Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.023146ms"} {"level":"info","ts":"2026-08-13T18:29:04.163395Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-08-13T18:29:04.163469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T18:29:04.163491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-08-13T18:29:04.1635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T18:29:04.16351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-08-13T18:29:04.163549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-08-13T18:29:04.17409Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T18:29:04.178492Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T18:29:04.180168Z","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-08-13T18:29:04.182346Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T18:29:04.18261Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T18:29:04.182642Z","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-08-13T18:29:04.182785Z","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-08-13T18:29:04.18311Z","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-08-13T18:29:04.183587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-08-13T18:29:04.183836Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-13T18:29:04.184986Z","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-08-13T18:29:04.185052Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-13T18:29:04.185082Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-13T18:29:04.185199Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T18:29:04.185262Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T18:29:04.56473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T18:29:04.564789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T18:29:04.56483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-08-13T18:29:04.564849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-08-13T18:29:04.56486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-13T18:29:04.564876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-08-13T18:29:04.564894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-13T18:29:04.566166Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T18:29:04.566357Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T18:29:04.566431Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T18:29:04.566505Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T18:29:04.566569Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T18:29:04.566582Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:29:04.567675Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:29:04.567786Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:29:04.56782Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:29:04.569541Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-08-13T18:29:04.569747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-13T18:29:07.225624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.719644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T18:29:07.225812Z","caller":"traceutil/trace.go:171","msg":"trace[2010508124] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"204.812066ms","start":"2026-08-13T18:29:07.020982Z","end":"2026-08-13T18:29:07.225794Z","steps":["trace[2010508124] 'read index received' (duration: 84.827038ms)","trace[2010508124] 'applied index is now lower than readState.Index' (duration: 119.983708ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:29:07.225842Z","caller":"traceutil/trace.go:171","msg":"trace[890999215] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"272.991837ms","start":"2026-08-13T18:29:06.95282Z","end":"2026-08-13T18:29:07.225812Z","steps":["trace[890999215] 'process raft request' (duration: 152.997218ms)","trace[890999215] 'compare' (duration: 118.543691ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:29:07.226015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.99993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-13T18:29:07.226065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.102074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T18:29:07.226111Z","caller":"traceutil/trace.go:171","msg":"trace[1206372372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:113; }","duration":"205.112142ms","start":"2026-08-13T18:29:07.020977Z","end":"2026-08-13T18:29:07.226089Z","steps":["trace[1206372372] 'agreement among raft nodes before linearized reading' (duration: 204.893308ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:29:07.226134Z","caller":"traceutil/trace.go:171","msg":"trace[1832609049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:113; }","duration":"181.211516ms","start":"2026-08-13T18:29:07.044882Z","end":"2026-08-13T18:29:07.226093Z","steps":["trace[1832609049] 'agreement among raft nodes before linearized reading' (duration: 181.098704ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:29:18.975608Z","caller":"traceutil/trace.go:171","msg":"trace[453732290] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"179.44573ms","start":"2026-08-13T18:29:18.79614Z","end":"2026-08-13T18:29:18.975586Z","steps":["trace[453732290] 'read index received' (duration: 179.195885ms)","trace[453732290] 'applied index is now lower than readState.Index' (duration: 248.775µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:29:18.975687Z","caller":"traceutil/trace.go:171","msg":"trace[988635017] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"322.328053ms","start":"2026-08-13T18:29:18.65332Z","end":"2026-08-13T18:29:18.975649Z","steps":["trace[988635017] 'process raft request' (duration: 322.091299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:18.975848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.701925ms","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-08-13T18:29:18.975911Z","caller":"traceutil/trace.go:171","msg":"trace[1149560866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"179.822897ms","start":"2026-08-13T18:29:18.796075Z","end":"2026-08-13T18:29:18.975898Z","steps":["trace[1149560866] 'agreement among raft nodes before linearized reading' (duration: 179.654994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:18.975947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.522738ms","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-08-13T18:29:18.976044Z","caller":"traceutil/trace.go:171","msg":"trace[1095971542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"154.685611ms","start":"2026-08-13T18:29:18.821339Z","end":"2026-08-13T18:29:18.976024Z","steps":["trace[1095971542] 'agreement among raft nodes before linearized reading' (duration: 154.426146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:18.976564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:29:18.653289Z","time spent":"322.564077ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:29:43.487421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.153891ms","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-08-13T18:29:43.487539Z","caller":"traceutil/trace.go:171","msg":"trace[346968993] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:599; }","duration":"184.285544ms","start":"2026-08-13T18:29:43.303235Z","end":"2026-08-13T18:29:43.487521Z","steps":["trace[346968993] 'range keys from in-memory index tree' (duration: 184.125701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:43.487755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.886299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T18:29:43.488095Z","caller":"traceutil/trace.go:171","msg":"trace[917196431] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"271.603592ms","start":"2026-08-13T18:29:43.21639Z","end":"2026-08-13T18:29:43.487993Z","steps":["trace[917196431] 'process raft request' (duration: 167.401517ms)","trace[917196431] 'compare' (duration: 103.616004ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:29:43.493792Z","caller":"traceutil/trace.go:171","msg":"trace[147674076] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"219.362741ms","start":"2026-08-13T18:29:43.274387Z","end":"2026-08-13T18:29:43.49375Z","steps":["trace[147674076] 'process raft request' (duration: 219.126827ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:29:49.661078Z","caller":"traceutil/trace.go:171","msg":"trace[547426584] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"480.068134ms","start":"2026-08-13T18:29:49.180915Z","end":"2026-08-13T18:29:49.660984Z","steps":["trace[547426584] 'read index received' (duration: 478.88072ms)","trace[547426584] 'applied index is now lower than readState.Index' (duration: 1.186254ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:29:49.661172Z","caller":"traceutil/trace.go:171","msg":"trace[1593933568] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"649.740623ms","start":"2026-08-13T18:29:49.011397Z","end":"2026-08-13T18:29:49.661137Z","steps":["trace[1593933568] 'process raft request' (duration: 648.889286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:49.661372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:29:49.011355Z","time spent":"649.910787ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:29:49.661402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.466701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-13T18:29:49.66149Z","caller":"traceutil/trace.go:171","msg":"trace[284342364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"480.679985ms","start":"2026-08-13T18:29:49.180791Z","end":"2026-08-13T18:29:49.661471Z","steps":["trace[284342364] 'agreement among raft nodes before linearized reading' (duration: 480.40955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:49.661541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:29:49.180767Z","time spent":"480.765387ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T18:29:49.661385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.63152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:29:49.661812Z","caller":"traceutil/trace.go:171","msg":"trace[1373321678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"473.099328ms","start":"2026-08-13T18:29:49.188701Z","end":"2026-08-13T18:29:49.6618Z","steps":["trace[1373321678] 'agreement among raft nodes before linearized reading' (duration: 472.623999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:49.661848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:29:49.18869Z","time spent":"473.147729ms","remote":"127.0.0.1:45362","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-08-13T18:29:49.661882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.520118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-13T18:29:49.661931Z","caller":"traceutil/trace.go:171","msg":"trace[743768575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"470.61487ms","start":"2026-08-13T18:29:49.191303Z","end":"2026-08-13T18:29:49.661918Z","steps":["trace[743768575] 'agreement among raft nodes before linearized reading' (duration: 470.436777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:49.661968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:29:49.191278Z","time spent":"470.682751ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T18:29:49.662399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.996728ms","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-08-13T18:29:49.66249Z","caller":"traceutil/trace.go:171","msg":"trace[463439943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"138.153931ms","start":"2026-08-13T18:29:49.524315Z","end":"2026-08-13T18:29:49.662469Z","steps":["trace[463439943] 'agreement among raft nodes before linearized reading' (duration: 137.183372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:30:30.182851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.941366ms","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-08-13T18:30:30.18295Z","caller":"traceutil/trace.go:171","msg":"trace[1435141847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1188; }","duration":"135.100519ms","start":"2026-08-13T18:30:30.047832Z","end":"2026-08-13T18:30:30.182932Z","steps":["trace[1435141847] 'range keys from in-memory index tree' (duration: 134.770492ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:18.181939Z","caller":"traceutil/trace.go:171","msg":"trace[745561869] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"170.598378ms","start":"2026-08-13T18:31:18.011319Z","end":"2026-08-13T18:31:18.181917Z","steps":["trace[745561869] 'process raft request' (duration: 170.331753ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:18.182145Z","caller":"traceutil/trace.go:171","msg":"trace[187366994] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1928; }","duration":"103.511453ms","start":"2026-08-13T18:31:18.07861Z","end":"2026-08-13T18:31:18.182121Z","steps":["trace[187366994] 'read index received' (duration: 72.905856ms)","trace[187366994] 'applied index is now lower than readState.Index' (duration: 30.604317ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:31:18.182467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.814619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T18:31:18.182618Z","caller":"traceutil/trace.go:171","msg":"trace[1433531794] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1857; }","duration":"104.017973ms","start":"2026-08-13T18:31:18.078572Z","end":"2026-08-13T18:31:18.18259Z","steps":["trace[1433531794] 'agreement among raft nodes before linearized reading' (duration: 103.754568ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:32:06.719833Z","caller":"traceutil/trace.go:171","msg":"trace[1773662518] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2251; }","duration":"130.421115ms","start":"2026-08-13T18:32:06.589357Z","end":"2026-08-13T18:32:06.719778Z","steps":["trace[1773662518] 'read index received' (duration: 48.185676ms)","trace[1773662518] 'applied index is now lower than readState.Index' (duration: 82.233589ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:32:06.719882Z","caller":"traceutil/trace.go:171","msg":"trace[308330804] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"159.794817ms","start":"2026-08-13T18:32:06.560056Z","end":"2026-08-13T18:32:06.719851Z","steps":["trace[308330804] 'process raft request' (duration: 77.538498ms)","trace[308330804] 'compare' (duration: 82.028625ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:32:06.720142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.757991ms","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-08-13T18:32:06.720186Z","caller":"traceutil/trace.go:171","msg":"trace[353241408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2169; }","duration":"130.857224ms","start":"2026-08-13T18:32:06.589319Z","end":"2026-08-13T18:32:06.720176Z","steps":["trace[353241408] 'agreement among raft nodes before linearized reading' (duration: 130.67083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:32:06.720292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.377485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-13T18:32:06.720375Z","caller":"traceutil/trace.go:171","msg":"trace[1594976307] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2169; }","duration":"112.529669ms","start":"2026-08-13T18:32:06.607824Z","end":"2026-08-13T18:32:06.720354Z","steps":["trace[1594976307] 'agreement among raft nodes before linearized reading' (duration: 112.326694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:32:06.720435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.587136ms","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-08-13T18:32:06.720703Z","caller":"traceutil/trace.go:171","msg":"trace[1079350059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2169; }","duration":"120.907182ms","start":"2026-08-13T18:32:06.599772Z","end":"2026-08-13T18:32:06.72068Z","steps":["trace[1079350059] 'agreement among raft nodes before linearized reading' (duration: 120.353241ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:32:13.023871Z","caller":"traceutil/trace.go:171","msg":"trace[347031473] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"173.231498ms","start":"2026-08-13T18:32:12.850601Z","end":"2026-08-13T18:32:13.023833Z","steps":["trace[347031473] 'process raft request' (duration: 104.780907ms)","trace[347031473] 'compare' (duration: 68.285378ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:32:13.109205Z","caller":"traceutil/trace.go:171","msg":"trace[1529793947] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"256.552308ms","start":"2026-08-13T18:32:12.852612Z","end":"2026-08-13T18:32:13.109164Z","steps":["trace[1529793947] 'process raft request' (duration: 256.501107ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:32:13.109222Z","caller":"traceutil/trace.go:171","msg":"trace[1497095027] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"257.641769ms","start":"2026-08-13T18:32:12.851521Z","end":"2026-08-13T18:32:13.109163Z","steps":["trace[1497095027] 'process raft request' (duration: 257.406344ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:32:13.109269Z","caller":"traceutil/trace.go:171","msg":"trace[2105144498] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"256.765883ms","start":"2026-08-13T18:32:12.852428Z","end":"2026-08-13T18:32:13.109194Z","steps":["trace[2105144498] 'process raft request' (duration: 256.6403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:32:47.231047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.276935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-13T18:32:47.231216Z","caller":"traceutil/trace.go:171","msg":"trace[1605345135] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2562; }","duration":"146.50337ms","start":"2026-08-13T18:32:47.084688Z","end":"2026-08-13T18:32:47.231191Z","steps":["trace[1605345135] 'range keys from in-memory index tree' (duration: 146.078502ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:29.316704Z","caller":"traceutil/trace.go:171","msg":"trace[1675055929] linearizableReadLoop","detail":"{readStateIndex:5191; appliedIndex:5190; }","duration":"127.141087ms","start":"2026-08-13T18:37:29.189539Z","end":"2026-08-13T18:37:29.31668Z","steps":["trace[1675055929] 'read index received' (duration: 123.278534ms)","trace[1675055929] 'applied index is now lower than readState.Index' (duration: 3.861343ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:37:29.316771Z","caller":"traceutil/trace.go:171","msg":"trace[328031521] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"155.083171ms","start":"2026-08-13T18:37:29.161653Z","end":"2026-08-13T18:37:29.316736Z","steps":["trace[328031521] 'process raft request' (duration: 151.122586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:29.316906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.350381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:37:29.316951Z","caller":"traceutil/trace.go:171","msg":"trace[159536134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5001; }","duration":"127.442564ms","start":"2026-08-13T18:37:29.189495Z","end":"2026-08-13T18:37:29.316938Z","steps":["trace[159536134] 'agreement among raft nodes before linearized reading' (duration: 127.30134ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:29.681369Z","caller":"traceutil/trace.go:171","msg":"trace[1746938154] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"183.390232ms","start":"2026-08-13T18:37:29.497938Z","end":"2026-08-13T18:37:29.681329Z","steps":["trace[1746938154] 'process raft request' (duration: 183.122176ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:29.718452Z","caller":"traceutil/trace.go:171","msg":"trace[325378551] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"183.816521ms","start":"2026-08-13T18:37:29.534603Z","end":"2026-08-13T18:37:29.71842Z","steps":["trace[325378551] 'process raft request' (duration: 183.633437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:43.377568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.70585ms","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-08-13T18:37:43.377613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.487774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-13T18:37:43.377688Z","caller":"traceutil/trace.go:171","msg":"trace[412115742] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5275; }","duration":"126.893214ms","start":"2026-08-13T18:37:43.25077Z","end":"2026-08-13T18:37:43.377664Z","steps":["trace[412115742] 'range keys from in-memory index tree' (duration: 126.482876ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:43.377702Z","caller":"traceutil/trace.go:171","msg":"trace[1188083297] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5275; }","duration":"161.708518ms","start":"2026-08-13T18:37:43.215972Z","end":"2026-08-13T18:37:43.377681Z","steps":["trace[1188083297] 'range keys from in-memory index tree' (duration: 161.226069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:44.164381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.433328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T18:37:44.164896Z","caller":"traceutil/trace.go:171","msg":"trace[2106601234] linearizableReadLoop","detail":"{readStateIndex:5480; appliedIndex:5478; }","duration":"546.453065ms","start":"2026-08-13T18:37:43.618421Z","end":"2026-08-13T18:37:44.164874Z","steps":["trace[2106601234] 'read index received' (duration: 27.300351ms)","trace[2106601234] 'applied index is now lower than readState.Index' (duration: 519.151124ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:37:44.165086Z","caller":"traceutil/trace.go:171","msg":"trace[1861635573] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"553.012531ms","start":"2026-08-13T18:37:43.61206Z","end":"2026-08-13T18:37:44.165072Z","steps":["trace[1861635573] 'process raft request' (duration: 552.718535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:44.165214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:37:43.612048Z","time spent":"553.122723ms","remote":"127.0.0.1:45622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:37:44.165146Z","caller":"traceutil/trace.go:171","msg":"trace[1379219846] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"553.099872ms","start":"2026-08-13T18:37:43.612001Z","end":"2026-08-13T18:37:44.165101Z","steps":["trace[1379219846] 'process raft request' (duration: 274.812899ms)","trace[1379219846] 'compare' (duration: 277.291154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:37:44.165385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.931034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:37:44.165458Z","caller":"traceutil/trace.go:171","msg":"trace[1421537102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5281; }","duration":"547.042126ms","start":"2026-08-13T18:37:43.618397Z","end":"2026-08-13T18:37:44.165439Z","steps":["trace[1421537102] 'agreement among raft nodes before linearized reading' (duration: 546.883193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:44.16552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:37:43.61839Z","time spent":"547.117267ms","remote":"127.0.0.1:45356","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-08-13T18:37:44.165497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.183925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T18:37:44.165609Z","caller":"traceutil/trace.go:171","msg":"trace[15168895] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5281; }","duration":"507.347077ms","start":"2026-08-13T18:37:43.658238Z","end":"2026-08-13T18:37:44.165585Z","steps":["trace[15168895] 'agreement among raft nodes before linearized reading' (duration: 507.088883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:44.16561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.561374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-13T18:37:44.165586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:37:43.611978Z","time spent":"553.46392ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:37:44.165666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:37:43.658225Z","time spent":"507.43129ms","remote":"127.0.0.1:45622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":48,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-08-13T18:37:44.165672Z","caller":"traceutil/trace.go:171","msg":"trace[1274567103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5281; }","duration":"128.625496ms","start":"2026-08-13T18:37:44.037029Z","end":"2026-08-13T18:37:44.165655Z","steps":["trace[1274567103] 'agreement among raft nodes before linearized reading' (duration: 128.392031ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:44.303542Z","caller":"traceutil/trace.go:171","msg":"trace[819321018] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"131.749426ms","start":"2026-08-13T18:37:44.171764Z","end":"2026-08-13T18:37:44.303514Z","steps":["trace[819321018] 'process raft request' (duration: 114.383905ms)","trace[819321018] 'compare' (duration: 16.837491ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:37:44.303147Z","caller":"traceutil/trace.go:171","msg":"trace[1939039209] linearizableReadLoop","detail":"{readStateIndex:5481; appliedIndex:5480; }","duration":"114.058679ms","start":"2026-08-13T18:37:44.189059Z","end":"2026-08-13T18:37:44.303118Z","steps":["trace[1939039209] 'read index received' (duration: 97.077434ms)","trace[1939039209] 'applied index is now lower than readState.Index' (duration: 16.979935ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:37:44.303981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.907434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:37:44.304042Z","caller":"traceutil/trace.go:171","msg":"trace[957897561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5282; }","duration":"115.034587ms","start":"2026-08-13T18:37:44.188983Z","end":"2026-08-13T18:37:44.304018Z","steps":["trace[957897561] 'agreement among raft nodes before linearized reading' (duration: 114.914234ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:38:03.915195Z","caller":"traceutil/trace.go:171","msg":"trace[1029246724] transaction","detail":"{read_only:false; response_revision:5601; number_of_response:1; }","duration":"100.819616ms","start":"2026-08-13T18:38:03.814342Z","end":"2026-08-13T18:38:03.915162Z","steps":["trace[1029246724] 'process raft request' (duration: 64.816028ms)","trace[1029246724] 'compare' (duration: 35.750473ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:38:03.915477Z","caller":"traceutil/trace.go:171","msg":"trace[1068325144] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"100.835366ms","start":"2026-08-13T18:38:03.814614Z","end":"2026-08-13T18:38:03.915449Z","steps":["trace[1068325144] 'process raft request' (duration: 100.469029ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:38:03.915686Z","caller":"traceutil/trace.go:171","msg":"trace[1922578321] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"100.846646ms","start":"2026-08-13T18:38:03.814789Z","end":"2026-08-13T18:38:03.915636Z","steps":["trace[1922578321] 'process raft request' (duration: 100.587921ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:39:04.659578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-08-13T18:39:04.76246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"89.674887ms","hash":1367171092,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":31850496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-13T18:39:04.762648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1367171092,"revision":3009,"compact-revision":-1} {"level":"warn","ts":"2026-08-13T18:43:58.566844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.866858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:43:58.566967Z","caller":"traceutil/trace.go:171","msg":"trace[767565598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8271; }","duration":"378.073132ms","start":"2026-08-13T18:43:58.18887Z","end":"2026-08-13T18:43:58.566943Z","steps":["trace[767565598] 'range keys from in-memory index tree' (duration: 377.704775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:43:58.567022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:43:58.188846Z","time spent":"378.165684ms","remote":"127.0.0.1:45356","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-08-13T18:43:58.567473Z","caller":"traceutil/trace.go:171","msg":"trace[2019548699] transaction","detail":"{read_only:false; response_revision:8272; number_of_response:1; }","duration":"341.54986ms","start":"2026-08-13T18:43:58.225886Z","end":"2026-08-13T18:43:58.567435Z","steps":["trace[2019548699] 'process raft request' (duration: 316.144421ms)","trace[2019548699] 'compare' (duration: 24.718436ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:43:58.567624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:43:58.225849Z","time spent":"341.694703ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:44:04.670286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6106} {"level":"info","ts":"2026-08-13T18:44:04.770973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6106,"took":"98.866921ms","hash":3919131096,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":23781376,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-13T18:44:04.771049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3919131096,"revision":6106,"compact-revision":3009} {"level":"info","ts":"2026-08-13T18:44:10.251711Z","caller":"traceutil/trace.go:171","msg":"trace[1971697752] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"130.340767ms","start":"2026-08-13T18:44:10.121327Z","end":"2026-08-13T18:44:10.251668Z","steps":["trace[1971697752] 'process raft request' (duration: 118.972849ms)","trace[1971697752] 'compare' (duration: 11.127314ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:44:40.150823Z","caller":"traceutil/trace.go:171","msg":"trace[173848240] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"164.25047ms","start":"2026-08-13T18:44:39.986547Z","end":"2026-08-13T18:44:40.150797Z","steps":["trace[173848240] 'process raft request' (duration: 164.081827ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:44:42.908911Z","caller":"traceutil/trace.go:171","msg":"trace[1241294191] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"115.573142ms","start":"2026-08-13T18:44:42.793313Z","end":"2026-08-13T18:44:42.908886Z","steps":["trace[1241294191] 'process raft request' (duration: 115.318527ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:44:43.058974Z","caller":"traceutil/trace.go:171","msg":"trace[1813957212] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"144.305366ms","start":"2026-08-13T18:44:42.914632Z","end":"2026-08-13T18:44:43.058937Z","steps":["trace[1813957212] 'process raft request' (duration: 52.5021ms)","trace[1813957212] 'compare' (duration: 91.338647ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:44:43.059426Z","caller":"traceutil/trace.go:171","msg":"trace[87771539] transaction","detail":"{read_only:false; response_revision:8563; number_of_response:1; }","duration":"143.634583ms","start":"2026-08-13T18:44:42.915757Z","end":"2026-08-13T18:44:43.059392Z","steps":["trace[87771539] 'process raft request' (duration: 143.082822ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:44:53.47275Z","caller":"traceutil/trace.go:171","msg":"trace[1907664899] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"116.186864ms","start":"2026-08-13T18:44:53.356533Z","end":"2026-08-13T18:44:53.47272Z","steps":["trace[1907664899] 'process raft request' (duration: 116.009431ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:44:53.476892Z","caller":"traceutil/trace.go:171","msg":"trace[691907436] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"120.012698ms","start":"2026-08-13T18:44:53.356838Z","end":"2026-08-13T18:44:53.47685Z","steps":["trace[691907436] 'process raft request' (duration: 119.781344ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:45:01.174142Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002351.wal"} {"level":"info","ts":"2026-08-13T18:46:10.887474Z","caller":"traceutil/trace.go:171","msg":"trace[2017379315] transaction","detail":"{read_only:false; response_revision:9418; number_of_response:1; }","duration":"177.839608ms","start":"2026-08-13T18:46:10.709607Z","end":"2026-08-13T18:46:10.887446Z","steps":["trace[2017379315] 'process raft request' (duration: 170.22632ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:46:45.660652Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T18:46:45.667063Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-13T18:46:45.667178Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-13T18:49:04.675571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8304} {"level":"info","ts":"2026-08-13T18:49:04.759305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8304,"took":"81.069213ms","hash":4202877531,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":26488832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-13T18:49:04.759402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4202877531,"revision":8304,"compact-revision":6106} {"level":"info","ts":"2026-08-13T18:54:04.684098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10801} {"level":"info","ts":"2026-08-13T18:54:04.76793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10801,"took":"80.466356ms","hash":3108199407,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":26943488,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-13T18:54:04.767996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3108199407,"revision":10801,"compact-revision":8304} {"level":"info","ts":"2026-08-13T18:55:47.327046Z","caller":"traceutil/trace.go:171","msg":"trace[1883751647] transaction","detail":"{read_only:false; response_revision:13670; number_of_response:1; }","duration":"124.865588ms","start":"2026-08-13T18:55:47.202152Z","end":"2026-08-13T18:55:47.327018Z","steps":["trace[1883751647] 'process raft request' (duration: 124.667184ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:56:23.725797Z","caller":"traceutil/trace.go:171","msg":"trace[1818007867] transaction","detail":"{read_only:false; response_revision:13879; number_of_response:1; }","duration":"175.795984ms","start":"2026-08-13T18:56:23.549953Z","end":"2026-08-13T18:56:23.725749Z","steps":["trace[1818007867] 'process raft request' (duration: 175.59907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:56:23.914726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.025484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T18:56:23.914842Z","caller":"traceutil/trace.go:171","msg":"trace[1765623289] linearizableReadLoop","detail":"{readStateIndex:14357; appliedIndex:14356; }","duration":"295.165705ms","start":"2026-08-13T18:56:23.619658Z","end":"2026-08-13T18:56:23.914823Z","steps":["trace[1765623289] 'read index received' (duration: 106.172537ms)","trace[1765623289] 'applied index is now lower than readState.Index' (duration: 188.991958ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:56:23.91494Z","caller":"traceutil/trace.go:171","msg":"trace[232135192] transaction","detail":"{read_only:false; response_revision:13880; number_of_response:1; }","duration":"362.990109ms","start":"2026-08-13T18:56:23.551906Z","end":"2026-08-13T18:56:23.914896Z","steps":["trace[232135192] 'process raft request' (duration: 217.682889ms)","trace[232135192] 'compare' (duration: 144.613797ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:56:23.915035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.343368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T18:56:23.915066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.816055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:56:23.915112Z","caller":"traceutil/trace.go:171","msg":"trace[420142949] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:13880; }","duration":"295.490422ms","start":"2026-08-13T18:56:23.6196Z","end":"2026-08-13T18:56:23.91509Z","steps":["trace[420142949] 'agreement among raft nodes before linearized reading' (duration: 295.337159ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:56:23.915111Z","caller":"traceutil/trace.go:171","msg":"trace[350628724] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:13880; }","duration":"201.899525ms","start":"2026-08-13T18:56:23.713194Z","end":"2026-08-13T18:56:23.915093Z","steps":["trace[350628724] 'agreement among raft nodes before linearized reading' (duration: 201.801404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:56:23.915136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:56:23.551895Z","time spent":"363.136591ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:56:33.231862Z","caller":"traceutil/trace.go:171","msg":"trace[1979966372] transaction","detail":"{read_only:false; response_revision:14046; number_of_response:1; }","duration":"100.338726ms","start":"2026-08-13T18:56:33.131487Z","end":"2026-08-13T18:56:33.231826Z","steps":["trace[1979966372] 'process raft request' (duration: 99.953599ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:59:04.691439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12870} {"level":"info","ts":"2026-08-13T18:59:04.77366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12870,"took":"78.565669ms","hash":1027636631,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":30470144,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-13T18:59:04.773819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1027636631,"revision":12870,"compact-revision":10801} {"level":"info","ts":"2026-08-13T19:03:49.513186Z","caller":"traceutil/trace.go:171","msg":"trace[615861421] linearizableReadLoop","detail":"{readStateIndex:18296; appliedIndex:18295; }","duration":"113.340877ms","start":"2026-08-13T19:03:49.399817Z","end":"2026-08-13T19:03:49.513158Z","steps":["trace[615861421] 'read index received' (duration: 62.028971ms)","trace[615861421] 'applied index is now lower than readState.Index' (duration: 51.310856ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:03:49.513245Z","caller":"traceutil/trace.go:171","msg":"trace[359781650] transaction","detail":"{read_only:false; response_revision:17696; number_of_response:1; }","duration":"114.475359ms","start":"2026-08-13T19:03:49.398731Z","end":"2026-08-13T19:03:49.513206Z","steps":["trace[359781650] 'process raft request' (duration: 63.148672ms)","trace[359781650] 'compare' (duration: 51.124913ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:03:49.513386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.539361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:03:49.513468Z","caller":"traceutil/trace.go:171","msg":"trace[732820820] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:17696; }","duration":"113.651423ms","start":"2026-08-13T19:03:49.399795Z","end":"2026-08-13T19:03:49.513447Z","steps":["trace[732820820] 'agreement among raft nodes before linearized reading' (duration: 113.455779ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:03:55.481401Z","caller":"traceutil/trace.go:171","msg":"trace[1757409975] transaction","detail":"{read_only:false; response_revision:17795; number_of_response:1; }","duration":"111.193555ms","start":"2026-08-13T19:03:55.370187Z","end":"2026-08-13T19:03:55.48138Z","steps":["trace[1757409975] 'process raft request' (duration: 111.006632ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:04:04.698391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15469} {"level":"info","ts":"2026-08-13T19:04:04.794718Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15469,"took":"93.17802ms","hash":1876846155,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":32546816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-13T19:04:04.794795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1876846155,"revision":15469,"compact-revision":12870} {"level":"info","ts":"2026-08-13T19:07:02.577632Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T19:07:02.584082Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-13T19:07:02.584364Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-13T19:09:04.705403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17929} {"level":"info","ts":"2026-08-13T19:09:04.823568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17929,"took":"114.249652ms","hash":3113368579,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":30699520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T19:09:04.823656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3113368579,"revision":17929,"compact-revision":15469} {"level":"info","ts":"2026-08-13T19:09:33.202355Z","caller":"traceutil/trace.go:171","msg":"trace[1991313135] transaction","detail":"{read_only:false; response_revision:20407; number_of_response:1; }","duration":"125.068431ms","start":"2026-08-13T19:09:33.077263Z","end":"2026-08-13T19:09:33.202332Z","steps":["trace[1991313135] 'process raft request' (duration: 124.897667ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:09:49.363055Z","caller":"traceutil/trace.go:171","msg":"trace[502262750] transaction","detail":"{read_only:false; response_revision:20490; number_of_response:1; }","duration":"100.493389ms","start":"2026-08-13T19:09:49.262522Z","end":"2026-08-13T19:09:49.363015Z","steps":["trace[502262750] 'process raft request' (duration: 100.295515ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:09:49.36886Z","caller":"traceutil/trace.go:171","msg":"trace[738428979] transaction","detail":"{read_only:false; response_revision:20491; number_of_response:1; }","duration":"104.397954ms","start":"2026-08-13T19:09:49.264435Z","end":"2026-08-13T19:09:49.368833Z","steps":["trace[738428979] 'process raft request' (duration: 104.090338ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:09:49.368874Z","caller":"traceutil/trace.go:171","msg":"trace[1266780741] transaction","detail":"{read_only:false; response_revision:20492; number_of_response:1; }","duration":"102.11196ms","start":"2026-08-13T19:09:49.266728Z","end":"2026-08-13T19:09:49.368839Z","steps":["trace[1266780741] 'process raft request' (duration: 101.992717ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:09:54.871969Z","caller":"traceutil/trace.go:171","msg":"trace[1477511254] transaction","detail":"{read_only:false; response_revision:20520; number_of_response:1; }","duration":"146.909309ms","start":"2026-08-13T19:09:54.725025Z","end":"2026-08-13T19:09:54.871934Z","steps":["trace[1477511254] 'process raft request' (duration: 146.699885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:09:55.138209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.264382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-13T19:09:55.13832Z","caller":"traceutil/trace.go:171","msg":"trace[1439071645] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20520; }","duration":"227.456896ms","start":"2026-08-13T19:09:54.910838Z","end":"2026-08-13T19:09:55.138295Z","steps":["trace[1439071645] 'range keys from in-memory index tree' (duration: 226.976866ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:09:55.55501Z","caller":"traceutil/trace.go:171","msg":"trace[776841391] linearizableReadLoop","detail":"{readStateIndex:21216; appliedIndex:21215; }","duration":"111.602462ms","start":"2026-08-13T19:09:55.44336Z","end":"2026-08-13T19:09:55.554962Z","steps":["trace[776841391] 'read index received' (duration: 96.973951ms)","trace[776841391] 'applied index is now lower than readState.Index' (duration: 14.626821ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:09:55.555047Z","caller":"traceutil/trace.go:171","msg":"trace[1906063438] transaction","detail":"{read_only:false; response_revision:20524; number_of_response:1; }","duration":"113.62875ms","start":"2026-08-13T19:09:55.441369Z","end":"2026-08-13T19:09:55.554998Z","steps":["trace[1906063438] 'process raft request' (duration: 98.895328ms)","trace[1906063438] 'compare' (duration: 14.529398ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:09:55.555421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.01499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-13T19:09:55.555471Z","caller":"traceutil/trace.go:171","msg":"trace[870788405] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:20524; }","duration":"112.122522ms","start":"2026-08-13T19:09:55.443332Z","end":"2026-08-13T19:09:55.555455Z","steps":["trace[870788405] 'agreement among raft nodes before linearized reading' (duration: 111.845417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:13:46.699102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.961154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:13:46.699238Z","caller":"traceutil/trace.go:171","msg":"trace[1782031242] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:22951; }","duration":"214.112727ms","start":"2026-08-13T19:13:46.485086Z","end":"2026-08-13T19:13:46.699199Z","steps":["trace[1782031242] 'count revisions from in-memory index tree' (duration: 213.193649ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:14:04.716702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20261} {"level":"info","ts":"2026-08-13T19:14:04.829744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20261,"took":"108.627968ms","hash":3493037057,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":42438656,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-13T19:14:04.829863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3493037057,"revision":20261,"compact-revision":17929} {"level":"warn","ts":"2026-08-13T19:17:20.789264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.954667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-13T19:17:20.789328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.204189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-13T19:17:20.789382Z","caller":"traceutil/trace.go:171","msg":"trace[548143243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25114; }","duration":"291.090249ms","start":"2026-08-13T19:17:20.498269Z","end":"2026-08-13T19:17:20.789359Z","steps":["trace[548143243] 'range keys from in-memory index tree' (duration: 290.616941ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:20.789383Z","caller":"traceutil/trace.go:171","msg":"trace[1043314240] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25114; }","duration":"282.302681ms","start":"2026-08-13T19:17:20.507063Z","end":"2026-08-13T19:17:20.789366Z","steps":["trace[1043314240] 'range keys from in-memory index tree' (duration: 282.010205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:20.789992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.663234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:17:20.790099Z","caller":"traceutil/trace.go:171","msg":"trace[1664064712] linearizableReadLoop","detail":"{readStateIndex:25939; appliedIndex:25938; }","duration":"192.714787ms","start":"2026-08-13T19:17:20.597368Z","end":"2026-08-13T19:17:20.790083Z","steps":["trace[1664064712] 'read index received' (duration: 54.820649ms)","trace[1664064712] 'applied index is now lower than readState.Index' (duration: 137.892808ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:20.790368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.604487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-13T19:17:20.790857Z","caller":"traceutil/trace.go:171","msg":"trace[627694373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25115; }","duration":"192.144057ms","start":"2026-08-13T19:17:20.5987Z","end":"2026-08-13T19:17:20.790844Z","steps":["trace[627694373] 'agreement among raft nodes before linearized reading' (duration: 191.602397ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:20.790691Z","caller":"traceutil/trace.go:171","msg":"trace[1808296462] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"221.835695ms","start":"2026-08-13T19:17:20.568823Z","end":"2026-08-13T19:17:20.790659Z","steps":["trace[1808296462] 'process raft request' (duration: 83.416966ms)","trace[1808296462] 'compare' (duration: 137.102393ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:20.790981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.584144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-13T19:17:20.790767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.832983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-13T19:17:20.791082Z","caller":"traceutil/trace.go:171","msg":"trace[1937171831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25115; }","duration":"188.16748ms","start":"2026-08-13T19:17:20.602905Z","end":"2026-08-13T19:17:20.791072Z","steps":["trace[1937171831] 'agreement among raft nodes before linearized reading' (duration: 187.738112ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:20.791083Z","caller":"traceutil/trace.go:171","msg":"trace[55134902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25115; }","duration":"193.744857ms","start":"2026-08-13T19:17:20.597307Z","end":"2026-08-13T19:17:20.791052Z","steps":["trace[55134902] 'agreement among raft nodes before linearized reading' (duration: 193.36136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:20.791081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.569687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-13T19:17:20.791184Z","caller":"traceutil/trace.go:171","msg":"trace[915033530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25115; }","duration":"188.730631ms","start":"2026-08-13T19:17:20.602423Z","end":"2026-08-13T19:17:20.791154Z","steps":["trace[915033530] 'agreement among raft nodes before linearized reading' (duration: 188.351914ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:21.094742Z","caller":"traceutil/trace.go:171","msg":"trace[105742311] linearizableReadLoop","detail":"{readStateIndex:25940; appliedIndex:25939; }","duration":"299.548451ms","start":"2026-08-13T19:17:20.795136Z","end":"2026-08-13T19:17:21.094684Z","steps":["trace[105742311] 'read index received' (duration: 232.09771ms)","trace[105742311] 'applied index is now lower than readState.Index' (duration: 67.449091ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:21.094811Z","caller":"traceutil/trace.go:171","msg":"trace[603936462] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"299.846956ms","start":"2026-08-13T19:17:20.794909Z","end":"2026-08-13T19:17:21.094756Z","steps":["trace[603936462] 'process raft request' (duration: 232.237033ms)","trace[603936462] 'compare' (duration: 67.343428ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:21.095071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:20.794895Z","time spent":"300.01626ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:21.095099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.919058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18434"} {"level":"info","ts":"2026-08-13T19:17:21.095206Z","caller":"traceutil/trace.go:171","msg":"trace[1092771134] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25116; }","duration":"300.05421ms","start":"2026-08-13T19:17:20.795122Z","end":"2026-08-13T19:17:21.095176Z","steps":["trace[1092771134] 'agreement among raft nodes before linearized reading' (duration: 299.710914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:21.095255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:20.795116Z","time spent":"300.128522ms","remote":"127.0.0.1:45530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18456,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-13T19:17:21.256347Z","caller":"traceutil/trace.go:171","msg":"trace[124858780] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"459.9408ms","start":"2026-08-13T19:17:20.796367Z","end":"2026-08-13T19:17:21.256308Z","steps":["trace[124858780] 'process raft request' (duration: 459.019132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:21.256573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:20.796356Z","time spent":"460.093512ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:21.256702Z","caller":"traceutil/trace.go:171","msg":"trace[1329533480] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"460.276626ms","start":"2026-08-13T19:17:20.796389Z","end":"2026-08-13T19:17:21.256666Z","steps":["trace[1329533480] 'process raft request' (duration: 459.825528ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:21.256868Z","caller":"traceutil/trace.go:171","msg":"trace[1370889566] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"460.050032ms","start":"2026-08-13T19:17:20.796716Z","end":"2026-08-13T19:17:21.256766Z","steps":["trace[1370889566] 'process raft request' (duration: 459.707676ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:21.257015Z","caller":"traceutil/trace.go:171","msg":"trace[658872441] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"307.011553ms","start":"2026-08-13T19:17:20.949988Z","end":"2026-08-13T19:17:21.256999Z","steps":["trace[658872441] 'process raft request' (duration: 306.643187ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:21.257015Z","caller":"traceutil/trace.go:171","msg":"trace[1008913209] linearizableReadLoop","detail":"{readStateIndex:25945; appliedIndex:25940; }","duration":"162.143442ms","start":"2026-08-13T19:17:21.094844Z","end":"2026-08-13T19:17:21.256987Z","steps":["trace[1008913209] 'read index received' (duration: 161.114123ms)","trace[1008913209] 'applied index is now lower than readState.Index' (duration: 1.028089ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:21.257147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:20.949947Z","time spent":"307.111396ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:21.2572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.739874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:21.257251Z","caller":"traceutil/trace.go:171","msg":"trace[2069932578] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25121; }","duration":"334.875447ms","start":"2026-08-13T19:17:20.922359Z","end":"2026-08-13T19:17:21.257235Z","steps":["trace[2069932578] 'agreement among raft nodes before linearized reading' (duration: 334.771915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:21.257295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:20.92233Z","time spent":"334.955308ms","remote":"127.0.0.1:58834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-08-13T19:17:21.257206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:20.796705Z","time spent":"460.307797ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:21.256925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:20.796354Z","time spent":"460.458859ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:21.25909Z","caller":"traceutil/trace.go:171","msg":"trace[1420077413] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"462.481188ms","start":"2026-08-13T19:17:20.796526Z","end":"2026-08-13T19:17:21.259007Z","steps":["trace[1420077413] 'process raft request' (duration: 459.835968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:21.259344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:20.796481Z","time spent":"462.742663ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:21.452152Z","caller":"traceutil/trace.go:171","msg":"trace[1202430356] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"129.182281ms","start":"2026-08-13T19:17:21.322939Z","end":"2026-08-13T19:17:21.452122Z","steps":["trace[1202430356] 'process raft request' (duration: 115.681563ms)","trace[1202430356] 'compare' (duration: 13.321345ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:21.627089Z","caller":"traceutil/trace.go:171","msg":"trace[916139611] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"298.802437ms","start":"2026-08-13T19:17:21.328255Z","end":"2026-08-13T19:17:21.627058Z","steps":["trace[916139611] 'process raft request' (duration: 298.699705ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:21.627143Z","caller":"traceutil/trace.go:171","msg":"trace[738341113] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"300.716074ms","start":"2026-08-13T19:17:21.326389Z","end":"2026-08-13T19:17:21.627105Z","steps":["trace[738341113] 'process raft request' (duration: 283.84581ms)","trace[738341113] 'compare' (duration: 16.539737ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:21.627362Z","caller":"traceutil/trace.go:171","msg":"trace[1464728103] linearizableReadLoop","detail":"{readStateIndex:25949; appliedIndex:25945; }","duration":"283.958313ms","start":"2026-08-13T19:17:21.343379Z","end":"2026-08-13T19:17:21.627337Z","steps":["trace[1464728103] 'read index received' (duration: 95.396435ms)","trace[1464728103] 'applied index is now lower than readState.Index' (duration: 188.560588ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:21.6275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:21.32635Z","time spent":"300.979808ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:21.627549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.154016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T19:17:21.627594Z","caller":"traceutil/trace.go:171","msg":"trace[319251256] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25125; }","duration":"284.277559ms","start":"2026-08-13T19:17:21.343307Z","end":"2026-08-13T19:17:21.627584Z","steps":["trace[319251256] 'agreement among raft nodes before linearized reading' (duration: 284.131276ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:21.627644Z","caller":"traceutil/trace.go:171","msg":"trace[236050054] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"284.35955ms","start":"2026-08-13T19:17:21.343268Z","end":"2026-08-13T19:17:21.627628Z","steps":["trace[236050054] 'process raft request' (duration: 283.733279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:21.918168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.141732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:17:21.918276Z","caller":"traceutil/trace.go:171","msg":"trace[957638098] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"272.469413ms","start":"2026-08-13T19:17:21.645789Z","end":"2026-08-13T19:17:21.918258Z","steps":["trace[957638098] 'process raft request' (duration: 133.128337ms)","trace[957638098] 'compare' (duration: 138.993749ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:22.51409Z","caller":"traceutil/trace.go:171","msg":"trace[413207994] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"133.195728ms","start":"2026-08-13T19:17:22.380863Z","end":"2026-08-13T19:17:22.514059Z","steps":["trace[413207994] 'process raft request' (duration: 133.003395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:22.796852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.792989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:22.796974Z","caller":"traceutil/trace.go:171","msg":"trace[962986434] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25128; }","duration":"169.014963ms","start":"2026-08-13T19:17:22.627936Z","end":"2026-08-13T19:17:22.796951Z","steps":["trace[962986434] 'range keys from in-memory index tree' (duration: 168.665747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:22.798388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.359997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-13T19:17:22.798483Z","caller":"traceutil/trace.go:171","msg":"trace[1771737907] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25128; }","duration":"172.53901ms","start":"2026-08-13T19:17:22.625924Z","end":"2026-08-13T19:17:22.798463Z","steps":["trace[1771737907] 'count revisions from in-memory index tree' (duration: 172.225924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:23.314241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.902008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:23.314384Z","caller":"traceutil/trace.go:171","msg":"trace[1653993813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25130; }","duration":"127.113512ms","start":"2026-08-13T19:17:23.187242Z","end":"2026-08-13T19:17:23.314355Z","steps":["trace[1653993813] 'range keys from in-memory index tree' (duration: 126.783616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:23.472643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.448962ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-13T19:17:23.472743Z","caller":"traceutil/trace.go:171","msg":"trace[905248858] linearizableReadLoop","detail":"{readStateIndex:25956; appliedIndex:25955; }","duration":"156.897192ms","start":"2026-08-13T19:17:23.315826Z","end":"2026-08-13T19:17:23.472723Z","steps":["trace[905248858] 'read index received' (duration: 22.356318ms)","trace[905248858] 'applied index is now lower than readState.Index' (duration: 134.539424ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:23.472859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.016824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:23.472879Z","caller":"traceutil/trace.go:171","msg":"trace[605459478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25131; }","duration":"157.132335ms","start":"2026-08-13T19:17:23.31574Z","end":"2026-08-13T19:17:23.472872Z","steps":["trace[605459478] 'agreement among raft nodes before linearized reading' (duration: 157.057634ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:23.708389Z","caller":"traceutil/trace.go:171","msg":"trace[1766687216] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"387.687907ms","start":"2026-08-13T19:17:23.320667Z","end":"2026-08-13T19:17:23.708354Z","steps":["trace[1766687216] 'process raft request' (duration: 387.585575ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:23.708392Z","caller":"traceutil/trace.go:171","msg":"trace[622007395] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"388.710927ms","start":"2026-08-13T19:17:23.319653Z","end":"2026-08-13T19:17:23.708364Z","steps":["trace[622007395] 'process raft request' (duration: 388.32034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:23.708615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:23.320641Z","time spent":"387.86493ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:23.708699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:23.319626Z","time spent":"388.981882ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:23.708973Z","caller":"traceutil/trace.go:171","msg":"trace[6041702] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"388.055624ms","start":"2026-08-13T19:17:23.320868Z","end":"2026-08-13T19:17:23.708923Z","steps":["trace[6041702] 'process raft request' (duration: 387.439872ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:23.709168Z","caller":"traceutil/trace.go:171","msg":"trace[465622361] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"387.79851ms","start":"2026-08-13T19:17:23.321341Z","end":"2026-08-13T19:17:23.70914Z","steps":["trace[465622361] 'process raft request' (duration: 387.493463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:23.709215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:23.32085Z","time spent":"388.248348ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:23.70936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:23.32133Z","time spent":"387.941323ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:23.906767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.840074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:17:23.907069Z","caller":"traceutil/trace.go:171","msg":"trace[762836400] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"448.271766ms","start":"2026-08-13T19:17:23.458764Z","end":"2026-08-13T19:17:23.907036Z","steps":["trace[762836400] 'process raft request' (duration: 317.021055ms)","trace[762836400] 'compare' (duration: 130.536288ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:23.907221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:23.458737Z","time spent":"448.403149ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:23.907547Z","caller":"traceutil/trace.go:171","msg":"trace[409630598] linearizableReadLoop","detail":"{readStateIndex:25964; appliedIndex:25956; }","duration":"248.33059ms","start":"2026-08-13T19:17:23.659193Z","end":"2026-08-13T19:17:23.907523Z","steps":["trace[409630598] 'read index received' (duration: 48.765513ms)","trace[409630598] 'applied index is now lower than readState.Index' (duration: 199.563197ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:23.907568Z","caller":"traceutil/trace.go:171","msg":"trace[701518875] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"273.94855ms","start":"2026-08-13T19:17:23.633596Z","end":"2026-08-13T19:17:23.907544Z","steps":["trace[701518875] 'process raft request' (duration: 273.498571ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:23.907625Z","caller":"traceutil/trace.go:171","msg":"trace[1828023752] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"274.268047ms","start":"2026-08-13T19:17:23.63329Z","end":"2026-08-13T19:17:23.907558Z","steps":["trace[1828023752] 'process raft request' (duration: 273.682626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:23.907724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.532804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-13T19:17:23.908269Z","caller":"traceutil/trace.go:171","msg":"trace[918846101] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"274.373519ms","start":"2026-08-13T19:17:23.633837Z","end":"2026-08-13T19:17:23.908211Z","steps":["trace[918846101] 'process raft request' (duration: 273.362809ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:23.908346Z","caller":"traceutil/trace.go:171","msg":"trace[643649356] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25139; }","duration":"248.929863ms","start":"2026-08-13T19:17:23.65915Z","end":"2026-08-13T19:17:23.90808Z","steps":["trace[643649356] 'agreement among raft nodes before linearized reading' (duration: 248.465233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:24.163776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.239929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:17:24.163968Z","caller":"traceutil/trace.go:171","msg":"trace[1587393592] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"250.688417ms","start":"2026-08-13T19:17:23.913258Z","end":"2026-08-13T19:17:24.163947Z","steps":["trace[1587393592] 'process raft request' (duration: 140.159782ms)","trace[1587393592] 'compare' (duration: 110.090947ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:24.164232Z","caller":"traceutil/trace.go:171","msg":"trace[1567123980] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"240.595364ms","start":"2026-08-13T19:17:23.923597Z","end":"2026-08-13T19:17:24.164193Z","steps":["trace[1567123980] 'process raft request' (duration: 240.293538ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:24.662477Z","caller":"traceutil/trace.go:171","msg":"trace[668665758] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"139.656412ms","start":"2026-08-13T19:17:24.52279Z","end":"2026-08-13T19:17:24.662447Z","steps":["trace[668665758] 'process raft request' (duration: 139.168672ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:25.418423Z","caller":"traceutil/trace.go:171","msg":"trace[795696569] linearizableReadLoop","detail":"{readStateIndex:25970; appliedIndex:25969; }","duration":"230.704695ms","start":"2026-08-13T19:17:25.187686Z","end":"2026-08-13T19:17:25.41839Z","steps":["trace[795696569] 'read index received' (duration: 230.535772ms)","trace[795696569] 'applied index is now lower than readState.Index' (duration: 167.383µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:25.418566Z","caller":"traceutil/trace.go:171","msg":"trace[394541204] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"253.556852ms","start":"2026-08-13T19:17:25.164989Z","end":"2026-08-13T19:17:25.418546Z","steps":["trace[394541204] 'process raft request' (duration: 253.207456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:25.418768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.066022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:25.418864Z","caller":"traceutil/trace.go:171","msg":"trace[1726378436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25145; }","duration":"231.242795ms","start":"2026-08-13T19:17:25.1876Z","end":"2026-08-13T19:17:25.418843Z","steps":["trace[1726378436] 'agreement among raft nodes before linearized reading' (duration: 231.085492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:25.429703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.06032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T19:17:25.429757Z","caller":"traceutil/trace.go:171","msg":"trace[130684158] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25146; }","duration":"232.139541ms","start":"2026-08-13T19:17:25.197604Z","end":"2026-08-13T19:17:25.429743Z","steps":["trace[130684158] 'agreement among raft nodes before linearized reading' (duration: 231.973648ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:25.43003Z","caller":"traceutil/trace.go:171","msg":"trace[1419163924] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"108.871893ms","start":"2026-08-13T19:17:25.321146Z","end":"2026-08-13T19:17:25.430018Z","steps":["trace[1419163924] 'process raft request' (duration: 108.354964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:25.430333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.37627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-08-13T19:17:25.430373Z","caller":"traceutil/trace.go:171","msg":"trace[303116949] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25146; }","duration":"227.436482ms","start":"2026-08-13T19:17:25.202922Z","end":"2026-08-13T19:17:25.430358Z","steps":["trace[303116949] 'agreement among raft nodes before linearized reading' (duration: 227.293279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:26.139677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.905053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:17:26.139971Z","caller":"traceutil/trace.go:171","msg":"trace[1506990194] linearizableReadLoop","detail":"{readStateIndex:25976; appliedIndex:25973; }","duration":"271.563726ms","start":"2026-08-13T19:17:25.868385Z","end":"2026-08-13T19:17:26.139949Z","steps":["trace[1506990194] 'read index received' (duration: 116.52132ms)","trace[1506990194] 'applied index is now lower than readState.Index' (duration: 155.040666ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:26.140098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.714498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:26.140133Z","caller":"traceutil/trace.go:171","msg":"trace[950271517] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25151; }","duration":"271.80012ms","start":"2026-08-13T19:17:25.86832Z","end":"2026-08-13T19:17:26.14012Z","steps":["trace[950271517] 'agreement among raft nodes before linearized reading' (duration: 271.694438ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:26.139984Z","caller":"traceutil/trace.go:171","msg":"trace[1785203723] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"422.173386ms","start":"2026-08-13T19:17:25.717711Z","end":"2026-08-13T19:17:26.139884Z","steps":["trace[1785203723] 'process raft request' (duration: 266.955527ms)","trace[1785203723] 'compare' (duration: 154.641619ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:26.140402Z","caller":"traceutil/trace.go:171","msg":"trace[966450072] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"422.247878ms","start":"2026-08-13T19:17:25.718085Z","end":"2026-08-13T19:17:26.140332Z","steps":["trace[966450072] 'process raft request' (duration: 421.714098ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:26.140298Z","caller":"traceutil/trace.go:171","msg":"trace[1630437636] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"420.920103ms","start":"2026-08-13T19:17:25.719321Z","end":"2026-08-13T19:17:26.140241Z","steps":["trace[1630437636] 'process raft request' (duration: 420.586567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:26.140732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:25.717697Z","time spent":"422.743748ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:26.140602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:25.718071Z","time spent":"422.435751ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:26.141071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:25.719314Z","time spent":"421.545836ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:26.196833Z","caller":"traceutil/trace.go:171","msg":"trace[1957406017] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"282.668018ms","start":"2026-08-13T19:17:25.914139Z","end":"2026-08-13T19:17:26.196807Z","steps":["trace[1957406017] 'process raft request' (duration: 282.451833ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:26.197297Z","caller":"traceutil/trace.go:171","msg":"trace[1845788821] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"282.391733ms","start":"2026-08-13T19:17:25.914891Z","end":"2026-08-13T19:17:26.197283Z","steps":["trace[1845788821] 'process raft request' (duration: 281.872353ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:26.197333Z","caller":"traceutil/trace.go:171","msg":"trace[678827407] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"281.915134ms","start":"2026-08-13T19:17:25.915388Z","end":"2026-08-13T19:17:26.197303Z","steps":["trace[678827407] 'process raft request' (duration: 281.859873ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:26.197346Z","caller":"traceutil/trace.go:171","msg":"trace[1174790464] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"282.20394ms","start":"2026-08-13T19:17:25.915112Z","end":"2026-08-13T19:17:26.197316Z","steps":["trace[1174790464] 'process raft request' (duration: 282.015406ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:26.445207Z","caller":"traceutil/trace.go:171","msg":"trace[1049412645] linearizableReadLoop","detail":"{readStateIndex:25981; appliedIndex:25976; }","duration":"256.720242ms","start":"2026-08-13T19:17:26.188456Z","end":"2026-08-13T19:17:26.445176Z","steps":["trace[1049412645] 'read index received' (duration: 8.179546ms)","trace[1049412645] 'applied index is now lower than readState.Index' (duration: 248.538735ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:26.445427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.943636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:26.445369Z","caller":"traceutil/trace.go:171","msg":"trace[705764056] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"274.518002ms","start":"2026-08-13T19:17:26.170814Z","end":"2026-08-13T19:17:26.445332Z","steps":["trace[705764056] 'process raft request' (duration: 274.161256ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:26.445473Z","caller":"traceutil/trace.go:171","msg":"trace[1805027780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25156; }","duration":"257.063288ms","start":"2026-08-13T19:17:26.188399Z","end":"2026-08-13T19:17:26.445462Z","steps":["trace[1805027780] 'agreement among raft nodes before linearized reading' (duration: 256.927915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:26.445828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.237656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-13T19:17:26.44591Z","caller":"traceutil/trace.go:171","msg":"trace[56826825] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25156; }","duration":"160.343438ms","start":"2026-08-13T19:17:26.285548Z","end":"2026-08-13T19:17:26.445892Z","steps":["trace[56826825] 'agreement among raft nodes before linearized reading' (duration: 159.828508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:26.724916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.996483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-13T19:17:26.72502Z","caller":"traceutil/trace.go:171","msg":"trace[1876856935] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25156; }","duration":"204.178326ms","start":"2026-08-13T19:17:26.520817Z","end":"2026-08-13T19:17:26.724995Z","steps":["trace[1876856935] 'range keys from in-memory index tree' (duration: 203.783869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:26.725249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.83233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-08-13T19:17:26.72536Z","caller":"traceutil/trace.go:171","msg":"trace[992488468] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25156; }","duration":"111.004404ms","start":"2026-08-13T19:17:26.614329Z","end":"2026-08-13T19:17:26.725333Z","steps":["trace[992488468] 'range keys from in-memory index tree' (duration: 110.599536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:27.198439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.503593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:17:27.198838Z","caller":"traceutil/trace.go:171","msg":"trace[1300881997] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"331.709456ms","start":"2026-08-13T19:17:26.8671Z","end":"2026-08-13T19:17:27.198809Z","steps":["trace[1300881997] 'process raft request' (duration: 207.711694ms)","trace[1300881997] 'compare' (duration: 122.521585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:27.198921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:26.867075Z","time spent":"331.810598ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:27.658072Z","caller":"traceutil/trace.go:171","msg":"trace[956428820] linearizableReadLoop","detail":"{readStateIndex:25984; appliedIndex:25983; }","duration":"135.93399ms","start":"2026-08-13T19:17:27.522114Z","end":"2026-08-13T19:17:27.658048Z","steps":["trace[956428820] 'read index received' (duration: 135.837218ms)","trace[956428820] 'applied index is now lower than readState.Index' (duration: 95.682µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:27.658138Z","caller":"traceutil/trace.go:171","msg":"trace[1271868600] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"222.259622ms","start":"2026-08-13T19:17:27.435861Z","end":"2026-08-13T19:17:27.658121Z","steps":["trace[1271868600] 'process raft request' (duration: 222.017558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:27.658215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.070422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:27.658263Z","caller":"traceutil/trace.go:171","msg":"trace[1033105955] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25159; }","duration":"136.149935ms","start":"2026-08-13T19:17:27.522096Z","end":"2026-08-13T19:17:27.658246Z","steps":["trace[1033105955] 'agreement among raft nodes before linearized reading' (duration: 136.054703ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:27.777463Z","caller":"traceutil/trace.go:171","msg":"trace[509897832] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"247.141819ms","start":"2026-08-13T19:17:27.5301Z","end":"2026-08-13T19:17:27.777242Z","steps":["trace[509897832] 'process raft request' (duration: 243.070421ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:30.129626Z","caller":"traceutil/trace.go:171","msg":"trace[1266758240] linearizableReadLoop","detail":"{readStateIndex:26005; appliedIndex:26004; }","duration":"244.307704ms","start":"2026-08-13T19:17:29.885286Z","end":"2026-08-13T19:17:30.129593Z","steps":["trace[1266758240] 'read index received' (duration: 244.07927ms)","trace[1266758240] 'applied index is now lower than readState.Index' (duration: 226.744µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:30.12973Z","caller":"traceutil/trace.go:171","msg":"trace[1065481344] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"260.77043ms","start":"2026-08-13T19:17:29.868916Z","end":"2026-08-13T19:17:30.129686Z","steps":["trace[1065481344] 'process raft request' (duration: 260.447224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.130017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.59058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-13T19:17:30.13007Z","caller":"traceutil/trace.go:171","msg":"trace[2146455832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25179; }","duration":"244.831565ms","start":"2026-08-13T19:17:29.885226Z","end":"2026-08-13T19:17:30.130058Z","steps":["trace[2146455832] 'agreement among raft nodes before linearized reading' (duration: 244.484327ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:30.445809Z","caller":"traceutil/trace.go:171","msg":"trace[163426445] linearizableReadLoop","detail":"{readStateIndex:26006; appliedIndex:26005; }","duration":"291.985385ms","start":"2026-08-13T19:17:30.153754Z","end":"2026-08-13T19:17:30.445739Z","steps":["trace[163426445] 'read index received' (duration: 239.292607ms)","trace[163426445] 'applied index is now lower than readState.Index' (duration: 52.690808ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:30.446233Z","caller":"traceutil/trace.go:171","msg":"trace[962517448] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"311.119462ms","start":"2026-08-13T19:17:30.135085Z","end":"2026-08-13T19:17:30.446204Z","steps":["trace[962517448] 'process raft request' (duration: 257.863373ms)","trace[962517448] 'compare' (duration: 52.541726ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:30.44661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.135058Z","time spent":"311.240095ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:30.446688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.899754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-13T19:17:30.446788Z","caller":"traceutil/trace.go:171","msg":"trace[1371077983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25180; }","duration":"293.085038ms","start":"2026-08-13T19:17:30.153681Z","end":"2026-08-13T19:17:30.446766Z","steps":["trace[1371077983] 'agreement among raft nodes before linearized reading' (duration: 292.559117ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:30.654561Z","caller":"traceutil/trace.go:171","msg":"trace[575972441] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"446.683277ms","start":"2026-08-13T19:17:30.207856Z","end":"2026-08-13T19:17:30.65454Z","steps":["trace[575972441] 'process raft request' (duration: 445.469943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.654717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.207834Z","time spent":"446.793689ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:30.654765Z","caller":"traceutil/trace.go:171","msg":"trace[887227295] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"443.268481ms","start":"2026-08-13T19:17:30.211465Z","end":"2026-08-13T19:17:30.654733Z","steps":["trace[887227295] 'process raft request' (duration: 442.988355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.654957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.211425Z","time spent":"443.425604ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:30.655018Z","caller":"traceutil/trace.go:171","msg":"trace[953620196] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"443.265591ms","start":"2026-08-13T19:17:30.211728Z","end":"2026-08-13T19:17:30.654993Z","steps":["trace[953620196] 'process raft request' (duration: 442.981585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.655146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.211696Z","time spent":"443.386283ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:30.656043Z","caller":"traceutil/trace.go:171","msg":"trace[954825367] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"443.434823ms","start":"2026-08-13T19:17:30.21256Z","end":"2026-08-13T19:17:30.655995Z","steps":["trace[954825367] 'process raft request' (duration: 442.334753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.656176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.212533Z","time spent":"443.576356ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:30.656271Z","caller":"traceutil/trace.go:171","msg":"trace[740168305] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"197.666661ms","start":"2026-08-13T19:17:30.45857Z","end":"2026-08-13T19:17:30.656236Z","steps":["trace[740168305] 'process raft request' (duration: 197.59535ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:30.656466Z","caller":"traceutil/trace.go:171","msg":"trace[1640187631] linearizableReadLoop","detail":"{readStateIndex:26010; appliedIndex:26006; }","duration":"210.454946ms","start":"2026-08-13T19:17:30.445909Z","end":"2026-08-13T19:17:30.656364Z","steps":["trace[1640187631] 'read index received' (duration: 207.332857ms)","trace[1640187631] 'applied index is now lower than readState.Index' (duration: 3.118729ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:30.656623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.470257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:30.656705Z","caller":"traceutil/trace.go:171","msg":"trace[164785940] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25186; }","duration":"465.557868ms","start":"2026-08-13T19:17:30.191129Z","end":"2026-08-13T19:17:30.656686Z","steps":["trace[164785940] 'agreement among raft nodes before linearized reading' (duration: 465.394095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.656769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.191092Z","time spent":"465.65836ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-08-13T19:17:30.656952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.431476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T19:17:30.65701Z","caller":"traceutil/trace.go:171","msg":"trace[329701275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25186; }","duration":"499.570819ms","start":"2026-08-13T19:17:30.157427Z","end":"2026-08-13T19:17:30.656997Z","steps":["trace[329701275] 'agreement among raft nodes before linearized reading' (duration: 499.370245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.657052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.157397Z","time spent":"499.64568ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-13T19:17:30.656638Z","caller":"traceutil/trace.go:171","msg":"trace[375313714] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"204.38222ms","start":"2026-08-13T19:17:30.452241Z","end":"2026-08-13T19:17:30.656623Z","steps":["trace[375313714] 'process raft request' (duration: 203.772388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.657154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.973974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:30.65719Z","caller":"traceutil/trace.go:171","msg":"trace[868121977] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25186; }","duration":"365.047505ms","start":"2026-08-13T19:17:30.292135Z","end":"2026-08-13T19:17:30.657182Z","steps":["trace[868121977] 'agreement among raft nodes before linearized reading' (duration: 364.976333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.657216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.29212Z","time spent":"365.091526ms","remote":"127.0.0.1:59062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-08-13T19:17:30.657635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.447206ms","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-08-13T19:17:30.658546Z","caller":"traceutil/trace.go:171","msg":"trace[1454572010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25186; }","duration":"501.267291ms","start":"2026-08-13T19:17:30.157261Z","end":"2026-08-13T19:17:30.658529Z","steps":["trace[1454572010] 'agreement among raft nodes before linearized reading' (duration: 499.271713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:30.658684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:30.15724Z","time spent":"501.431164ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T19:17:46.053578Z","caller":"traceutil/trace.go:171","msg":"trace[1409559650] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"169.520064ms","start":"2026-08-13T19:17:45.884027Z","end":"2026-08-13T19:17:46.053547Z","steps":["trace[1409559650] 'process raft request' (duration: 169.221358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:46.31117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.844651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:46.311304Z","caller":"traceutil/trace.go:171","msg":"trace[915572813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25294; }","duration":"123.071685ms","start":"2026-08-13T19:17:46.1882Z","end":"2026-08-13T19:17:46.311272Z","steps":["trace[915572813] 'range keys from in-memory index tree' (duration: 122.736348ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:46.632227Z","caller":"traceutil/trace.go:171","msg":"trace[1808502667] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"103.111982ms","start":"2026-08-13T19:17:46.529084Z","end":"2026-08-13T19:17:46.632196Z","steps":["trace[1808502667] 'process raft request' (duration: 102.905199ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:46.8626Z","caller":"traceutil/trace.go:171","msg":"trace[679899890] linearizableReadLoop","detail":"{readStateIndex:26129; appliedIndex:26125; }","duration":"126.269837ms","start":"2026-08-13T19:17:46.736304Z","end":"2026-08-13T19:17:46.862574Z","steps":["trace[679899890] 'read index received' (duration: 105.087341ms)","trace[679899890] 'applied index is now lower than readState.Index' (duration: 21.181146ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:46.862621Z","caller":"traceutil/trace.go:171","msg":"trace[1555556918] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"141.458857ms","start":"2026-08-13T19:17:46.721119Z","end":"2026-08-13T19:17:46.862578Z","steps":["trace[1555556918] 'process raft request' (duration: 120.255281ms)","trace[1555556918] 'compare' (duration: 20.892019ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:46.862631Z","caller":"traceutil/trace.go:171","msg":"trace[1102002164] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"136.654314ms","start":"2026-08-13T19:17:46.725939Z","end":"2026-08-13T19:17:46.862593Z","steps":["trace[1102002164] 'process raft request' (duration: 136.548572ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:46.862739Z","caller":"traceutil/trace.go:171","msg":"trace[2048107960] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"135.399861ms","start":"2026-08-13T19:17:46.727327Z","end":"2026-08-13T19:17:46.862727Z","steps":["trace[2048107960] 'process raft request' (duration: 135.203507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:46.863015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.686475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-13T19:17:46.863059Z","caller":"traceutil/trace.go:171","msg":"trace[445008532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25300; }","duration":"126.788747ms","start":"2026-08-13T19:17:46.736256Z","end":"2026-08-13T19:17:46.863045Z","steps":["trace[445008532] 'agreement among raft nodes before linearized reading' (duration: 126.639524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:46.863143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.240875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-13T19:17:46.863269Z","caller":"traceutil/trace.go:171","msg":"trace[290155363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25300; }","duration":"126.395158ms","start":"2026-08-13T19:17:46.736852Z","end":"2026-08-13T19:17:46.863247Z","steps":["trace[290155363] 'agreement among raft nodes before linearized reading' (duration: 126.087613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:46.863326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.178064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-13T19:17:46.863401Z","caller":"traceutil/trace.go:171","msg":"trace[1340095364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25300; }","duration":"126.325217ms","start":"2026-08-13T19:17:46.737059Z","end":"2026-08-13T19:17:46.863384Z","steps":["trace[1340095364] 'agreement among raft nodes before linearized reading' (duration: 126.085963ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:46.86401Z","caller":"traceutil/trace.go:171","msg":"trace[1583402608] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"138.019821ms","start":"2026-08-13T19:17:46.724842Z","end":"2026-08-13T19:17:46.862861Z","steps":["trace[1583402608] 'process raft request' (duration: 137.592233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:47.097901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.946915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:17:47.098296Z","caller":"traceutil/trace.go:171","msg":"trace[1337149204] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"229.41639ms","start":"2026-08-13T19:17:46.86883Z","end":"2026-08-13T19:17:47.098246Z","steps":["trace[1337149204] 'process raft request' (duration: 119.963756ms)","trace[1337149204] 'compare' (duration: 108.727299ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:47.181484Z","caller":"traceutil/trace.go:171","msg":"trace[1032893816] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"312.162302ms","start":"2026-08-13T19:17:46.869292Z","end":"2026-08-13T19:17:47.181454Z","steps":["trace[1032893816] 'process raft request' (duration: 312.08063ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:47.181556Z","caller":"traceutil/trace.go:171","msg":"trace[1554951749] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"312.282644ms","start":"2026-08-13T19:17:46.869224Z","end":"2026-08-13T19:17:47.181506Z","steps":["trace[1554951749] 'process raft request' (duration: 311.987038ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:47.181669Z","caller":"traceutil/trace.go:171","msg":"trace[1542727022] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"311.259865ms","start":"2026-08-13T19:17:46.870367Z","end":"2026-08-13T19:17:47.181627Z","steps":["trace[1542727022] 'process raft request' (duration: 311.048121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:47.181757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:46.869206Z","time spent":"312.443548ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:47.181811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:46.869287Z","time spent":"312.289164ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:47.181881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:46.870359Z","time spent":"311.390378ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:47.399788Z","caller":"traceutil/trace.go:171","msg":"trace[1569788238] linearizableReadLoop","detail":"{readStateIndex:26134; appliedIndex:26133; }","duration":"126.400868ms","start":"2026-08-13T19:17:47.273328Z","end":"2026-08-13T19:17:47.399729Z","steps":["trace[1569788238] 'read index received' (duration: 126.129943ms)","trace[1569788238] 'applied index is now lower than readState.Index' (duration: 269.475µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:47.400188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.831466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-13T19:17:47.400245Z","caller":"traceutil/trace.go:171","msg":"trace[2061155437] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25305; }","duration":"126.959759ms","start":"2026-08-13T19:17:47.273265Z","end":"2026-08-13T19:17:47.400225Z","steps":["trace[2061155437] 'agreement among raft nodes before linearized reading' (duration: 126.611162ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:47.400746Z","caller":"traceutil/trace.go:171","msg":"trace[1821871181] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"130.492277ms","start":"2026-08-13T19:17:47.270239Z","end":"2026-08-13T19:17:47.400731Z","steps":["trace[1821871181] 'process raft request' (duration: 129.301244ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:47.871386Z","caller":"traceutil/trace.go:171","msg":"trace[385375875] linearizableReadLoop","detail":"{readStateIndex:26135; appliedIndex:26134; }","duration":"126.095372ms","start":"2026-08-13T19:17:47.745254Z","end":"2026-08-13T19:17:47.87135Z","steps":["trace[385375875] 'read index received' (duration: 125.810437ms)","trace[385375875] 'applied index is now lower than readState.Index' (duration: 283.095µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:47.871479Z","caller":"traceutil/trace.go:171","msg":"trace[653555660] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"132.268512ms","start":"2026-08-13T19:17:47.739154Z","end":"2026-08-13T19:17:47.871423Z","steps":["trace[653555660] 'process raft request' (duration: 131.956496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:47.871592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.302197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-08-13T19:17:47.871641Z","caller":"traceutil/trace.go:171","msg":"trace[1408375556] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:25306; }","duration":"126.423238ms","start":"2026-08-13T19:17:47.745202Z","end":"2026-08-13T19:17:47.871625Z","steps":["trace[1408375556] 'agreement among raft nodes before linearized reading' (duration: 126.286156ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:48.415995Z","caller":"traceutil/trace.go:171","msg":"trace[1602311586] linearizableReadLoop","detail":"{readStateIndex:26138; appliedIndex:26137; }","duration":"226.83052ms","start":"2026-08-13T19:17:48.189137Z","end":"2026-08-13T19:17:48.415968Z","steps":["trace[1602311586] 'read index received' (duration: 214.865581ms)","trace[1602311586] 'applied index is now lower than readState.Index' (duration: 11.963499ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:48.416021Z","caller":"traceutil/trace.go:171","msg":"trace[215979225] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"281.283921ms","start":"2026-08-13T19:17:48.134698Z","end":"2026-08-13T19:17:48.415982Z","steps":["trace[215979225] 'process raft request' (duration: 269.349253ms)","trace[215979225] 'compare' (duration: 11.463489ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:48.416246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.080755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:48.416699Z","caller":"traceutil/trace.go:171","msg":"trace[168709191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25308; }","duration":"227.568034ms","start":"2026-08-13T19:17:48.189108Z","end":"2026-08-13T19:17:48.416676Z","steps":["trace[168709191] 'agreement among raft nodes before linearized reading' (duration: 226.954343ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:48.418208Z","caller":"traceutil/trace.go:171","msg":"trace[1500716591] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"120.709319ms","start":"2026-08-13T19:17:48.29747Z","end":"2026-08-13T19:17:48.418179Z","steps":["trace[1500716591] 'process raft request' (duration: 120.649128ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:48.418209Z","caller":"traceutil/trace.go:171","msg":"trace[681867633] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"157.928962ms","start":"2026-08-13T19:17:48.260245Z","end":"2026-08-13T19:17:48.418174Z","steps":["trace[681867633] 'process raft request' (duration: 157.738149ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:51.117466Z","caller":"traceutil/trace.go:171","msg":"trace[140779385] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"237.113115ms","start":"2026-08-13T19:17:50.880318Z","end":"2026-08-13T19:17:51.117431Z","steps":["trace[140779385] 'process raft request' (duration: 237.038484ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:51.117733Z","caller":"traceutil/trace.go:171","msg":"trace[1274861005] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"238.83315ms","start":"2026-08-13T19:17:50.878858Z","end":"2026-08-13T19:17:51.117691Z","steps":["trace[1274861005] 'process raft request' (duration: 193.37136ms)","trace[1274861005] 'compare' (duration: 44.709205ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:51.117923Z","caller":"traceutil/trace.go:171","msg":"trace[19190404] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"238.539904ms","start":"2026-08-13T19:17:50.879367Z","end":"2026-08-13T19:17:51.117907Z","steps":["trace[19190404] 'process raft request' (duration: 237.880291ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:51.324403Z","caller":"traceutil/trace.go:171","msg":"trace[1536502894] transaction","detail":"{read_only:false; response_revision:25334; number_of_response:1; }","duration":"120.602328ms","start":"2026-08-13T19:17:51.203769Z","end":"2026-08-13T19:17:51.324372Z","steps":["trace[1536502894] 'process raft request' (duration: 120.545597ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:51.324699Z","caller":"traceutil/trace.go:171","msg":"trace[1371729839] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"120.937244ms","start":"2026-08-13T19:17:51.203749Z","end":"2026-08-13T19:17:51.324686Z","steps":["trace[1371729839] 'process raft request' (duration: 120.440764ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:51.324632Z","caller":"traceutil/trace.go:171","msg":"trace[738446795] transaction","detail":"{read_only:false; response_revision:25332; number_of_response:1; }","duration":"121.536495ms","start":"2026-08-13T19:17:51.203042Z","end":"2026-08-13T19:17:51.324578Z","steps":["trace[738446795] 'process raft request' (duration: 119.547547ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:59.437637Z","caller":"traceutil/trace.go:171","msg":"trace[428552250] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"285.822829ms","start":"2026-08-13T19:17:59.151786Z","end":"2026-08-13T19:17:59.437609Z","steps":["trace[428552250] 'process raft request' (duration: 285.580564ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:59.437933Z","caller":"traceutil/trace.go:171","msg":"trace[678960817] linearizableReadLoop","detail":"{readStateIndex:26286; appliedIndex:26282; }","duration":"251.664005ms","start":"2026-08-13T19:17:59.186244Z","end":"2026-08-13T19:17:59.437908Z","steps":["trace[678960817] 'read index received' (duration: 18.364981ms)","trace[678960817] 'applied index is now lower than readState.Index' (duration: 233.297694ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:59.437983Z","caller":"traceutil/trace.go:171","msg":"trace[313583485] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"283.122596ms","start":"2026-08-13T19:17:59.154796Z","end":"2026-08-13T19:17:59.437918Z","steps":["trace[313583485] 'process raft request' (duration: 282.758289ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:59.438034Z","caller":"traceutil/trace.go:171","msg":"trace[1926766627] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"264.068243ms","start":"2026-08-13T19:17:59.173916Z","end":"2026-08-13T19:17:59.437984Z","steps":["trace[1926766627] 'process raft request' (duration: 263.867489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:59.438201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.93009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:17:59.438244Z","caller":"traceutil/trace.go:171","msg":"trace[347646160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25453; }","duration":"252.034522ms","start":"2026-08-13T19:17:59.186195Z","end":"2026-08-13T19:17:59.43823Z","steps":["trace[347646160] 'agreement among raft nodes before linearized reading' (duration: 251.860409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:59.438273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.2711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T19:17:59.438307Z","caller":"traceutil/trace.go:171","msg":"trace[787857560] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25453; }","duration":"146.380672ms","start":"2026-08-13T19:17:59.291915Z","end":"2026-08-13T19:17:59.438296Z","steps":["trace[787857560] 'agreement among raft nodes before linearized reading' (duration: 146.28613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:59.438354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.502804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-13T19:17:59.438449Z","caller":"traceutil/trace.go:171","msg":"trace[1923955796] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25453; }","duration":"214.675267ms","start":"2026-08-13T19:17:59.223755Z","end":"2026-08-13T19:17:59.43843Z","steps":["trace[1923955796] 'agreement among raft nodes before linearized reading' (duration: 214.410112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:59.735755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.848785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:17:59.735898Z","caller":"traceutil/trace.go:171","msg":"trace[1848788406] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"290.269524ms","start":"2026-08-13T19:17:59.445605Z","end":"2026-08-13T19:17:59.735874Z","steps":["trace[1848788406] 'process raft request' (duration: 91.166415ms)","trace[1848788406] 'compare' (duration: 198.366705ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:59.760588Z","caller":"traceutil/trace.go:171","msg":"trace[630281765] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"313.530518ms","start":"2026-08-13T19:17:59.447029Z","end":"2026-08-13T19:17:59.760559Z","steps":["trace[630281765] 'process raft request' (duration: 313.319934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:59.760813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:59.446983Z","time spent":"313.708752ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:59.760911Z","caller":"traceutil/trace.go:171","msg":"trace[1283708124] linearizableReadLoop","detail":"{readStateIndex:26291; appliedIndex:26286; }","duration":"242.264856ms","start":"2026-08-13T19:17:59.518629Z","end":"2026-08-13T19:17:59.760894Z","steps":["trace[1283708124] 'read index received' (duration: 18.164048ms)","trace[1283708124] 'applied index is now lower than readState.Index' (duration: 224.099328ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:59.760912Z","caller":"traceutil/trace.go:171","msg":"trace[397036164] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"313.738892ms","start":"2026-08-13T19:17:59.447135Z","end":"2026-08-13T19:17:59.760874Z","steps":["trace[397036164] 'process raft request' (duration: 313.376786ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:59.760971Z","caller":"traceutil/trace.go:171","msg":"trace[2107113721] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"313.314365ms","start":"2026-08-13T19:17:59.447607Z","end":"2026-08-13T19:17:59.760921Z","steps":["trace[2107113721] 'process raft request' (duration: 313.132972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:59.761078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:59.447129Z","time spent":"313.859245ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:59.761062Z","caller":"traceutil/trace.go:171","msg":"trace[284654385] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"280.867313ms","start":"2026-08-13T19:17:59.480159Z","end":"2026-08-13T19:17:59.761027Z","steps":["trace[284654385] 'process raft request' (duration: 280.68539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:59.761153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:59.447601Z","time spent":"313.458888ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:59.761175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.544141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-13T19:17:59.76123Z","caller":"traceutil/trace.go:171","msg":"trace[1281616990] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25458; }","duration":"242.672024ms","start":"2026-08-13T19:17:59.518539Z","end":"2026-08-13T19:17:59.761211Z","steps":["trace[1281616990] 'agreement among raft nodes before linearized reading' (duration: 242.49393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:59.761337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.861147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T19:17:59.761401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.92309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21d414c9-c6eb-4eb4-8c9b-00bdd52c0b80\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-13T19:17:59.76145Z","caller":"traceutil/trace.go:171","msg":"trace[1661271560] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25458; }","duration":"161.026741ms","start":"2026-08-13T19:17:59.600398Z","end":"2026-08-13T19:17:59.761425Z","steps":["trace[1661271560] 'agreement among raft nodes before linearized reading' (duration: 160.861188ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:59.761455Z","caller":"traceutil/trace.go:171","msg":"trace[523876272] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-21d414c9-c6eb-4eb4-8c9b-00bdd52c0b80; range_end:; response_count:1; response_revision:25458; }","duration":"170.976611ms","start":"2026-08-13T19:17:59.590462Z","end":"2026-08-13T19:17:59.761438Z","steps":["trace[523876272] 'agreement among raft nodes before linearized reading' (duration: 170.788667ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:00.77836Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066bd.wal"} {"level":"info","ts":"2026-08-13T19:18:01.873494Z","caller":"traceutil/trace.go:171","msg":"trace[1970946686] linearizableReadLoop","detail":"{readStateIndex:26306; appliedIndex:26305; }","duration":"107.889164ms","start":"2026-08-13T19:18:01.765581Z","end":"2026-08-13T19:18:01.87347Z","steps":["trace[1970946686] 'read index received' (duration: 107.67257ms)","trace[1970946686] 'applied index is now lower than readState.Index' (duration: 215.234µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:18:01.87359Z","caller":"traceutil/trace.go:171","msg":"trace[876586539] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"126.590222ms","start":"2026-08-13T19:18:01.746964Z","end":"2026-08-13T19:18:01.873554Z","steps":["trace[876586539] 'process raft request' (duration: 126.323807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:01.873854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.247301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-13T19:18:01.873894Z","caller":"traceutil/trace.go:171","msg":"trace[2053969745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25473; }","duration":"108.338303ms","start":"2026-08-13T19:18:01.765546Z","end":"2026-08-13T19:18:01.873884Z","steps":["trace[2053969745] 'agreement among raft nodes before linearized reading' (duration: 108.056698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:02.127708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.049115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:18:02.127831Z","caller":"traceutil/trace.go:171","msg":"trace[1168853613] linearizableReadLoop","detail":"{readStateIndex:26307; appliedIndex:26306; }","duration":"254.236865ms","start":"2026-08-13T19:18:01.873572Z","end":"2026-08-13T19:18:02.127808Z","steps":["trace[1168853613] 'read index received' (duration: 118.977907ms)","trace[1168853613] 'applied index is now lower than readState.Index' (duration: 135.257548ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:18:02.127911Z","caller":"traceutil/trace.go:171","msg":"trace[493061817] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"361.129529ms","start":"2026-08-13T19:18:01.766732Z","end":"2026-08-13T19:18:02.127861Z","steps":["trace[493061817] 'process raft request' (duration: 225.81646ms)","trace[493061817] 'compare' (duration: 134.709298ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:18:02.128059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.307692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-13T19:18:02.128104Z","caller":"traceutil/trace.go:171","msg":"trace[1603082109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25474; }","duration":"362.396813ms","start":"2026-08-13T19:18:01.765695Z","end":"2026-08-13T19:18:02.128092Z","steps":["trace[1603082109] 'agreement among raft nodes before linearized reading' (duration: 362.174349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:02.128141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:18:01.765661Z","time spent":"362.471665ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T19:18:02.128182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:18:01.766719Z","time spent":"361.286353ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:18:02.128343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.215879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-13T19:18:02.128405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.149178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-13T19:18:02.128438Z","caller":"traceutil/trace.go:171","msg":"trace[1583540235] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25474; }","duration":"351.366302ms","start":"2026-08-13T19:18:01.777051Z","end":"2026-08-13T19:18:02.128418Z","steps":["trace[1583540235] 'agreement among raft nodes before linearized reading' (duration: 351.079157ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:02.128503Z","caller":"traceutil/trace.go:171","msg":"trace[1085044459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25474; }","duration":"362.318192ms","start":"2026-08-13T19:18:01.766162Z","end":"2026-08-13T19:18:02.12848Z","steps":["trace[1085044459] 'agreement among raft nodes before linearized reading' (duration: 362.010836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:02.128506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:18:01.777041Z","time spent":"351.454134ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":81,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-08-13T19:18:02.128558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:18:01.766142Z","time spent":"362.408994ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-13T19:18:02.131909Z","caller":"traceutil/trace.go:171","msg":"trace[1623839828] transaction","detail":"{read_only:false; response_revision:25476; number_of_response:1; }","duration":"157.581264ms","start":"2026-08-13T19:18:01.974303Z","end":"2026-08-13T19:18:02.131884Z","steps":["trace[1623839828] 'process raft request' (duration: 157.530264ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:02.131961Z","caller":"traceutil/trace.go:171","msg":"trace[428561195] transaction","detail":"{read_only:false; response_revision:25475; number_of_response:1; }","duration":"253.121772ms","start":"2026-08-13T19:18:01.878815Z","end":"2026-08-13T19:18:02.131937Z","steps":["trace[428561195] 'process raft request' (duration: 252.875638ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:02.289793Z","caller":"traceutil/trace.go:171","msg":"trace[1945891028] linearizableReadLoop","detail":"{readStateIndex:26311; appliedIndex:26309; }","duration":"135.32397ms","start":"2026-08-13T19:18:02.15441Z","end":"2026-08-13T19:18:02.289734Z","steps":["trace[1945891028] 'read index received' (duration: 17.69597ms)","trace[1945891028] 'applied index is now lower than readState.Index' (duration: 117.62649ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:18:02.289848Z","caller":"traceutil/trace.go:171","msg":"trace[1138734636] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"155.880483ms","start":"2026-08-13T19:18:02.133924Z","end":"2026-08-13T19:18:02.289805Z","steps":["trace[1138734636] 'process raft request' (duration: 150.189974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:02.290126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.698927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21d414c9-c6eb-4eb4-8c9b-00bdd52c0b80\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-08-13T19:18:02.290191Z","caller":"traceutil/trace.go:171","msg":"trace[1960731960] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-21d414c9-c6eb-4eb4-8c9b-00bdd52c0b80; range_end:; response_count:1; response_revision:25478; }","duration":"135.817099ms","start":"2026-08-13T19:18:02.154361Z","end":"2026-08-13T19:18:02.290178Z","steps":["trace[1960731960] 'agreement among raft nodes before linearized reading' (duration: 135.563564ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:04.924802Z","caller":"traceutil/trace.go:171","msg":"trace[356134217] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"127.156533ms","start":"2026-08-13T19:18:04.797617Z","end":"2026-08-13T19:18:04.924774Z","steps":["trace[356134217] 'process raft request' (duration: 126.946609ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:04.973111Z","caller":"traceutil/trace.go:171","msg":"trace[1835935981] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"174.610221ms","start":"2026-08-13T19:18:04.798468Z","end":"2026-08-13T19:18:04.973079Z","steps":["trace[1835935981] 'process raft request' (duration: 174.444087ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:07.591828Z","caller":"traceutil/trace.go:171","msg":"trace[489692367] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"116.653461ms","start":"2026-08-13T19:18:07.475119Z","end":"2026-08-13T19:18:07.591773Z","steps":["trace[489692367] 'process raft request' (duration: 116.453247ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:07.658908Z","caller":"traceutil/trace.go:171","msg":"trace[1909931291] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"150.383486ms","start":"2026-08-13T19:18:07.508501Z","end":"2026-08-13T19:18:07.658885Z","steps":["trace[1909931291] 'process raft request' (duration: 149.875367ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:09.362269Z","caller":"traceutil/trace.go:171","msg":"trace[2136816988] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"119.548446ms","start":"2026-08-13T19:18:09.242661Z","end":"2026-08-13T19:18:09.36221Z","steps":["trace[2136816988] 'process raft request' (duration: 119.20993ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:38.518742Z","caller":"traceutil/trace.go:171","msg":"trace[1711735814] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"166.136499ms","start":"2026-08-13T19:18:38.352567Z","end":"2026-08-13T19:18:38.518704Z","steps":["trace[1711735814] 'process raft request' (duration: 134.777619ms)","trace[1711735814] 'compare' (duration: 31.163046ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:18:39.635457Z","caller":"traceutil/trace.go:171","msg":"trace[189066550] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"158.522494ms","start":"2026-08-13T19:18:39.476888Z","end":"2026-08-13T19:18:39.635411Z","steps":["trace[189066550] 'process raft request' (duration: 139.706173ms)","trace[189066550] 'compare' (duration: 18.577756ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:18:39.869525Z","caller":"traceutil/trace.go:171","msg":"trace[639232258] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"159.101903ms","start":"2026-08-13T19:18:39.710388Z","end":"2026-08-13T19:18:39.86949Z","steps":["trace[639232258] 'process raft request' (duration: 88.664346ms)","trace[639232258] 'compare' (duration: 69.713714ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:18:40.136537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.191817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:18:40.136648Z","caller":"traceutil/trace.go:171","msg":"trace[2093096695] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25802; }","duration":"100.372321ms","start":"2026-08-13T19:18:40.036247Z","end":"2026-08-13T19:18:40.13662Z","steps":["trace[2093096695] 'count revisions from in-memory index tree' (duration: 100.026884ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:40.36101Z","caller":"traceutil/trace.go:171","msg":"trace[2120535544] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"119.454276ms","start":"2026-08-13T19:18:40.241522Z","end":"2026-08-13T19:18:40.360976Z","steps":["trace[2120535544] 'process raft request' (duration: 119.259083ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:40.402461Z","caller":"traceutil/trace.go:171","msg":"trace[1460353486] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"133.119618ms","start":"2026-08-13T19:18:40.269316Z","end":"2026-08-13T19:18:40.402435Z","steps":["trace[1460353486] 'process raft request' (duration: 132.829472ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:40.40251Z","caller":"traceutil/trace.go:171","msg":"trace[2047954764] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"127.104182ms","start":"2026-08-13T19:18:40.275373Z","end":"2026-08-13T19:18:40.402477Z","steps":["trace[2047954764] 'process raft request' (duration: 126.96382ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:40.603319Z","caller":"traceutil/trace.go:171","msg":"trace[2066706263] linearizableReadLoop","detail":"{readStateIndex:26648; appliedIndex:26647; }","duration":"104.653522ms","start":"2026-08-13T19:18:40.498622Z","end":"2026-08-13T19:18:40.603276Z","steps":["trace[2066706263] 'read index received' (duration: 44.155965ms)","trace[2066706263] 'applied index is now lower than readState.Index' (duration: 60.495797ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:18:40.603871Z","caller":"traceutil/trace.go:171","msg":"trace[86801637] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"196.575902ms","start":"2026-08-13T19:18:40.407259Z","end":"2026-08-13T19:18:40.603835Z","steps":["trace[86801637] 'process raft request' (duration: 135.519724ms)","trace[86801637] 'compare' (duration: 60.278173ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:18:40.60394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.278475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-08-13T19:18:40.604063Z","caller":"traceutil/trace.go:171","msg":"trace[1219521226] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25807; }","duration":"105.456988ms","start":"2026-08-13T19:18:40.498576Z","end":"2026-08-13T19:18:40.604033Z","steps":["trace[1219521226] 'agreement among raft nodes before linearized reading' (duration: 105.034069ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:40.83166Z","caller":"traceutil/trace.go:171","msg":"trace[869501913] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"221.935586ms","start":"2026-08-13T19:18:40.609699Z","end":"2026-08-13T19:18:40.831635Z","steps":["trace[869501913] 'process raft request' (duration: 221.803304ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:40.831748Z","caller":"traceutil/trace.go:171","msg":"trace[1960173433] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"222.009527ms","start":"2026-08-13T19:18:40.609612Z","end":"2026-08-13T19:18:40.831622Z","steps":["trace[1960173433] 'process raft request' (duration: 198.002057ms)","trace[1960173433] 'compare' (duration: 23.580772ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:18:40.831932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.399674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T19:18:40.831936Z","caller":"traceutil/trace.go:171","msg":"trace[1577650938] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"221.359665ms","start":"2026-08-13T19:18:40.610518Z","end":"2026-08-13T19:18:40.831878Z","steps":["trace[1577650938] 'process raft request' (duration: 221.08513ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:40.831987Z","caller":"traceutil/trace.go:171","msg":"trace[1589333736] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25811; }","duration":"107.538798ms","start":"2026-08-13T19:18:40.724435Z","end":"2026-08-13T19:18:40.831973Z","steps":["trace[1589333736] 'agreement among raft nodes before linearized reading' (duration: 107.411255ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:40.831774Z","caller":"traceutil/trace.go:171","msg":"trace[247041277] linearizableReadLoop","detail":"{readStateIndex:26652; appliedIndex:26649; }","duration":"107.231801ms","start":"2026-08-13T19:18:40.724513Z","end":"2026-08-13T19:18:40.831744Z","steps":["trace[247041277] 'read index received' (duration: 83.12347ms)","trace[247041277] 'applied index is now lower than readState.Index' (duration: 24.106931ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:18:40.832405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.124374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T19:18:40.832503Z","caller":"traceutil/trace.go:171","msg":"trace[1419876423] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25811; }","duration":"102.313687ms","start":"2026-08-13T19:18:40.730167Z","end":"2026-08-13T19:18:40.832481Z","steps":["trace[1419876423] 'agreement among raft nodes before linearized reading' (duration: 102.024632ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:41.313415Z","caller":"traceutil/trace.go:171","msg":"trace[732021244] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"123.613075ms","start":"2026-08-13T19:18:41.189761Z","end":"2026-08-13T19:18:41.313374Z","steps":["trace[732021244] 'process raft request' (duration: 123.453232ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:41.31361Z","caller":"traceutil/trace.go:171","msg":"trace[715372889] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"135.727068ms","start":"2026-08-13T19:18:41.177842Z","end":"2026-08-13T19:18:41.313569Z","steps":["trace[715372889] 'process raft request' (duration: 62.797772ms)","trace[715372889] 'compare' (duration: 72.189412ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:18:41.557478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.070672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:18:41.557585Z","caller":"traceutil/trace.go:171","msg":"trace[392355895] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25814; }","duration":"102.199785ms","start":"2026-08-13T19:18:41.455358Z","end":"2026-08-13T19:18:41.557558Z","steps":["trace[392355895] 'count revisions from in-memory index tree' (duration: 101.9409ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:41.746872Z","caller":"traceutil/trace.go:171","msg":"trace[667566581] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"104.578991ms","start":"2026-08-13T19:18:41.642271Z","end":"2026-08-13T19:18:41.74685Z","steps":["trace[667566581] 'process raft request' (duration: 104.420078ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:42.292402Z","caller":"traceutil/trace.go:171","msg":"trace[285493558] linearizableReadLoop","detail":"{readStateIndex:26659; appliedIndex:26658; }","duration":"114.764456ms","start":"2026-08-13T19:18:42.177603Z","end":"2026-08-13T19:18:42.292368Z","steps":["trace[285493558] 'read index received' (duration: 114.377979ms)","trace[285493558] 'applied index is now lower than readState.Index' (duration: 385.187µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:18:42.292555Z","caller":"traceutil/trace.go:171","msg":"trace[1906124858] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"200.682859ms","start":"2026-08-13T19:18:42.091858Z","end":"2026-08-13T19:18:42.292541Z","steps":["trace[1906124858] 'process raft request' (duration: 200.274781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:42.292915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.493671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-13T19:18:42.293002Z","caller":"traceutil/trace.go:171","msg":"trace[1046945778] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25818; }","duration":"113.642385ms","start":"2026-08-13T19:18:42.179337Z","end":"2026-08-13T19:18:42.292979Z","steps":["trace[1046945778] 'agreement among raft nodes before linearized reading' (duration: 113.43474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:42.293226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.313044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T19:18:42.293306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.706705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:18:42.29328Z","caller":"traceutil/trace.go:171","msg":"trace[171712812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25818; }","duration":"107.406935ms","start":"2026-08-13T19:18:42.185861Z","end":"2026-08-13T19:18:42.293268Z","steps":["trace[171712812] 'agreement among raft nodes before linearized reading' (duration: 107.288193ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:42.293339Z","caller":"traceutil/trace.go:171","msg":"trace[1845327500] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25818; }","duration":"115.792416ms","start":"2026-08-13T19:18:42.177537Z","end":"2026-08-13T19:18:42.29333Z","steps":["trace[1845327500] 'agreement among raft nodes before linearized reading' (duration: 115.731695ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:45.429951Z","caller":"traceutil/trace.go:171","msg":"trace[713475978] linearizableReadLoop","detail":"{readStateIndex:26682; appliedIndex:26681; }","duration":"239.592944ms","start":"2026-08-13T19:18:45.190329Z","end":"2026-08-13T19:18:45.429922Z","steps":["trace[713475978] 'read index received' (duration: 239.40926ms)","trace[713475978] 'applied index is now lower than readState.Index' (duration: 181.894µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:18:45.429999Z","caller":"traceutil/trace.go:171","msg":"trace[1378866601] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"240.443311ms","start":"2026-08-13T19:18:45.189518Z","end":"2026-08-13T19:18:45.429961Z","steps":["trace[1378866601] 'process raft request' (duration: 240.129755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:45.430319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.805928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:18:45.430446Z","caller":"traceutil/trace.go:171","msg":"trace[957714888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25840; }","duration":"240.125473ms","start":"2026-08-13T19:18:45.190291Z","end":"2026-08-13T19:18:45.430416Z","steps":["trace[957714888] 'agreement among raft nodes before linearized reading' (duration: 239.765046ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:45.446636Z","caller":"traceutil/trace.go:171","msg":"trace[55061729] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"120.20054ms","start":"2026-08-13T19:18:45.326382Z","end":"2026-08-13T19:18:45.446582Z","steps":["trace[55061729] 'process raft request' (duration: 119.919224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:45.447049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.716074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:18:45.447151Z","caller":"traceutil/trace.go:171","msg":"trace[1674630215] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25842; }","duration":"196.865496ms","start":"2026-08-13T19:18:45.250264Z","end":"2026-08-13T19:18:45.44713Z","steps":["trace[1674630215] 'agreement among raft nodes before linearized reading' (duration: 196.694853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:18:45.447207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.003308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-13T19:18:45.447257Z","caller":"traceutil/trace.go:171","msg":"trace[1940936274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25842; }","duration":"118.096389ms","start":"2026-08-13T19:18:45.32914Z","end":"2026-08-13T19:18:45.447237Z","steps":["trace[1940936274] 'agreement among raft nodes before linearized reading' (duration: 117.924276ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:18:45.608766Z","caller":"traceutil/trace.go:171","msg":"trace[607830461] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"155.986215ms","start":"2026-08-13T19:18:45.452722Z","end":"2026-08-13T19:18:45.608708Z","steps":["trace[607830461] 'process raft request' (duration: 122.595886ms)","trace[607830461] 'compare' (duration: 33.166274ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:19:04.723806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23118} {"level":"info","ts":"2026-08-13T19:19:04.85756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23118,"took":"128.274333ms","hash":3938160255,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":36397056,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-13T19:19:04.857643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3938160255,"revision":23118,"compact-revision":20261} {"level":"info","ts":"2026-08-13T19:24:04.73176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25980} {"level":"info","ts":"2026-08-13T19:24:04.877705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25980,"took":"140.54232ms","hash":1694502532,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":35856384,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-13T19:24:04.877811Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1694502532,"revision":25980,"compact-revision":23118} {"level":"info","ts":"2026-08-13T19:24:34.870615Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T19:24:34.875886Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-13T19:24:34.876021Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-13T19:29:04.745158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28852} {"level":"info","ts":"2026-08-13T19:29:04.89021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28852,"took":"137.756308ms","hash":1109667137,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":31109120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T19:29:04.890291Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1109667137,"revision":28852,"compact-revision":25980} {"level":"info","ts":"2026-08-13T19:34:04.750924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31075} {"level":"info","ts":"2026-08-13T19:34:04.8825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31075,"took":"127.259501ms","hash":528094705,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":30605312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T19:34:04.882684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":528094705,"revision":31075,"compact-revision":28852} {"level":"info","ts":"2026-08-13T19:39:04.757506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33333} {"level":"info","ts":"2026-08-13T19:39:04.923425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33333,"took":"158.061888ms","hash":2668227306,"current-db-size-bytes":59691008,"current-db-size":"60 MB","current-db-size-in-use-bytes":31281152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T19:39:04.923491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2668227306,"revision":33333,"compact-revision":31075} {"level":"info","ts":"2026-08-13T19:44:04.766892Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35631} {"level":"info","ts":"2026-08-13T19:44:04.91279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35631,"took":"141.286764ms","hash":2623100109,"current-db-size-bytes":59691008,"current-db-size":"60 MB","current-db-size-in-use-bytes":31903744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-13T19:44:04.912963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2623100109,"revision":35631,"compact-revision":33333} {"level":"info","ts":"2026-08-13T19:46:01.853403Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T19:46:01.859271Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-13T19:46:01.859368Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-13T19:49:04.775114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37859} {"level":"info","ts":"2026-08-13T19:49:04.899919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37859,"took":"119.811944ms","hash":3460949049,"current-db-size-bytes":59691008,"current-db-size":"60 MB","current-db-size-in-use-bytes":32550912,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-13T19:49:04.899996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3460949049,"revision":37859,"compact-revision":35631}