{"level":"warn","ts":"2026-08-19T09:13:37.179472Z","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-19T09:13:37.179566Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-19T09:13:37.179655Z","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-19T09:13:37.179667Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-19T09:13:37.179684Z","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-19T09:13:37.180254Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-08-19T09:13:37.180375Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-19T09:13:37.184467Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.885484ms"} {"level":"info","ts":"2026-08-19T09:13:37.190205Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-08-19T09:13:37.190322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T09:13:37.190365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-08-19T09:13:37.190389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T09:13:37.190424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-08-19T09:13:37.190488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-08-19T09:13:37.195319Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T09:13:37.197449Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T09:13:37.19886Z","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-19T09:13:37.201046Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T09:13:37.2036Z","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-19T09:13:37.203732Z","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-19T09:13:37.203758Z","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-19T09:13:37.203712Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T09:13:37.205034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-08-19T09:13:37.205131Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-19T09:13:37.205293Z","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-19T09:13:37.205422Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-19T09:13:37.205459Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-19T09:13:37.205817Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T09:13:37.205863Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T09:13:37.391007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T09:13:37.391068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T09:13:37.391092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-08-19T09:13:37.391111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-08-19T09:13:37.391123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-19T09:13:37.391139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-08-19T09:13:37.391283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-19T09:13:37.39238Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T09:13:37.392385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:13:37.392384Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:13:37.392428Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:13:37.392896Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T09:13:37.393003Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T09:13:37.393343Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:13:37.393467Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:13:37.393502Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:13:37.396495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T09:13:37.396495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"warn","ts":"2026-08-19T09:13:55.626093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.295618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-19T09:13:55.626189Z","caller":"traceutil/trace.go:171","msg":"trace[528826816] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"389.161525ms","start":"2026-08-19T09:13:55.236999Z","end":"2026-08-19T09:13:55.626161Z","steps":["trace[528826816] 'process raft request' (duration: 382.727221ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:13:55.62623Z","caller":"traceutil/trace.go:171","msg":"trace[1217735320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"389.464272ms","start":"2026-08-19T09:13:55.236745Z","end":"2026-08-19T09:13:55.626209Z","steps":["trace[1217735320] 'range keys from in-memory index tree' (duration: 389.190775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:13:55.626294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:13:55.236739Z","time spent":"389.544914ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:13:55.626918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:13:55.236974Z","time spent":"389.302528ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:13:55.942019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.91985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:13:55.942127Z","caller":"traceutil/trace.go:171","msg":"trace[219053894] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"312.3142ms","start":"2026-08-19T09:13:55.629795Z","end":"2026-08-19T09:13:55.94211Z","steps":["trace[219053894] 'process raft request' (duration: 103.092623ms)","trace[219053894] 'compare' (duration: 208.782995ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:13:55.942187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:13:55.629777Z","time spent":"312.382463ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:13:56.090801Z","caller":"traceutil/trace.go:171","msg":"trace[1269019394] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"455.592399ms","start":"2026-08-19T09:13:55.635181Z","end":"2026-08-19T09:13:56.090774Z","steps":["trace[1269019394] 'process raft request' (duration: 416.528994ms)","trace[1269019394] 'compare' (duration: 38.91411ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:13:56.091011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:13:55.635169Z","time spent":"455.772683ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:14:20.950186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.11004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:14:20.950332Z","caller":"traceutil/trace.go:171","msg":"trace[1958577436] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"355.787944ms","start":"2026-08-19T09:14:20.594517Z","end":"2026-08-19T09:14:20.950305Z","steps":["trace[1958577436] 'read index received' (duration: 32.662µs)","trace[1958577436] 'applied index is now lower than readState.Index' (duration: 355.753712ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:14:20.950549Z","caller":"traceutil/trace.go:171","msg":"trace[1978332506] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"618.742071ms","start":"2026-08-19T09:14:20.331791Z","end":"2026-08-19T09:14:20.950533Z","steps":["trace[1978332506] 'process raft request' (duration: 95.21085ms)","trace[1978332506] 'compare' (duration: 522.990037ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:14:20.950631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:14:20.331738Z","time spent":"618.859425ms","remote":"127.0.0.1:35622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:14:20.950925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.473269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9zw74\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-19T09:14:20.950954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.405531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:14:20.951019Z","caller":"traceutil/trace.go:171","msg":"trace[1758718708] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9zw74; range_end:; response_count:1; response_revision:593; }","duration":"344.608491ms","start":"2026-08-19T09:14:20.606389Z","end":"2026-08-19T09:14:20.950998Z","steps":["trace[1758718708] 'agreement among raft nodes before linearized reading' (duration: 344.338574ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:20.951053Z","caller":"traceutil/trace.go:171","msg":"trace[802534894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"356.554075ms","start":"2026-08-19T09:14:20.594476Z","end":"2026-08-19T09:14:20.95103Z","steps":["trace[802534894] 'agreement among raft nodes before linearized reading' (duration: 356.36804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:20.951021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.308251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-19T09:14:20.951112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:14:20.594451Z","time spent":"356.651926ms","remote":"127.0.0.1:35064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T09:14:20.951127Z","caller":"traceutil/trace.go:171","msg":"trace[551874597] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:593; }","duration":"318.474557ms","start":"2026-08-19T09:14:20.632632Z","end":"2026-08-19T09:14:20.951106Z","steps":["trace[551874597] 'agreement among raft nodes before linearized reading' (duration: 318.258232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:20.951177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:14:20.632591Z","time spent":"318.576429ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-08-19T09:14:20.951066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:14:20.606366Z","time spent":"344.691114ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-9zw74\" "} {"level":"warn","ts":"2026-08-19T09:14:21.477536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204402998815541,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T09:14:21.57706Z","caller":"traceutil/trace.go:171","msg":"trace[466796726] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"610.651624ms","start":"2026-08-19T09:14:20.966384Z","end":"2026-08-19T09:14:21.577036Z","steps":["trace[466796726] 'process raft request' (duration: 603.241004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:21.577224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:14:20.966373Z","time spent":"610.767457ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:14:21.589785Z","caller":"traceutil/trace.go:171","msg":"trace[458424284] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"613.121611ms","start":"2026-08-19T09:14:20.976625Z","end":"2026-08-19T09:14:21.589747Z","steps":["trace[458424284] 'read index received' (duration: 593.08159ms)","trace[458424284] 'applied index is now lower than readState.Index' (duration: 20.039061ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:14:21.589987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.331646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-19T09:14:21.590061Z","caller":"traceutil/trace.go:171","msg":"trace[146174218] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"209.70547ms","start":"2026-08-19T09:14:21.380339Z","end":"2026-08-19T09:14:21.590045Z","steps":["trace[146174218] 'process raft request' (duration: 206.753211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:21.590075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.459478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-19T09:14:21.59013Z","caller":"traceutil/trace.go:171","msg":"trace[1323536652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"311.54053ms","start":"2026-08-19T09:14:21.278574Z","end":"2026-08-19T09:14:21.590115Z","steps":["trace[1323536652] 'agreement among raft nodes before linearized reading' (duration: 311.381116ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:21.590048Z","caller":"traceutil/trace.go:171","msg":"trace[1497095490] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:596; }","duration":"613.457628ms","start":"2026-08-19T09:14:20.976577Z","end":"2026-08-19T09:14:21.590034Z","steps":["trace[1497095490] 'agreement among raft nodes before linearized reading' (duration: 613.320365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:21.590101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.455726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:14:21.590178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:14:21.278559Z","time spent":"311.609602ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T09:14:21.590217Z","caller":"traceutil/trace.go:171","msg":"trace[242342973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"245.58144ms","start":"2026-08-19T09:14:21.344601Z","end":"2026-08-19T09:14:21.590183Z","steps":["trace[242342973] 'agreement among raft nodes before linearized reading' (duration: 245.382944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:21.590177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:14:20.976559Z","time spent":"613.609804ms","remote":"127.0.0.1:35226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-19T09:14:21.590365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.518274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T09:14:21.5904Z","caller":"traceutil/trace.go:171","msg":"trace[735960011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"306.607286ms","start":"2026-08-19T09:14:21.283781Z","end":"2026-08-19T09:14:21.590388Z","steps":["trace[735960011] 'agreement among raft nodes before linearized reading' (duration: 306.524854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:21.590432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:14:21.283751Z","time spent":"306.672998ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-19T09:14:22.865685Z","caller":"traceutil/trace.go:171","msg":"trace[1418915687] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"136.978897ms","start":"2026-08-19T09:14:22.728674Z","end":"2026-08-19T09:14:22.865653Z","steps":["trace[1418915687] 'process raft request' (duration: 131.769947ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:22.87034Z","caller":"traceutil/trace.go:171","msg":"trace[1021371455] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"140.935794ms","start":"2026-08-19T09:14:22.72937Z","end":"2026-08-19T09:14:22.870305Z","steps":["trace[1021371455] 'process raft request' (duration: 140.77781ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:35.817315Z","caller":"traceutil/trace.go:171","msg":"trace[1448404588] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"153.318988ms","start":"2026-08-19T09:14:35.663963Z","end":"2026-08-19T09:14:35.817282Z","steps":["trace[1448404588] 'process raft request' (duration: 153.178024ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:35.834588Z","caller":"traceutil/trace.go:171","msg":"trace[164850146] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"124.945433ms","start":"2026-08-19T09:14:35.709617Z","end":"2026-08-19T09:14:35.834563Z","steps":["trace[164850146] 'read index received' (duration: 107.582474ms)","trace[164850146] 'applied index is now lower than readState.Index' (duration: 17.361569ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:14:35.834727Z","caller":"traceutil/trace.go:171","msg":"trace[1134941338] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"169.810484ms","start":"2026-08-19T09:14:35.664879Z","end":"2026-08-19T09:14:35.834689Z","steps":["trace[1134941338] 'process raft request' (duration: 169.529616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:14:35.834802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.171429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7wnlw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-19T09:14:35.834841Z","caller":"traceutil/trace.go:171","msg":"trace[1314780898] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7wnlw; range_end:; response_count:1; response_revision:759; }","duration":"125.284931ms","start":"2026-08-19T09:14:35.709543Z","end":"2026-08-19T09:14:35.834828Z","steps":["trace[1314780898] 'agreement among raft nodes before linearized reading' (duration: 125.116447ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:36.072805Z","caller":"traceutil/trace.go:171","msg":"trace[1634626791] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"201.407837ms","start":"2026-08-19T09:14:35.871367Z","end":"2026-08-19T09:14:36.072774Z","steps":["trace[1634626791] 'read index received' (duration: 180.592865ms)","trace[1634626791] 'applied index is now lower than readState.Index' (duration: 20.813282ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:14:36.072911Z","caller":"traceutil/trace.go:171","msg":"trace[505014275] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"228.726603ms","start":"2026-08-19T09:14:35.844123Z","end":"2026-08-19T09:14:36.07285Z","steps":["trace[505014275] 'process raft request' (duration: 207.774668ms)","trace[505014275] 'compare' (duration: 20.743521ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:14:36.07307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.678144ms","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-19T09:14:36.073174Z","caller":"traceutil/trace.go:171","msg":"trace[2109263164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"201.845929ms","start":"2026-08-19T09:14:35.871306Z","end":"2026-08-19T09:14:36.073152Z","steps":["trace[2109263164] 'agreement among raft nodes before linearized reading' (duration: 201.559892ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:15:25.09957Z","caller":"traceutil/trace.go:171","msg":"trace[688492330] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"104.770398ms","start":"2026-08-19T09:15:24.994764Z","end":"2026-08-19T09:15:25.099535Z","steps":["trace[688492330] 'process raft request' (duration: 104.556032ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:15:25.220808Z","caller":"traceutil/trace.go:171","msg":"trace[1309525099] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"116.543707ms","start":"2026-08-19T09:15:25.10423Z","end":"2026-08-19T09:15:25.220774Z","steps":["trace[1309525099] 'process raft request' (duration: 116.299599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:15:53.316354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.913763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9f8224b7-8d83-48d8-9d92-c0f3fe61a1bb\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-19T09:15:53.316548Z","caller":"traceutil/trace.go:171","msg":"trace[941191880] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9f8224b7-8d83-48d8-9d92-c0f3fe61a1bb; range_end:; response_count:1; response_revision:1826; }","duration":"218.16716ms","start":"2026-08-19T09:15:53.098359Z","end":"2026-08-19T09:15:53.316526Z","steps":["trace[941191880] 'range keys from in-memory index tree' (duration: 217.654335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:15:53.316569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.399808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-08-19T09:15:53.316723Z","caller":"traceutil/trace.go:171","msg":"trace[1685435796] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1826; }","duration":"207.604043ms","start":"2026-08-19T09:15:53.109084Z","end":"2026-08-19T09:15:53.316688Z","steps":["trace[1685435796] 'range keys from in-memory index tree' (duration: 207.194613ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:15:53.533203Z","caller":"traceutil/trace.go:171","msg":"trace[872129355] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1897; }","duration":"284.555551ms","start":"2026-08-19T09:15:53.248615Z","end":"2026-08-19T09:15:53.533171Z","steps":["trace[872129355] 'read index received' (duration: 284.456268ms)","trace[872129355] 'applied index is now lower than readState.Index' (duration: 98.183µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:15:53.533286Z","caller":"traceutil/trace.go:171","msg":"trace[715484608] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"332.214918ms","start":"2026-08-19T09:15:53.201049Z","end":"2026-08-19T09:15:53.533264Z","steps":["trace[715484608] 'process raft request' (duration: 331.991612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:15:53.533404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.781007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-19T09:15:53.533414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:15:53.201007Z","time spent":"332.333791ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:15:53.533441Z","caller":"traceutil/trace.go:171","msg":"trace[1413079570] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1827; }","duration":"284.88152ms","start":"2026-08-19T09:15:53.248548Z","end":"2026-08-19T09:15:53.53343Z","steps":["trace[1413079570] 'agreement among raft nodes before linearized reading' (duration: 284.728585ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:15:53.563772Z","caller":"traceutil/trace.go:171","msg":"trace[1762739581] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"241.55084ms","start":"2026-08-19T09:15:53.322203Z","end":"2026-08-19T09:15:53.563754Z","steps":["trace[1762739581] 'process raft request' (duration: 241.342335ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:15:53.563945Z","caller":"traceutil/trace.go:171","msg":"trace[400969599] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"238.012484ms","start":"2026-08-19T09:15:53.325905Z","end":"2026-08-19T09:15:53.563917Z","steps":["trace[400969599] 'process raft request' (duration: 237.787948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:15:53.564089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.445873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-19T09:15:53.564151Z","caller":"traceutil/trace.go:171","msg":"trace[1814821586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1829; }","duration":"206.557635ms","start":"2026-08-19T09:15:53.357579Z","end":"2026-08-19T09:15:53.564137Z","steps":["trace[1814821586] 'agreement among raft nodes before linearized reading' (duration: 206.320959ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:06.734159Z","caller":"traceutil/trace.go:171","msg":"trace[1614628722] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"216.4558ms","start":"2026-08-19T09:16:06.517677Z","end":"2026-08-19T09:16:06.734133Z","steps":["trace[1614628722] 'process raft request' (duration: 216.293435ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:06.736202Z","caller":"traceutil/trace.go:171","msg":"trace[1904966237] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2022; }","duration":"142.0572ms","start":"2026-08-19T09:16:06.594123Z","end":"2026-08-19T09:16:06.73618Z","steps":["trace[1904966237] 'read index received' (duration: 139.846399ms)","trace[1904966237] 'applied index is now lower than readState.Index' (duration: 2.209381ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:16:06.736321Z","caller":"traceutil/trace.go:171","msg":"trace[2106302882] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"205.753061ms","start":"2026-08-19T09:16:06.530547Z","end":"2026-08-19T09:16:06.7363Z","steps":["trace[2106302882] 'process raft request' (duration: 205.508454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:16:06.736351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.214063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:16:06.736402Z","caller":"traceutil/trace.go:171","msg":"trace[719171470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1950; }","duration":"142.308027ms","start":"2026-08-19T09:16:06.594069Z","end":"2026-08-19T09:16:06.736377Z","steps":["trace[719171470] 'agreement among raft nodes before linearized reading' (duration: 142.229635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:16:06.736504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.288076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-19T09:16:06.736593Z","caller":"traceutil/trace.go:171","msg":"trace[713949573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1950; }","duration":"132.43467ms","start":"2026-08-19T09:16:06.604138Z","end":"2026-08-19T09:16:06.736573Z","steps":["trace[713949573] 'agreement among raft nodes before linearized reading' (duration: 132.209643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:16:06.736587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.862297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-19T09:16:06.736661Z","caller":"traceutil/trace.go:171","msg":"trace[1006646484] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1950; }","duration":"113.9769ms","start":"2026-08-19T09:16:06.622666Z","end":"2026-08-19T09:16:06.736643Z","steps":["trace[1006646484] 'agreement among raft nodes before linearized reading' (duration: 113.773254ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:37.105514Z","caller":"traceutil/trace.go:171","msg":"trace[1334334269] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"131.452263ms","start":"2026-08-19T09:16:36.97404Z","end":"2026-08-19T09:16:37.105492Z","steps":["trace[1334334269] 'process raft request' (duration: 131.290799ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:37.285025Z","caller":"traceutil/trace.go:171","msg":"trace[1050523805] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2201; }","duration":"232.377631ms","start":"2026-08-19T09:16:37.05262Z","end":"2026-08-19T09:16:37.284998Z","steps":["trace[1050523805] 'read index received' (duration: 52.739556ms)","trace[1050523805] 'applied index is now lower than readState.Index' (duration: 179.636955ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:16:37.285158Z","caller":"traceutil/trace.go:171","msg":"trace[486274651] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"253.282595ms","start":"2026-08-19T09:16:37.031835Z","end":"2026-08-19T09:16:37.285118Z","steps":["trace[486274651] 'process raft request' (duration: 244.831947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:16:37.28531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.793657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-08-19T09:16:37.285233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.584127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:16:37.285377Z","caller":"traceutil/trace.go:171","msg":"trace[315781813] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2123; }","duration":"163.872489ms","start":"2026-08-19T09:16:37.12149Z","end":"2026-08-19T09:16:37.285363Z","steps":["trace[315781813] 'agreement among raft nodes before linearized reading' (duration: 163.707555ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:37.285439Z","caller":"traceutil/trace.go:171","msg":"trace[1073496920] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2123; }","duration":"232.815032ms","start":"2026-08-19T09:16:37.05257Z","end":"2026-08-19T09:16:37.285385Z","steps":["trace[1073496920] 'agreement among raft nodes before linearized reading' (duration: 232.593326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:16:37.897102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.469292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-19T09:16:37.897202Z","caller":"traceutil/trace.go:171","msg":"trace[28240263] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2124; }","duration":"131.616317ms","start":"2026-08-19T09:16:37.765557Z","end":"2026-08-19T09:16:37.897173Z","steps":["trace[28240263] 'range keys from in-memory index tree' (duration: 131.276077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:16:37.897204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.977507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-19T09:16:37.897285Z","caller":"traceutil/trace.go:171","msg":"trace[2073559890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2124; }","duration":"115.117022ms","start":"2026-08-19T09:16:37.78215Z","end":"2026-08-19T09:16:37.897267Z","steps":["trace[2073559890] 'range keys from in-memory index tree' (duration: 114.731541ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:43.578093Z","caller":"traceutil/trace.go:171","msg":"trace[1061873787] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"237.136129ms","start":"2026-08-19T09:16:43.340874Z","end":"2026-08-19T09:16:43.57801Z","steps":["trace[1061873787] 'process raft request' (duration: 236.953374ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:17:34.997831Z","caller":"traceutil/trace.go:171","msg":"trace[658370279] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"253.010189ms","start":"2026-08-19T09:17:34.744797Z","end":"2026-08-19T09:17:34.997807Z","steps":["trace[658370279] 'process raft request' (duration: 252.830575ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:17:35.070088Z","caller":"traceutil/trace.go:171","msg":"trace[753385493] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"325.023168ms","start":"2026-08-19T09:17:34.745037Z","end":"2026-08-19T09:17:35.07006Z","steps":["trace[753385493] 'process raft request' (duration: 276.801181ms)","trace[753385493] 'compare' (duration: 48.043713ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:17:35.070161Z","caller":"traceutil/trace.go:171","msg":"trace[1237379321] linearizableReadLoop","detail":"{readStateIndex:2706; appliedIndex:2704; }","duration":"275.237169ms","start":"2026-08-19T09:17:34.794903Z","end":"2026-08-19T09:17:35.07014Z","steps":["trace[1237379321] 'read index received' (duration: 202.695366ms)","trace[1237379321] 'applied index is now lower than readState.Index' (duration: 72.540833ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:17:35.070248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:17:34.745026Z","time spent":"325.142231ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:17:35.070311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.387552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:17:35.070367Z","caller":"traceutil/trace.go:171","msg":"trace[1635606981] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2608; }","duration":"275.473545ms","start":"2026-08-19T09:17:34.794867Z","end":"2026-08-19T09:17:35.07034Z","steps":["trace[1635606981] 'agreement among raft nodes before linearized reading' (duration: 275.389193ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:06.66735Z","caller":"traceutil/trace.go:171","msg":"trace[553023671] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"127.931267ms","start":"2026-08-19T09:18:06.539395Z","end":"2026-08-19T09:18:06.667326Z","steps":["trace[553023671] 'process raft request' (duration: 127.777363ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:12.201042Z","caller":"traceutil/trace.go:171","msg":"trace[1138627249] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"130.917807ms","start":"2026-08-19T09:18:12.070061Z","end":"2026-08-19T09:18:12.200979Z","steps":["trace[1138627249] 'process raft request' (duration: 130.758582ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:12.53843Z","caller":"traceutil/trace.go:171","msg":"trace[1556825887] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"104.732602ms","start":"2026-08-19T09:18:12.43367Z","end":"2026-08-19T09:18:12.538403Z","steps":["trace[1556825887] 'process raft request' (duration: 104.527896ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:37.72653Z","caller":"traceutil/trace.go:171","msg":"trace[393042385] transaction","detail":"{read_only:false; response_revision:3792; number_of_response:1; }","duration":"121.27712ms","start":"2026-08-19T09:20:37.605225Z","end":"2026-08-19T09:20:37.726503Z","steps":["trace[393042385] 'process raft request' (duration: 121.098975ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:43.033016Z","caller":"traceutil/trace.go:171","msg":"trace[746883200] transaction","detail":"{read_only:false; response_revision:3821; number_of_response:1; }","duration":"310.833159ms","start":"2026-08-19T09:20:42.722146Z","end":"2026-08-19T09:20:43.032979Z","steps":["trace[746883200] 'process raft request' (duration: 310.643665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:20:43.03319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:20:42.722118Z","time spent":"310.986554ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:22:01.233161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.750027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-19T09:22:01.233284Z","caller":"traceutil/trace.go:171","msg":"trace[1089975842] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4847; }","duration":"118.927741ms","start":"2026-08-19T09:22:01.114328Z","end":"2026-08-19T09:22:01.233256Z","steps":["trace[1089975842] 'range keys from in-memory index tree' (duration: 118.563621ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:01.454106Z","caller":"traceutil/trace.go:171","msg":"trace[470089937] linearizableReadLoop","detail":"{readStateIndex:5036; appliedIndex:5035; }","duration":"109.683181ms","start":"2026-08-19T09:22:01.344383Z","end":"2026-08-19T09:22:01.454066Z","steps":["trace[470089937] 'read index received' (duration: 18.263865ms)","trace[470089937] 'applied index is now lower than readState.Index' (duration: 91.417956ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:22:01.454322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.917297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:22:01.4544Z","caller":"traceutil/trace.go:171","msg":"trace[148544522] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"140.28354ms","start":"2026-08-19T09:22:01.314087Z","end":"2026-08-19T09:22:01.454371Z","steps":["trace[148544522] 'process raft request' (duration: 48.578086ms)","trace[148544522] 'compare' (duration: 91.177079ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:22:01.454428Z","caller":"traceutil/trace.go:171","msg":"trace[44440651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4849; }","duration":"110.03246ms","start":"2026-08-19T09:22:01.344374Z","end":"2026-08-19T09:22:01.454407Z","steps":["trace[44440651] 'agreement among raft nodes before linearized reading' (duration: 109.878797ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:02.160317Z","caller":"traceutil/trace.go:171","msg":"trace[863304595] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"131.834992ms","start":"2026-08-19T09:22:02.028455Z","end":"2026-08-19T09:22:02.16029Z","steps":["trace[863304595] 'process raft request' (duration: 131.655377ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:05.595415Z","caller":"traceutil/trace.go:171","msg":"trace[1117294003] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4973; }","duration":"111.646014ms","start":"2026-08-19T09:22:05.483732Z","end":"2026-08-19T09:22:05.595378Z","steps":["trace[1117294003] 'process raft request' (duration: 111.536501ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:05.595431Z","caller":"traceutil/trace.go:171","msg":"trace[1065730449] linearizableReadLoop","detail":"{readStateIndex:5163; appliedIndex:5163; }","duration":"107.894681ms","start":"2026-08-19T09:22:05.487491Z","end":"2026-08-19T09:22:05.595386Z","steps":["trace[1065730449] 'read index received' (duration: 107.878931ms)","trace[1065730449] 'applied index is now lower than readState.Index' (duration: 11.36µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:22:05.595676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.159669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-t7nq7-tls\" ","response":"range_response_count:1 size:4428"} {"level":"info","ts":"2026-08-19T09:22:05.595768Z","caller":"traceutil/trace.go:171","msg":"trace[1771238923] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-t7nq7-tls; range_end:; response_count:1; response_revision:4973; }","duration":"108.289054ms","start":"2026-08-19T09:22:05.487458Z","end":"2026-08-19T09:22:05.595747Z","steps":["trace[1771238923] 'agreement among raft nodes before linearized reading' (duration: 108.006306ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:05.810434Z","caller":"traceutil/trace.go:171","msg":"trace[1735459091] linearizableReadLoop","detail":"{readStateIndex:5164; appliedIndex:5163; }","duration":"177.557731ms","start":"2026-08-19T09:22:05.632845Z","end":"2026-08-19T09:22:05.810403Z","steps":["trace[1735459091] 'read index received' (duration: 177.244162ms)","trace[1735459091] 'applied index is now lower than readState.Index' (duration: 311.679µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:22:05.810511Z","caller":"traceutil/trace.go:171","msg":"trace[1240754770] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"189.261028ms","start":"2026-08-19T09:22:05.621196Z","end":"2026-08-19T09:22:05.810457Z","steps":["trace[1240754770] 'process raft request' (duration: 188.97825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:05.810674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.811607ms","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-19T09:22:05.810728Z","caller":"traceutil/trace.go:171","msg":"trace[1369070484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4974; }","duration":"177.94445ms","start":"2026-08-19T09:22:05.632773Z","end":"2026-08-19T09:22:05.810717Z","steps":["trace[1369070484] 'agreement among raft nodes before linearized reading' (duration: 177.737285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:06.155546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.024442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:22:06.155694Z","caller":"traceutil/trace.go:171","msg":"trace[1071730077] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"173.395307ms","start":"2026-08-19T09:22:05.98228Z","end":"2026-08-19T09:22:06.155675Z","steps":["trace[1071730077] 'read index received' (duration: 47.131µs)","trace[1071730077] 'applied index is now lower than readState.Index' (duration: 173.346206ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:22:06.155745Z","caller":"traceutil/trace.go:171","msg":"trace[1342150938] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"190.891301ms","start":"2026-08-19T09:22:05.964815Z","end":"2026-08-19T09:22:06.155706Z","steps":["trace[1342150938] 'process raft request' (duration: 12.633752ms)","trace[1342150938] 'compare' (duration: 177.691084ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:22:06.155944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.654514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-10dad343-f322-4889-bf2d-acb6871de2c6\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-19T09:22:06.155987Z","caller":"traceutil/trace.go:171","msg":"trace[338417762] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-10dad343-f322-4889-bf2d-acb6871de2c6; range_end:; response_count:1; response_revision:4981; }","duration":"173.748606ms","start":"2026-08-19T09:22:05.982226Z","end":"2026-08-19T09:22:06.155975Z","steps":["trace[338417762] 'agreement among raft nodes before linearized reading' (duration: 173.50047ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:18.532188Z","caller":"traceutil/trace.go:171","msg":"trace[897845160] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"107.689647ms","start":"2026-08-19T09:22:18.424462Z","end":"2026-08-19T09:22:18.532152Z","steps":["trace[897845160] 'process raft request' (duration: 99.788352ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:18.573927Z","caller":"traceutil/trace.go:171","msg":"trace[1135617607] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"146.026305ms","start":"2026-08-19T09:22:18.42787Z","end":"2026-08-19T09:22:18.573896Z","steps":["trace[1135617607] 'process raft request' (duration: 145.766818ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:18.811646Z","caller":"traceutil/trace.go:171","msg":"trace[1577449349] linearizableReadLoop","detail":"{readStateIndex:5457; appliedIndex:5455; }","duration":"107.925985ms","start":"2026-08-19T09:22:18.703658Z","end":"2026-08-19T09:22:18.811584Z","steps":["trace[1577449349] 'read index received' (duration: 45.291758ms)","trace[1577449349] 'applied index is now lower than readState.Index' (duration: 62.633517ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:22:18.811682Z","caller":"traceutil/trace.go:171","msg":"trace[871216651] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"108.255552ms","start":"2026-08-19T09:22:18.703386Z","end":"2026-08-19T09:22:18.811642Z","steps":["trace[871216651] 'process raft request' (duration: 108.048716ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:18.811722Z","caller":"traceutil/trace.go:171","msg":"trace[410399048] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"107.685207ms","start":"2026-08-19T09:22:18.703971Z","end":"2026-08-19T09:22:18.811656Z","steps":["trace[410399048] 'process raft request' (duration: 107.486901ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:18.811751Z","caller":"traceutil/trace.go:171","msg":"trace[319719535] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"107.382689ms","start":"2026-08-19T09:22:18.704324Z","end":"2026-08-19T09:22:18.811706Z","steps":["trace[319719535] 'process raft request' (duration: 107.152733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:18.811805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.123869ms","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:5"} {"level":"info","ts":"2026-08-19T09:22:18.811847Z","caller":"traceutil/trace.go:171","msg":"trace[2010927809] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5262; }","duration":"108.228961ms","start":"2026-08-19T09:22:18.703605Z","end":"2026-08-19T09:22:18.811834Z","steps":["trace[2010927809] 'agreement among raft nodes before linearized reading' (duration: 108.110238ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:18.811835Z","caller":"traceutil/trace.go:171","msg":"trace[663904960] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"106.829764ms","start":"2026-08-19T09:22:18.704982Z","end":"2026-08-19T09:22:18.811812Z","steps":["trace[663904960] 'process raft request' (duration: 106.513995ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:18.811598Z","caller":"traceutil/trace.go:171","msg":"trace[887104036] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"108.461937ms","start":"2026-08-19T09:22:18.703054Z","end":"2026-08-19T09:22:18.811516Z","steps":["trace[887104036] 'process raft request' (duration: 89.957786ms)","trace[887104036] 'compare' (duration: 18.283086ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:22:19.365244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.846525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:22:19.365592Z","caller":"traceutil/trace.go:171","msg":"trace[262465020] linearizableReadLoop","detail":"{readStateIndex:5464; appliedIndex:5462; }","duration":"505.298039ms","start":"2026-08-19T09:22:18.860271Z","end":"2026-08-19T09:22:19.365569Z","steps":["trace[262465020] 'read index received' (duration: 105.987602ms)","trace[262465020] 'applied index is now lower than readState.Index' (duration: 399.309137ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:22:19.36564Z","caller":"traceutil/trace.go:171","msg":"trace[1807227097] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"533.236165ms","start":"2026-08-19T09:22:18.832371Z","end":"2026-08-19T09:22:19.365607Z","steps":["trace[1807227097] 'process raft request' (duration: 533.089271ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:19.3657Z","caller":"traceutil/trace.go:171","msg":"trace[1658655630] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"541.803298ms","start":"2026-08-19T09:22:18.823811Z","end":"2026-08-19T09:22:19.365614Z","steps":["trace[1658655630] 'process raft request' (duration: 142.497961ms)","trace[1658655630] 'compare' (duration: 398.67833ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:22:19.36578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.509844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-19T09:22:19.365823Z","caller":"traceutil/trace.go:171","msg":"trace[278349773] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5265; }","duration":"505.595667ms","start":"2026-08-19T09:22:18.860217Z","end":"2026-08-19T09:22:19.365812Z","steps":["trace[278349773] 'agreement among raft nodes before linearized reading' (duration: 505.451923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:19.36585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:22:18.860195Z","time spent":"505.649758ms","remote":"127.0.0.1:35256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1308,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-08-19T09:22:19.36587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:22:18.832353Z","time spent":"533.434591ms","remote":"127.0.0.1:52986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7075,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:22:19.365907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:22:18.82379Z","time spent":"542.013883ms","remote":"127.0.0.1:35130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":902,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:22:19.632112Z","caller":"traceutil/trace.go:171","msg":"trace[1883568018] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"275.735229ms","start":"2026-08-19T09:22:19.356342Z","end":"2026-08-19T09:22:19.632077Z","steps":["trace[1883568018] 'process raft request' (duration: 246.411705ms)","trace[1883568018] 'compare' (duration: 29.069408ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:22:19.632177Z","caller":"traceutil/trace.go:171","msg":"trace[504794172] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5464; }","duration":"266.421337ms","start":"2026-08-19T09:22:19.365728Z","end":"2026-08-19T09:22:19.63215Z","steps":["trace[504794172] 'read index received' (duration: 237.037241ms)","trace[504794172] 'applied index is now lower than readState.Index' (duration: 29.375776ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:22:19.632323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.915961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-r96zx\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-19T09:22:19.632395Z","caller":"traceutil/trace.go:171","msg":"trace[1571722611] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-r96zx; range_end:; response_count:1; response_revision:5266; }","duration":"535.044014ms","start":"2026-08-19T09:22:19.097331Z","end":"2026-08-19T09:22:19.632375Z","steps":["trace[1571722611] 'agreement among raft nodes before linearized reading' (duration: 534.823088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:19.632432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:22:19.097306Z","time spent":"535.116975ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7841,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-r96zx\" "} {"level":"warn","ts":"2026-08-19T09:22:19.632651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.953652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:22:19.632704Z","caller":"traceutil/trace.go:171","msg":"trace[906104445] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5266; }","duration":"346.065416ms","start":"2026-08-19T09:22:19.286624Z","end":"2026-08-19T09:22:19.632689Z","steps":["trace[906104445] 'agreement among raft nodes before linearized reading' (duration: 345.576042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:19.63274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:22:19.2866Z","time spent":"346.130007ms","remote":"127.0.0.1:48942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:22:19.632744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.21026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-19T09:22:19.632799Z","caller":"traceutil/trace.go:171","msg":"trace[985753287] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5267; }","duration":"263.315384ms","start":"2026-08-19T09:22:19.369471Z","end":"2026-08-19T09:22:19.632787Z","steps":["trace[985753287] 'agreement among raft nodes before linearized reading' (duration: 263.154159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:19.632791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.20755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g5bqd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-19T09:22:19.632861Z","caller":"traceutil/trace.go:171","msg":"trace[2065180861] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g5bqd; range_end:; response_count:1; response_revision:5266; }","duration":"648.317013ms","start":"2026-08-19T09:22:18.984527Z","end":"2026-08-19T09:22:19.632844Z","steps":["trace[2065180861] 'agreement among raft nodes before linearized reading' (duration: 647.670215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:19.632894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.812837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T09:22:19.632898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:22:18.984515Z","time spent":"648.374424ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-g5bqd\" "} {"level":"info","ts":"2026-08-19T09:22:19.632929Z","caller":"traceutil/trace.go:171","msg":"trace[1505212036] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5266; }","duration":"696.851418ms","start":"2026-08-19T09:22:18.936067Z","end":"2026-08-19T09:22:19.632918Z","steps":["trace[1505212036] 'agreement among raft nodes before linearized reading' (duration: 696.15343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:19.632958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:22:18.936017Z","time spent":"696.93222ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:22:19.632958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.295955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:22:19.633069Z","caller":"traceutil/trace.go:171","msg":"trace[1075580588] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:0; response_revision:5267; }","duration":"262.420798ms","start":"2026-08-19T09:22:19.370637Z","end":"2026-08-19T09:22:19.633058Z","steps":["trace[1075580588] 'agreement among raft nodes before linearized reading' (duration: 262.298885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:19.642365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.94874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-08-19T09:22:19.642452Z","caller":"traceutil/trace.go:171","msg":"trace[500525934] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5266; }","duration":"752.081543ms","start":"2026-08-19T09:22:18.890354Z","end":"2026-08-19T09:22:19.642435Z","steps":["trace[500525934] 'agreement among raft nodes before linearized reading' (duration: 741.957629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:22:19.642487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:22:18.890335Z","time spent":"752.141435ms","remote":"127.0.0.1:35146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225431,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-08-19T09:22:27.027065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.519627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-530e8421-6bfe-4ba8-a58e-0c0af34bb2be\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-19T09:22:27.027297Z","caller":"traceutil/trace.go:171","msg":"trace[1037067979] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-530e8421-6bfe-4ba8-a58e-0c0af34bb2be; range_end:; response_count:1; response_revision:5440; }","duration":"102.800695ms","start":"2026-08-19T09:22:26.924474Z","end":"2026-08-19T09:22:27.027275Z","steps":["trace[1037067979] 'range keys from in-memory index tree' (duration: 102.330082ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:23:37.69106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-08-19T09:23:37.757409Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"64.890489ms","hash":2241168179,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":33746944,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-19T09:23:37.757552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2241168179,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-08-19T09:28:21.983535Z","caller":"traceutil/trace.go:171","msg":"trace[1571795413] linearizableReadLoop","detail":"{readStateIndex:8461; appliedIndex:8460; }","duration":"371.659414ms","start":"2026-08-19T09:28:21.611849Z","end":"2026-08-19T09:28:21.983509Z","steps":["trace[1571795413] 'read index received' (duration: 371.48011ms)","trace[1571795413] 'applied index is now lower than readState.Index' (duration: 177.994µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:28:21.983778Z","caller":"traceutil/trace.go:171","msg":"trace[1249945464] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"600.648565ms","start":"2026-08-19T09:28:21.383118Z","end":"2026-08-19T09:28:21.983767Z","steps":["trace[1249945464] 'process raft request' (duration: 600.202203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:21.98389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:28:21.383096Z","time spent":"600.718588ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:28:21.984114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.24937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g5bqd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-19T09:28:21.984177Z","caller":"traceutil/trace.go:171","msg":"trace[198171345] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g5bqd; range_end:; response_count:1; response_revision:8174; }","duration":"372.362342ms","start":"2026-08-19T09:28:21.6118Z","end":"2026-08-19T09:28:21.984162Z","steps":["trace[198171345] 'agreement among raft nodes before linearized reading' (duration: 372.113085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:21.984251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:28:21.611779Z","time spent":"372.464365ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-g5bqd\" "} {"level":"info","ts":"2026-08-19T09:28:21.985343Z","caller":"traceutil/trace.go:171","msg":"trace[890914273] transaction","detail":"{read_only:false; response_revision:8175; number_of_response:1; }","duration":"321.03784ms","start":"2026-08-19T09:28:21.664266Z","end":"2026-08-19T09:28:21.985304Z","steps":["trace[890914273] 'process raft request' (duration: 320.875136ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:21.985369Z","caller":"traceutil/trace.go:171","msg":"trace[1422874590] transaction","detail":"{read_only:false; response_revision:8176; number_of_response:1; }","duration":"217.369558ms","start":"2026-08-19T09:28:21.767978Z","end":"2026-08-19T09:28:21.985348Z","steps":["trace[1422874590] 'process raft request' (duration: 217.258494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:21.985403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.440874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-19T09:28:21.985456Z","caller":"traceutil/trace.go:171","msg":"trace[1802530943] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8177; }","duration":"233.547216ms","start":"2026-08-19T09:28:21.751894Z","end":"2026-08-19T09:28:21.985442Z","steps":["trace[1802530943] 'agreement among raft nodes before linearized reading' (duration: 233.397983ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:21.985408Z","caller":"traceutil/trace.go:171","msg":"trace[1641449162] transaction","detail":"{read_only:false; response_revision:8177; number_of_response:1; }","duration":"172.767617ms","start":"2026-08-19T09:28:21.812613Z","end":"2026-08-19T09:28:21.985381Z","steps":["trace[1641449162] 'process raft request' (duration: 172.654134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:21.98579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.821194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-19T09:28:21.985554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:28:21.664246Z","time spent":"321.168574ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:28:21.985862Z","caller":"traceutil/trace.go:171","msg":"trace[392763794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8177; }","duration":"233.934147ms","start":"2026-08-19T09:28:21.751918Z","end":"2026-08-19T09:28:21.985852Z","steps":["trace[392763794] 'agreement among raft nodes before linearized reading' (duration: 233.637428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:34.511353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.527131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:28:34.511414Z","caller":"traceutil/trace.go:171","msg":"trace[236078001] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8242; }","duration":"134.636992ms","start":"2026-08-19T09:28:34.376761Z","end":"2026-08-19T09:28:34.511398Z","steps":["trace[236078001] 'count revisions from in-memory index tree' (duration: 134.419087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:34.511436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.362678ms","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-19T09:28:34.511526Z","caller":"traceutil/trace.go:171","msg":"trace[100348550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8242; }","duration":"161.4753ms","start":"2026-08-19T09:28:34.350033Z","end":"2026-08-19T09:28:34.511508Z","steps":["trace[100348550] 'range keys from in-memory index tree' (duration: 161.154762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:34.511572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.123608ms","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-19T09:28:34.511695Z","caller":"traceutil/trace.go:171","msg":"trace[531628055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8242; }","duration":"162.294833ms","start":"2026-08-19T09:28:34.349381Z","end":"2026-08-19T09:28:34.511676Z","steps":["trace[531628055] 'range keys from in-memory index tree' (duration: 161.776499ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:34.669518Z","caller":"traceutil/trace.go:171","msg":"trace[709770918] transaction","detail":"{read_only:false; response_revision:8244; number_of_response:1; }","duration":"153.108774ms","start":"2026-08-19T09:28:34.516383Z","end":"2026-08-19T09:28:34.669492Z","steps":["trace[709770918] 'process raft request' (duration: 109.508371ms)","trace[709770918] 'compare' (duration: 43.488ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:28:34.669689Z","caller":"traceutil/trace.go:171","msg":"trace[1808933086] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"153.207837ms","start":"2026-08-19T09:28:34.516471Z","end":"2026-08-19T09:28:34.669679Z","steps":["trace[1808933086] 'process raft request' (duration: 152.985571ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:34.670023Z","caller":"traceutil/trace.go:171","msg":"trace[1291376050] transaction","detail":"{read_only:false; response_revision:8246; number_of_response:1; }","duration":"153.521436ms","start":"2026-08-19T09:28:34.51649Z","end":"2026-08-19T09:28:34.670012Z","steps":["trace[1291376050] 'process raft request' (duration: 153.153256ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:37.697384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6064} {"level":"info","ts":"2026-08-19T09:28:37.79154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6064,"took":"92.299094ms","hash":1678484189,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":23310336,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-19T09:28:37.791629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1678484189,"revision":6064,"compact-revision":2970} {"level":"info","ts":"2026-08-19T09:29:04.332392Z","caller":"traceutil/trace.go:171","msg":"trace[1721975112] linearizableReadLoop","detail":"{readStateIndex:8753; appliedIndex:8751; }","duration":"134.368056ms","start":"2026-08-19T09:29:04.197999Z","end":"2026-08-19T09:29:04.332368Z","steps":["trace[1721975112] 'read index received' (duration: 14.343999ms)","trace[1721975112] 'applied index is now lower than readState.Index' (duration: 120.022867ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:29:04.332459Z","caller":"traceutil/trace.go:171","msg":"trace[1319330975] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"181.209206ms","start":"2026-08-19T09:29:04.151221Z","end":"2026-08-19T09:29:04.332431Z","steps":["trace[1319330975] 'process raft request' (duration: 135.62486ms)","trace[1319330975] 'compare' (duration: 45.373111ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:29:04.332654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.642233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-19T09:29:04.332706Z","caller":"traceutil/trace.go:171","msg":"trace[2096965187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8454; }","duration":"134.747296ms","start":"2026-08-19T09:29:04.197945Z","end":"2026-08-19T09:29:04.332693Z","steps":["trace[2096965187] 'agreement among raft nodes before linearized reading' (duration: 134.50735ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:29:14.394234Z","caller":"traceutil/trace.go:171","msg":"trace[796618701] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"204.456387ms","start":"2026-08-19T09:29:14.189746Z","end":"2026-08-19T09:29:14.394202Z","steps":["trace[796618701] 'process raft request' (duration: 204.296012ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:29:14.397883Z","caller":"traceutil/trace.go:171","msg":"trace[569947037] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"156.018493ms","start":"2026-08-19T09:29:14.241841Z","end":"2026-08-19T09:29:14.397859Z","steps":["trace[569947037] 'process raft request' (duration: 155.462258ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:29:14.904865Z","caller":"traceutil/trace.go:171","msg":"trace[784730914] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"102.020608ms","start":"2026-08-19T09:29:14.802799Z","end":"2026-08-19T09:29:14.904819Z","steps":["trace[784730914] 'process raft request' (duration: 101.625088ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:29:15.084595Z","caller":"traceutil/trace.go:171","msg":"trace[15177791] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"172.823508ms","start":"2026-08-19T09:29:14.911745Z","end":"2026-08-19T09:29:15.084568Z","steps":["trace[15177791] 'process raft request' (duration: 98.547864ms)","trace[15177791] 'compare' (duration: 74.153871ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:29:15.111014Z","caller":"traceutil/trace.go:171","msg":"trace[201991031] linearizableReadLoop","detail":"{readStateIndex:8861; appliedIndex:8858; }","duration":"191.847016ms","start":"2026-08-19T09:29:14.919146Z","end":"2026-08-19T09:29:15.110993Z","steps":["trace[201991031] 'read index received' (duration: 91.524024ms)","trace[201991031] 'applied index is now lower than readState.Index' (duration: 100.322322ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:29:15.111152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.985769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:29:15.111136Z","caller":"traceutil/trace.go:171","msg":"trace[1430607789] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"199.085841ms","start":"2026-08-19T09:29:14.912Z","end":"2026-08-19T09:29:15.111086Z","steps":["trace[1430607789] 'process raft request' (duration: 198.819463ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:29:15.111182Z","caller":"traceutil/trace.go:171","msg":"trace[1248779250] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8558; }","duration":"192.05943ms","start":"2026-08-19T09:29:14.919111Z","end":"2026-08-19T09:29:15.11117Z","steps":["trace[1248779250] 'agreement among raft nodes before linearized reading' (duration: 191.966647ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:29:15.111213Z","caller":"traceutil/trace.go:171","msg":"trace[907013289] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"199.076751ms","start":"2026-08-19T09:29:14.912129Z","end":"2026-08-19T09:29:15.111206Z","steps":["trace[907013289] 'process raft request' (duration: 198.824284ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:29:15.29159Z","caller":"traceutil/trace.go:171","msg":"trace[1593464986] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"168.508821ms","start":"2026-08-19T09:29:15.123052Z","end":"2026-08-19T09:29:15.291561Z","steps":["trace[1593464986] 'process raft request' (duration: 89.20526ms)","trace[1593464986] 'compare' (duration: 79.110007ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:29:15.315957Z","caller":"traceutil/trace.go:171","msg":"trace[2010738534] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"139.892406ms","start":"2026-08-19T09:29:15.176035Z","end":"2026-08-19T09:29:15.315927Z","steps":["trace[2010738534] 'process raft request' (duration: 139.707541ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:30:04.152565Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002483.wal"} {"level":"info","ts":"2026-08-19T09:30:27.709745Z","caller":"traceutil/trace.go:171","msg":"trace[717540740] linearizableReadLoop","detail":"{readStateIndex:9607; appliedIndex:9604; }","duration":"114.78623ms","start":"2026-08-19T09:30:27.594921Z","end":"2026-08-19T09:30:27.709707Z","steps":["trace[717540740] 'read index received' (duration: 48.154423ms)","trace[717540740] 'applied index is now lower than readState.Index' (duration: 66.630087ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:30:27.709967Z","caller":"traceutil/trace.go:171","msg":"trace[1098341876] transaction","detail":"{read_only:false; response_revision:9284; number_of_response:1; }","duration":"190.746249ms","start":"2026-08-19T09:30:27.519157Z","end":"2026-08-19T09:30:27.709904Z","steps":["trace[1098341876] 'process raft request' (duration: 190.342308ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:30:27.710017Z","caller":"traceutil/trace.go:171","msg":"trace[1741401636] transaction","detail":"{read_only:false; response_revision:9283; number_of_response:1; }","duration":"191.661395ms","start":"2026-08-19T09:30:27.518327Z","end":"2026-08-19T09:30:27.709988Z","steps":["trace[1741401636] 'process raft request' (duration: 124.727949ms)","trace[1741401636] 'compare' (duration: 66.036851ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:30:27.710012Z","caller":"traceutil/trace.go:171","msg":"trace[1384214357] transaction","detail":"{read_only:false; response_revision:9285; number_of_response:1; }","duration":"179.71162ms","start":"2026-08-19T09:30:27.530282Z","end":"2026-08-19T09:30:27.709993Z","steps":["trace[1384214357] 'process raft request' (duration: 179.341539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:30:27.710634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.705084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:30:27.710704Z","caller":"traceutil/trace.go:171","msg":"trace[1276753382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9285; }","duration":"115.818387ms","start":"2026-08-19T09:30:27.594862Z","end":"2026-08-19T09:30:27.71068Z","steps":["trace[1276753382] 'agreement among raft nodes before linearized reading' (duration: 115.100837ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:31:19.896243Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T09:31:19.902742Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-19T09:31:19.902857Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-19T09:32:22.20558Z","caller":"traceutil/trace.go:171","msg":"trace[538566404] transaction","detail":"{read_only:false; response_revision:10100; number_of_response:1; }","duration":"115.781096ms","start":"2026-08-19T09:32:22.089737Z","end":"2026-08-19T09:32:22.205518Z","steps":["trace[538566404] 'process raft request' (duration: 115.406645ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:33:37.703111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8266} {"level":"info","ts":"2026-08-19T09:33:37.778062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8266,"took":"73.193145ms","hash":530423366,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":25350144,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-19T09:33:37.778132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":530423366,"revision":8266,"compact-revision":6064} {"level":"info","ts":"2026-08-19T09:33:51.594072Z","caller":"traceutil/trace.go:171","msg":"trace[2118742713] transaction","detail":"{read_only:false; response_revision:11017; number_of_response:1; }","duration":"163.930869ms","start":"2026-08-19T09:33:51.430114Z","end":"2026-08-19T09:33:51.594045Z","steps":["trace[2118742713] 'process raft request' (duration: 163.769434ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:33:51.606214Z","caller":"traceutil/trace.go:171","msg":"trace[48319576] linearizableReadLoop","detail":"{readStateIndex:11391; appliedIndex:11389; }","duration":"118.396497ms","start":"2026-08-19T09:33:51.487774Z","end":"2026-08-19T09:33:51.60617Z","steps":["trace[48319576] 'read index received' (duration: 106.235435ms)","trace[48319576] 'applied index is now lower than readState.Index' (duration: 12.159852ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:33:51.60634Z","caller":"traceutil/trace.go:171","msg":"trace[703713261] transaction","detail":"{read_only:false; response_revision:11018; number_of_response:1; }","duration":"168.627987ms","start":"2026-08-19T09:33:51.437695Z","end":"2026-08-19T09:33:51.606323Z","steps":["trace[703713261] 'process raft request' (duration: 168.36708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:33:51.606415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.577162ms","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-19T09:33:51.606523Z","caller":"traceutil/trace.go:171","msg":"trace[1013977211] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:11018; }","duration":"118.764357ms","start":"2026-08-19T09:33:51.487719Z","end":"2026-08-19T09:33:51.606483Z","steps":["trace[1013977211] 'agreement among raft nodes before linearized reading' (duration: 118.594553ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:34:04.87501Z","caller":"traceutil/trace.go:171","msg":"trace[1281135789] linearizableReadLoop","detail":"{readStateIndex:11468; appliedIndex:11467; }","duration":"147.10587ms","start":"2026-08-19T09:34:04.72788Z","end":"2026-08-19T09:34:04.874986Z","steps":["trace[1281135789] 'read index received' (duration: 146.948845ms)","trace[1281135789] 'applied index is now lower than readState.Index' (duration: 155.335µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:34:04.875087Z","caller":"traceutil/trace.go:171","msg":"trace[1895632772] transaction","detail":"{read_only:false; response_revision:11093; number_of_response:1; }","duration":"185.816675ms","start":"2026-08-19T09:34:04.689246Z","end":"2026-08-19T09:34:04.875063Z","steps":["trace[1895632772] 'process raft request' (duration: 185.568048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:34:04.875182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.282984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:34:04.875223Z","caller":"traceutil/trace.go:171","msg":"trace[1317282373] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:11093; }","duration":"147.383788ms","start":"2026-08-19T09:34:04.727828Z","end":"2026-08-19T09:34:04.875212Z","steps":["trace[1317282373] 'agreement among raft nodes before linearized reading' (duration: 147.295715ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:34:05.004212Z","caller":"traceutil/trace.go:171","msg":"trace[1271921492] transaction","detail":"{read_only:false; response_revision:11095; number_of_response:1; }","duration":"125.398628ms","start":"2026-08-19T09:34:04.878777Z","end":"2026-08-19T09:34:05.004175Z","steps":["trace[1271921492] 'process raft request' (duration: 119.843818ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:38:37.709953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10757} {"level":"info","ts":"2026-08-19T09:38:37.788846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10757,"took":"76.43453ms","hash":3493827438,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":27418624,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-19T09:38:37.788902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3493827438,"revision":10757,"compact-revision":8266} {"level":"info","ts":"2026-08-19T09:39:53.006375Z","caller":"traceutil/trace.go:171","msg":"trace[1261495595] transaction","detail":"{read_only:false; response_revision:13476; number_of_response:1; }","duration":"137.911935ms","start":"2026-08-19T09:39:52.868435Z","end":"2026-08-19T09:39:53.006347Z","steps":["trace[1261495595] 'process raft request' (duration: 137.738011ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:34.395893Z","caller":"traceutil/trace.go:171","msg":"trace[1656293451] linearizableReadLoop","detail":"{readStateIndex:14180; appliedIndex:14179; }","duration":"171.906869ms","start":"2026-08-19T09:40:34.223963Z","end":"2026-08-19T09:40:34.39587Z","steps":["trace[1656293451] 'read index received' (duration: 171.636422ms)","trace[1656293451] 'applied index is now lower than readState.Index' (duration: 269.367µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:40:34.395987Z","caller":"traceutil/trace.go:171","msg":"trace[899166903] transaction","detail":"{read_only:false; response_revision:13713; number_of_response:1; }","duration":"179.981054ms","start":"2026-08-19T09:40:34.215967Z","end":"2026-08-19T09:40:34.395948Z","steps":["trace[899166903] 'process raft request' (duration: 179.737287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:34.396136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.799716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-19T09:40:34.396181Z","caller":"traceutil/trace.go:171","msg":"trace[13142014] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13713; }","duration":"167.879728ms","start":"2026-08-19T09:40:34.228288Z","end":"2026-08-19T09:40:34.396168Z","steps":["trace[13142014] 'agreement among raft nodes before linearized reading' (duration: 167.733674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:34.396128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.126395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-19T09:40:34.396288Z","caller":"traceutil/trace.go:171","msg":"trace[978659515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13713; }","duration":"172.336682ms","start":"2026-08-19T09:40:34.223933Z","end":"2026-08-19T09:40:34.39627Z","steps":["trace[978659515] 'agreement among raft nodes before linearized reading' (duration: 172.027043ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:43:37.717661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12953} {"level":"info","ts":"2026-08-19T09:43:37.798023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12953,"took":"77.355223ms","hash":1744864731,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":31051776,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-19T09:43:37.798103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1744864731,"revision":12953,"compact-revision":10757} {"level":"warn","ts":"2026-08-19T09:43:38.383346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.58346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-19T09:43:38.383447Z","caller":"traceutil/trace.go:171","msg":"trace[172377846] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15479; }","duration":"120.769497ms","start":"2026-08-19T09:43:38.262656Z","end":"2026-08-19T09:43:38.383425Z","steps":["trace[172377846] 'range keys from in-memory index tree' (duration: 120.422626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:47:54.716592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.506585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T09:47:54.716693Z","caller":"traceutil/trace.go:171","msg":"trace[1466616191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17502; }","duration":"122.661238ms","start":"2026-08-19T09:47:54.594011Z","end":"2026-08-19T09:47:54.716672Z","steps":["trace[1466616191] 'range keys from in-memory index tree' (duration: 122.426252ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:48:00.676212Z","caller":"traceutil/trace.go:171","msg":"trace[279691318] transaction","detail":"{read_only:false; response_revision:17536; number_of_response:1; }","duration":"218.578877ms","start":"2026-08-19T09:48:00.457581Z","end":"2026-08-19T09:48:00.67616Z","steps":["trace[279691318] 'process raft request' (duration: 218.379832ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:48:00.708215Z","caller":"traceutil/trace.go:171","msg":"trace[2080893097] transaction","detail":"{read_only:false; response_revision:17537; number_of_response:1; }","duration":"100.533813ms","start":"2026-08-19T09:48:00.607649Z","end":"2026-08-19T09:48:00.708183Z","steps":["trace[2080893097] 'process raft request' (duration: 100.356489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:48:01.156521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.915332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-19T09:48:01.156611Z","caller":"traceutil/trace.go:171","msg":"trace[157292107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17537; }","duration":"172.049357ms","start":"2026-08-19T09:48:00.984535Z","end":"2026-08-19T09:48:01.156585Z","steps":["trace[157292107] 'range keys from in-memory index tree' (duration: 171.717458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:48:01.156623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.960752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-19T09:48:01.156737Z","caller":"traceutil/trace.go:171","msg":"trace[1299668824] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:17537; }","duration":"406.127186ms","start":"2026-08-19T09:48:00.750582Z","end":"2026-08-19T09:48:01.156709Z","steps":["trace[1299668824] 'range keys from in-memory index tree' (duration: 405.669283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:48:01.15684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:48:00.750558Z","time spent":"406.26611ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-08-19T09:48:01.156645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.586145ms","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-19T09:48:01.156983Z","caller":"traceutil/trace.go:171","msg":"trace[82882320] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17537; }","duration":"278.974185ms","start":"2026-08-19T09:48:00.877994Z","end":"2026-08-19T09:48:01.156969Z","steps":["trace[82882320] 'range keys from in-memory index tree' (duration: 278.367258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:48:01.156999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.047882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-19T09:48:01.157077Z","caller":"traceutil/trace.go:171","msg":"trace[1234946155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17537; }","duration":"166.198366ms","start":"2026-08-19T09:48:00.990862Z","end":"2026-08-19T09:48:01.15706Z","steps":["trace[1234946155] 'range keys from in-memory index tree' (duration: 165.835456ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:48:01.223678Z","caller":"traceutil/trace.go:171","msg":"trace[1959588479] linearizableReadLoop","detail":"{readStateIndex:18127; appliedIndex:18126; }","duration":"152.668347ms","start":"2026-08-19T09:48:01.070983Z","end":"2026-08-19T09:48:01.223652Z","steps":["trace[1959588479] 'read index received' (duration: 152.489102ms)","trace[1959588479] 'applied index is now lower than readState.Index' (duration: 177.885µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:48:01.223803Z","caller":"traceutil/trace.go:171","msg":"trace[1078938756] transaction","detail":"{read_only:false; response_revision:17538; number_of_response:1; }","duration":"191.686573ms","start":"2026-08-19T09:48:01.032077Z","end":"2026-08-19T09:48:01.223764Z","steps":["trace[1078938756] 'process raft request' (duration: 191.386664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:48:01.223904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.897034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-19T09:48:01.223949Z","caller":"traceutil/trace.go:171","msg":"trace[403261643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17538; }","duration":"152.997406ms","start":"2026-08-19T09:48:01.07094Z","end":"2026-08-19T09:48:01.223937Z","steps":["trace[403261643] 'agreement among raft nodes before linearized reading' (duration: 152.832352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:48:01.240207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.828657ms","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-19T09:48:01.240286Z","caller":"traceutil/trace.go:171","msg":"trace[274600661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17540; }","duration":"161.956211ms","start":"2026-08-19T09:48:01.078311Z","end":"2026-08-19T09:48:01.240267Z","steps":["trace[274600661] 'agreement among raft nodes before linearized reading' (duration: 161.676343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:48:01.240325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.757171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-19T09:48:01.240382Z","caller":"traceutil/trace.go:171","msg":"trace[1190240192] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17540; }","duration":"144.842743ms","start":"2026-08-19T09:48:01.095522Z","end":"2026-08-19T09:48:01.240365Z","steps":["trace[1190240192] 'agreement among raft nodes before linearized reading' (duration: 144.674938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:48:01.240419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.448287ms","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-19T09:48:01.24044Z","caller":"traceutil/trace.go:171","msg":"trace[1635880926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17540; }","duration":"157.487229ms","start":"2026-08-19T09:48:01.082947Z","end":"2026-08-19T09:48:01.240434Z","steps":["trace[1635880926] 'agreement among raft nodes before linearized reading' (duration: 157.428078ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:48:37.724362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15473} {"level":"info","ts":"2026-08-19T09:48:37.821708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15473,"took":"93.829162ms","hash":1554152442,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":33787904,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-19T09:48:37.821803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1554152442,"revision":15473,"compact-revision":12953} {"level":"info","ts":"2026-08-19T09:51:28.344577Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T09:51:28.349597Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-19T09:51:28.349692Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-19T09:52:56.965511Z","caller":"traceutil/trace.go:171","msg":"trace[1566973178] transaction","detail":"{read_only:false; response_revision:19863; number_of_response:1; }","duration":"167.121293ms","start":"2026-08-19T09:52:56.79836Z","end":"2026-08-19T09:52:56.965481Z","steps":["trace[1566973178] 'process raft request' (duration: 166.008143ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:52:56.965557Z","caller":"traceutil/trace.go:171","msg":"trace[153590694] transaction","detail":"{read_only:false; response_revision:19864; number_of_response:1; }","duration":"166.386872ms","start":"2026-08-19T09:52:56.799127Z","end":"2026-08-19T09:52:56.965514Z","steps":["trace[153590694] 'process raft request' (duration: 166.147416ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:52:56.965512Z","caller":"traceutil/trace.go:171","msg":"trace[467154643] linearizableReadLoop","detail":"{readStateIndex:20531; appliedIndex:20530; }","duration":"166.591157ms","start":"2026-08-19T09:52:56.798879Z","end":"2026-08-19T09:52:56.96547Z","steps":["trace[467154643] 'read index received' (duration: 165.512568ms)","trace[467154643] 'applied index is now lower than readState.Index' (duration: 1.074359ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:52:56.965778Z","caller":"traceutil/trace.go:171","msg":"trace[1082122699] transaction","detail":"{read_only:false; response_revision:19865; number_of_response:1; }","duration":"166.012081ms","start":"2026-08-19T09:52:56.799736Z","end":"2026-08-19T09:52:56.965748Z","steps":["trace[1082122699] 'process raft request' (duration: 165.688573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:52:56.966035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.135982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-19T09:52:56.966117Z","caller":"traceutil/trace.go:171","msg":"trace[1487574876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19865; }","duration":"167.244236ms","start":"2026-08-19T09:52:56.798856Z","end":"2026-08-19T09:52:56.9661Z","steps":["trace[1487574876] 'agreement among raft nodes before linearized reading' (duration: 166.662749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:52:56.966562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.616546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-19T09:52:56.966607Z","caller":"traceutil/trace.go:171","msg":"trace[1534992160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19865; }","duration":"167.668167ms","start":"2026-08-19T09:52:56.798924Z","end":"2026-08-19T09:52:56.966592Z","steps":["trace[1534992160] 'agreement among raft nodes before linearized reading' (duration: 167.558294ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:53:02.554199Z","caller":"traceutil/trace.go:171","msg":"trace[1278972201] transaction","detail":"{read_only:false; response_revision:19893; number_of_response:1; }","duration":"163.921305ms","start":"2026-08-19T09:53:02.390251Z","end":"2026-08-19T09:53:02.554172Z","steps":["trace[1278972201] 'process raft request' (duration: 163.731629ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:53:02.638414Z","caller":"traceutil/trace.go:171","msg":"trace[272909491] transaction","detail":"{read_only:false; response_revision:19894; number_of_response:1; }","duration":"166.416353ms","start":"2026-08-19T09:53:02.471964Z","end":"2026-08-19T09:53:02.638381Z","steps":["trace[272909491] 'process raft request' (duration: 166.234118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:53:02.975815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.212374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-19T09:53:02.975899Z","caller":"traceutil/trace.go:171","msg":"trace[1038055967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19894; }","duration":"219.335626ms","start":"2026-08-19T09:53:02.756545Z","end":"2026-08-19T09:53:02.975881Z","steps":["trace[1038055967] 'range keys from in-memory index tree' (duration: 219.06412ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:53:03.160189Z","caller":"traceutil/trace.go:171","msg":"trace[1914421973] linearizableReadLoop","detail":"{readStateIndex:20564; appliedIndex:20563; }","duration":"170.944737ms","start":"2026-08-19T09:53:02.989221Z","end":"2026-08-19T09:53:03.160166Z","steps":["trace[1914421973] 'read index received' (duration: 170.71335ms)","trace[1914421973] 'applied index is now lower than readState.Index' (duration: 230.347µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:53:03.160304Z","caller":"traceutil/trace.go:171","msg":"trace[420289543] transaction","detail":"{read_only:false; response_revision:19895; number_of_response:1; }","duration":"179.678646ms","start":"2026-08-19T09:53:02.980615Z","end":"2026-08-19T09:53:03.160294Z","steps":["trace[420289543] 'process raft request' (duration: 179.391397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:53:03.16052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.281296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-19T09:53:03.16073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.935647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-19T09:53:03.160792Z","caller":"traceutil/trace.go:171","msg":"trace[1379655006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19895; }","duration":"152.020939ms","start":"2026-08-19T09:53:03.008752Z","end":"2026-08-19T09:53:03.160773Z","steps":["trace[1379655006] 'agreement among raft nodes before linearized reading' (duration: 151.839555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:53:03.160778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.456252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-19T09:53:03.160867Z","caller":"traceutil/trace.go:171","msg":"trace[397689186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19895; }","duration":"152.683059ms","start":"2026-08-19T09:53:03.008165Z","end":"2026-08-19T09:53:03.160848Z","steps":["trace[397689186] 'agreement among raft nodes before linearized reading' (duration: 152.36006ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:53:03.16056Z","caller":"traceutil/trace.go:171","msg":"trace[1543988572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19895; }","duration":"171.355517ms","start":"2026-08-19T09:53:02.989196Z","end":"2026-08-19T09:53:03.160551Z","steps":["trace[1543988572] 'agreement among raft nodes before linearized reading' (duration: 171.221114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:53:03.161135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.579448ms","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-19T09:53:03.161267Z","caller":"traceutil/trace.go:171","msg":"trace[1951554847] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19895; }","duration":"169.748175ms","start":"2026-08-19T09:53:02.991496Z","end":"2026-08-19T09:53:03.161244Z","steps":["trace[1951554847] 'agreement among raft nodes before linearized reading' (duration: 169.132378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:53:03.161679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.955595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-19T09:53:03.161804Z","caller":"traceutil/trace.go:171","msg":"trace[848236042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19895; }","duration":"172.139969ms","start":"2026-08-19T09:53:02.989638Z","end":"2026-08-19T09:53:03.161778Z","steps":["trace[848236042] 'agreement among raft nodes before linearized reading' (duration: 170.881215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:53:03.554776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.65408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T09:53:03.55517Z","caller":"traceutil/trace.go:171","msg":"trace[206185922] transaction","detail":"{read_only:false; response_revision:19898; number_of_response:1; }","duration":"388.194886ms","start":"2026-08-19T09:53:03.166952Z","end":"2026-08-19T09:53:03.555147Z","steps":["trace[206185922] 'process raft request' (duration: 388.047783ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:53:03.555177Z","caller":"traceutil/trace.go:171","msg":"trace[1783463199] transaction","detail":"{read_only:false; response_revision:19897; number_of_response:1; }","duration":"388.32643ms","start":"2026-08-19T09:53:03.166834Z","end":"2026-08-19T09:53:03.555161Z","steps":["trace[1783463199] 'process raft request' (duration: 388.075643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:53:03.555283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:53:03.166941Z","time spent":"388.292309ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:53:03.555335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:53:03.16681Z","time spent":"388.433383ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:53:03.555118Z","caller":"traceutil/trace.go:171","msg":"trace[1200725689] transaction","detail":"{read_only:false; response_revision:19896; number_of_response:1; }","duration":"389.088841ms","start":"2026-08-19T09:53:03.165938Z","end":"2026-08-19T09:53:03.555027Z","steps":["trace[1200725689] 'process raft request' (duration: 15.086082ms)","trace[1200725689] 'compare' (duration: 373.481085ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:53:03.555899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:53:03.165918Z","time spent":"389.928064ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:53:03.55553Z","caller":"traceutil/trace.go:171","msg":"trace[1383102409] transaction","detail":"{read_only:false; response_revision:19900; number_of_response:1; }","duration":"388.30888ms","start":"2026-08-19T09:53:03.1672Z","end":"2026-08-19T09:53:03.555508Z","steps":["trace[1383102409] 'process raft request' (duration: 388.120884ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:53:03.55551Z","caller":"traceutil/trace.go:171","msg":"trace[324560915] transaction","detail":"{read_only:false; response_revision:19899; number_of_response:1; }","duration":"388.443403ms","start":"2026-08-19T09:53:03.167035Z","end":"2026-08-19T09:53:03.555479Z","steps":["trace[324560915] 'process raft request' (duration: 388.061343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:53:03.556178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:53:03.167188Z","time spent":"388.845223ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:53:03.556417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:53:03.167028Z","time spent":"389.200684ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:53:03.850363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.86926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T09:53:03.850478Z","caller":"traceutil/trace.go:171","msg":"trace[373637862] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:19900; }","duration":"158.028804ms","start":"2026-08-19T09:53:03.692408Z","end":"2026-08-19T09:53:03.850437Z","steps":["trace[373637862] 'count revisions from in-memory index tree' (duration: 157.744726ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:53:04.058429Z","caller":"traceutil/trace.go:171","msg":"trace[1804629620] transaction","detail":"{read_only:false; response_revision:19901; number_of_response:1; }","duration":"287.155308ms","start":"2026-08-19T09:53:03.771244Z","end":"2026-08-19T09:53:04.058399Z","steps":["trace[1804629620] 'process raft request' (duration: 286.948542ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:53:37.738667Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18029} {"level":"info","ts":"2026-08-19T09:53:37.8498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18029,"took":"108.285165ms","hash":1166838574,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":30179328,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-19T09:53:37.849849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1166838574,"revision":18029,"compact-revision":15473} {"level":"info","ts":"2026-08-19T09:56:46.921782Z","caller":"traceutil/trace.go:171","msg":"trace[1660890326] linearizableReadLoop","detail":"{readStateIndex:23024; appliedIndex:23023; }","duration":"221.430677ms","start":"2026-08-19T09:56:46.700324Z","end":"2026-08-19T09:56:46.921755Z","steps":["trace[1660890326] 'read index received' (duration: 221.291373ms)","trace[1660890326] 'applied index is now lower than readState.Index' (duration: 137.994µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:56:46.921803Z","caller":"traceutil/trace.go:171","msg":"trace[738118629] transaction","detail":"{read_only:false; response_revision:22285; number_of_response:1; }","duration":"253.579444ms","start":"2026-08-19T09:56:46.668192Z","end":"2026-08-19T09:56:46.921772Z","steps":["trace[738118629] 'process raft request' (duration: 253.416999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:56:46.922112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.764156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-19T09:56:46.922204Z","caller":"traceutil/trace.go:171","msg":"trace[462759926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22285; }","duration":"221.907509ms","start":"2026-08-19T09:56:46.700274Z","end":"2026-08-19T09:56:46.922181Z","steps":["trace[462759926] 'agreement among raft nodes before linearized reading' (duration: 221.57879ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:56:47.050718Z","caller":"traceutil/trace.go:171","msg":"trace[492775263] linearizableReadLoop","detail":"{readStateIndex:23026; appliedIndex:23024; }","duration":"128.798401ms","start":"2026-08-19T09:56:46.921861Z","end":"2026-08-19T09:56:47.050659Z","steps":["trace[492775263] 'read index received' (duration: 122.003606ms)","trace[492775263] 'applied index is now lower than readState.Index' (duration: 6.792885ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:56:47.050963Z","caller":"traceutil/trace.go:171","msg":"trace[89399610] transaction","detail":"{read_only:false; response_revision:22288; number_of_response:1; }","duration":"125.178412ms","start":"2026-08-19T09:56:46.92576Z","end":"2026-08-19T09:56:47.050939Z","steps":["trace[89399610] 'process raft request' (duration: 125.027528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:56:47.050998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.495925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-19T09:56:47.051063Z","caller":"traceutil/trace.go:171","msg":"trace[172098071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22287; }","duration":"328.623379ms","start":"2026-08-19T09:56:46.722423Z","end":"2026-08-19T09:56:47.051046Z","steps":["trace[172098071] 'agreement among raft nodes before linearized reading' (duration: 328.388283ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:56:47.051101Z","caller":"traceutil/trace.go:171","msg":"trace[1044021264] transaction","detail":"{read_only:false; response_revision:22286; number_of_response:1; }","duration":"315.595334ms","start":"2026-08-19T09:56:46.73546Z","end":"2026-08-19T09:56:47.051055Z","steps":["trace[1044021264] 'process raft request' (duration: 308.213653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:56:47.05115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.257932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-19T09:56:47.051206Z","caller":"traceutil/trace.go:171","msg":"trace[1175290421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22288; }","duration":"309.353884ms","start":"2026-08-19T09:56:46.741837Z","end":"2026-08-19T09:56:47.05119Z","steps":["trace[1175290421] 'agreement among raft nodes before linearized reading' (duration: 309.20948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:56:47.051127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:56:46.722404Z","time spent":"328.707782ms","remote":"127.0.0.1:35344","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":"warn","ts":"2026-08-19T09:56:47.051252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:56:46.741815Z","time spent":"309.429066ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:56:47.051269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.86217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-19T09:56:47.05122Z","caller":"traceutil/trace.go:171","msg":"trace[523490066] transaction","detail":"{read_only:false; response_revision:22287; number_of_response:1; }","duration":"160.563888ms","start":"2026-08-19T09:56:46.890602Z","end":"2026-08-19T09:56:47.051166Z","steps":["trace[523490066] 'process raft request' (duration: 159.988773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:56:47.051282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:56:46.73544Z","time spent":"315.746169ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:56:47.051302Z","caller":"traceutil/trace.go:171","msg":"trace[2015159466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22288; }","duration":"327.937471ms","start":"2026-08-19T09:56:46.723355Z","end":"2026-08-19T09:56:47.051293Z","steps":["trace[2015159466] 'agreement among raft nodes before linearized reading' (duration: 327.779377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:56:47.051341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:56:46.723344Z","time spent":"327.990742ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:56:47.051331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.530881ms","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-19T09:56:47.051478Z","caller":"traceutil/trace.go:171","msg":"trace[1029545607] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22288; }","duration":"217.734267ms","start":"2026-08-19T09:56:46.833691Z","end":"2026-08-19T09:56:47.051425Z","steps":["trace[1029545607] 'agreement among raft nodes before linearized reading' (duration: 217.324396ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:58:37.744902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20294} {"level":"info","ts":"2026-08-19T09:58:37.855342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20294,"took":"106.216735ms","hash":1411629392,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":43368448,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-19T09:58:37.855392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1411629392,"revision":20294,"compact-revision":18029} {"level":"info","ts":"2026-08-19T10:03:37.751497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23393} {"level":"info","ts":"2026-08-19T10:03:37.885101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23393,"took":"129.349584ms","hash":2882841185,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-19T10:03:37.885175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2882841185,"revision":23393,"compact-revision":20294}