{"level":"warn","ts":"2026-08-12T04:53:48.609738Z","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-12T04:53:48.609855Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-12T04:53:48.609971Z","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-12T04:53:48.609994Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-12T04:53:48.610022Z","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-12T04:53:48.610745Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-08-12T04:53:48.610839Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-12T04:53:48.613721Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.600658ms"} {"level":"info","ts":"2026-08-12T04:53:48.619171Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-08-12T04:53:48.619262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T04:53:48.619286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-08-12T04:53:48.619305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T04:53:48.619335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-08-12T04:53:48.619391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-08-12T04:53:48.624661Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T04:53:48.626203Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T04:53:48.630685Z","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-12T04:53:48.632717Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T04:53:48.633631Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T04:53:48.633827Z","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-12T04:53:48.633872Z","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-12T04:53:48.633882Z","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-12T04:53:48.634154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-08-12T04:53:48.635905Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-12T04:53:48.637762Z","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-12T04:53:48.637876Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-12T04:53:48.637925Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-12T04:53:48.638142Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T04:53:48.6382Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T04:53:48.920299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T04:53:48.920355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T04:53:48.920393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-08-12T04:53:48.920473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-08-12T04:53:48.920497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-12T04:53:48.920515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-08-12T04:53:48.920531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-12T04:53:48.921614Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T04:53:48.922638Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T04:53:48.922702Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T04:53:48.922806Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T04:53:48.923173Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T04:53:48.923299Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T04:53:48.924957Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T04:53:48.925072Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T04:53:48.92512Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T04:53:48.926627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T04:53:48.927014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-08-12T04:54:05.444916Z","caller":"traceutil/trace.go:171","msg":"trace[431074845] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"469.272045ms","start":"2026-08-12T04:54:04.975618Z","end":"2026-08-12T04:54:05.44489Z","steps":["trace[431074845] 'read index received' (duration: 469.055179ms)","trace[431074845] 'applied index is now lower than readState.Index' (duration: 215.806µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T04:54:05.44511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.405504ms","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-12T04:54:05.4452Z","caller":"traceutil/trace.go:171","msg":"trace[2127342342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"320.561259ms","start":"2026-08-12T04:54:05.124624Z","end":"2026-08-12T04:54:05.445185Z","steps":["trace[2127342342] 'agreement among raft nodes before linearized reading' (duration: 320.428825ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:54:05.445142Z","caller":"traceutil/trace.go:171","msg":"trace[612085794] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"473.980698ms","start":"2026-08-12T04:54:04.970997Z","end":"2026-08-12T04:54:05.444978Z","steps":["trace[612085794] 'process raft request' (duration: 473.734522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:54:05.445332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.391951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-08-12T04:54:05.445243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:54:05.124602Z","time spent":"320.63088ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T04:54:05.445474Z","caller":"traceutil/trace.go:171","msg":"trace[888926459] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:409; }","duration":"318.580876ms","start":"2026-08-12T04:54:05.126867Z","end":"2026-08-12T04:54:05.445448Z","steps":["trace[888926459] 'agreement among raft nodes before linearized reading' (duration: 318.279578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:54:05.44554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:54:05.126704Z","time spent":"318.824922ms","remote":"127.0.0.1:53788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-08-12T04:54:05.445125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.44033ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T04:54:05.445809Z","caller":"traceutil/trace.go:171","msg":"trace[2113557477] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:409; }","duration":"470.187099ms","start":"2026-08-12T04:54:04.975605Z","end":"2026-08-12T04:54:05.445792Z","steps":["trace[2113557477] 'agreement among raft nodes before linearized reading' (duration: 469.408699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:54:05.446363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:54:04.970976Z","time spent":"474.41479ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T04:54:05.627616Z","caller":"traceutil/trace.go:171","msg":"trace[119275858] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"167.088657ms","start":"2026-08-12T04:54:05.460502Z","end":"2026-08-12T04:54:05.62759Z","steps":["trace[119275858] 'process raft request' (duration: 167.020755ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:54:05.627944Z","caller":"traceutil/trace.go:171","msg":"trace[264563828] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"177.53187ms","start":"2026-08-12T04:54:05.450354Z","end":"2026-08-12T04:54:05.627886Z","steps":["trace[264563828] 'process raft request' (duration: 125.234323ms)","trace[264563828] 'compare' (duration: 51.781883ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T04:54:27.168542Z","caller":"traceutil/trace.go:171","msg":"trace[1748074960] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"206.947379ms","start":"2026-08-12T04:54:26.961539Z","end":"2026-08-12T04:54:27.168487Z","steps":["trace[1748074960] 'process raft request' (duration: 113.378693ms)","trace[1748074960] 'compare' (duration: 93.303949ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T04:54:27.16869Z","caller":"traceutil/trace.go:171","msg":"trace[1796541977] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"207.086913ms","start":"2026-08-12T04:54:26.961555Z","end":"2026-08-12T04:54:27.168642Z","steps":["trace[1796541977] 'process raft request' (duration: 206.866967ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:54:27.168816Z","caller":"traceutil/trace.go:171","msg":"trace[1691203683] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"206.771965ms","start":"2026-08-12T04:54:26.961989Z","end":"2026-08-12T04:54:27.168761Z","steps":["trace[1691203683] 'process raft request' (duration: 206.528158ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:54:27.169207Z","caller":"traceutil/trace.go:171","msg":"trace[11281384] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"111.126585ms","start":"2026-08-12T04:54:27.058063Z","end":"2026-08-12T04:54:27.169189Z","steps":["trace[11281384] 'process raft request' (duration: 110.639182ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:54:33.334321Z","caller":"traceutil/trace.go:171","msg":"trace[756875568] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"115.181681ms","start":"2026-08-12T04:54:33.219108Z","end":"2026-08-12T04:54:33.33429Z","steps":["trace[756875568] 'process raft request' (duration: 114.838592ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:54:33.850111Z","caller":"traceutil/trace.go:171","msg":"trace[1319052698] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"245.657091ms","start":"2026-08-12T04:54:33.604431Z","end":"2026-08-12T04:54:33.850088Z","steps":["trace[1319052698] 'read index received' (duration: 243.015772ms)","trace[1319052698] 'applied index is now lower than readState.Index' (duration: 2.640089ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T04:54:33.850176Z","caller":"traceutil/trace.go:171","msg":"trace[1161261025] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"412.646175ms","start":"2026-08-12T04:54:33.437496Z","end":"2026-08-12T04:54:33.850142Z","steps":["trace[1161261025] 'process raft request' (duration: 410.070498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:54:33.850349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.902827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-12T04:54:33.850376Z","caller":"traceutil/trace.go:171","msg":"trace[923524116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"246.001489ms","start":"2026-08-12T04:54:33.604369Z","end":"2026-08-12T04:54:33.85037Z","steps":["trace[923524116] 'agreement among raft nodes before linearized reading' (duration: 245.818434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:54:33.850375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:54:33.437469Z","time spent":"412.787689ms","remote":"127.0.0.1:54078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T04:54:49.250299Z","caller":"traceutil/trace.go:171","msg":"trace[1440556480] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"113.563598ms","start":"2026-08-12T04:54:49.136713Z","end":"2026-08-12T04:54:49.250276Z","steps":["trace[1440556480] 'read index received' (duration: 113.400974ms)","trace[1440556480] 'applied index is now lower than readState.Index' (duration: 161.444µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T04:54:49.250355Z","caller":"traceutil/trace.go:171","msg":"trace[604722713] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"134.24941ms","start":"2026-08-12T04:54:49.11608Z","end":"2026-08-12T04:54:49.25033Z","steps":["trace[604722713] 'process raft request' (duration: 134.048594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:54:49.25043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.697941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T04:54:49.250463Z","caller":"traceutil/trace.go:171","msg":"trace[1947196642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"113.783174ms","start":"2026-08-12T04:54:49.13667Z","end":"2026-08-12T04:54:49.250453Z","steps":["trace[1947196642] 'agreement among raft nodes before linearized reading' (duration: 113.713612ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:55:38.755856Z","caller":"traceutil/trace.go:171","msg":"trace[1811385571] linearizableReadLoop","detail":"{readStateIndex:1497; appliedIndex:1496; }","duration":"108.974855ms","start":"2026-08-12T04:55:38.64685Z","end":"2026-08-12T04:55:38.755825Z","steps":["trace[1811385571] 'read index received' (duration: 108.739299ms)","trace[1811385571] 'applied index is now lower than readState.Index' (duration: 234.446µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T04:55:38.755929Z","caller":"traceutil/trace.go:171","msg":"trace[418646714] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"113.933753ms","start":"2026-08-12T04:55:38.641944Z","end":"2026-08-12T04:55:38.755878Z","steps":["trace[418646714] 'process raft request' (duration: 113.715817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:55:38.756205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.326455ms","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-12T04:55:38.756326Z","caller":"traceutil/trace.go:171","msg":"trace[683762844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1454; }","duration":"109.5557ms","start":"2026-08-12T04:55:38.646742Z","end":"2026-08-12T04:55:38.756297Z","steps":["trace[683762844] 'agreement among raft nodes before linearized reading' (duration: 109.224932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:04.980158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.332877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T04:56:04.980321Z","caller":"traceutil/trace.go:171","msg":"trace[1923777998] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"100.309368ms","start":"2026-08-12T04:56:04.879989Z","end":"2026-08-12T04:56:04.980299Z","steps":["trace[1923777998] 'read index received' (duration: 28.29µs)","trace[1923777998] 'applied index is now lower than readState.Index' (duration: 100.279548ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T04:56:04.98036Z","caller":"traceutil/trace.go:171","msg":"trace[15288499] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"254.669452ms","start":"2026-08-12T04:56:04.725657Z","end":"2026-08-12T04:56:04.980326Z","steps":["trace[15288499] 'process raft request' (duration: 50.847462ms)","trace[15288499] 'compare' (duration: 203.112401ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T04:56:04.980607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.578625ms","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-12T04:56:04.980671Z","caller":"traceutil/trace.go:171","msg":"trace[1689201755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1875; }","duration":"100.700128ms","start":"2026-08-12T04:56:04.879956Z","end":"2026-08-12T04:56:04.980656Z","steps":["trace[1689201755] 'agreement among raft nodes before linearized reading' (duration: 100.450052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:05.350856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.628353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T04:56:05.350935Z","caller":"traceutil/trace.go:171","msg":"trace[963824178] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"361.009715ms","start":"2026-08-12T04:56:04.989913Z","end":"2026-08-12T04:56:05.350923Z","steps":["trace[963824178] 'read index received' (duration: 202.250819ms)","trace[963824178] 'applied index is now lower than readState.Index' (duration: 158.758026ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T04:56:05.35098Z","caller":"traceutil/trace.go:171","msg":"trace[1951698132] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"361.625922ms","start":"2026-08-12T04:56:04.989346Z","end":"2026-08-12T04:56:05.350972Z","steps":["trace[1951698132] 'process raft request' (duration: 202.809403ms)","trace[1951698132] 'compare' (duration: 158.47113ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T04:56:05.351017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:56:04.989316Z","time spent":"361.681133ms","remote":"127.0.0.1:53876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T04:56:05.351303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.329243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d115d3b-4b43-41dc-aa6c-11a0081e3edf\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-12T04:56:05.351416Z","caller":"traceutil/trace.go:171","msg":"trace[1290871491] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d115d3b-4b43-41dc-aa6c-11a0081e3edf; range_end:; response_count:1; response_revision:1876; }","duration":"361.519428ms","start":"2026-08-12T04:56:04.989872Z","end":"2026-08-12T04:56:05.351392Z","steps":["trace[1290871491] 'agreement among raft nodes before linearized reading' (duration: 361.142079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:05.35148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:56:04.989862Z","time spent":"361.60802ms","remote":"127.0.0.1:53788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d115d3b-4b43-41dc-aa6c-11a0081e3edf\" "} {"level":"info","ts":"2026-08-12T04:56:05.387366Z","caller":"traceutil/trace.go:171","msg":"trace[999295215] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"396.785498ms","start":"2026-08-12T04:56:04.990564Z","end":"2026-08-12T04:56:05.387349Z","steps":["trace[999295215] 'process raft request' (duration: 396.627834ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:56:05.387384Z","caller":"traceutil/trace.go:171","msg":"trace[1269874027] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"397.079866ms","start":"2026-08-12T04:56:04.990259Z","end":"2026-08-12T04:56:05.387339Z","steps":["trace[1269874027] 'process raft request' (duration: 396.895231ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:56:05.387476Z","caller":"traceutil/trace.go:171","msg":"trace[1356000081] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"396.631535ms","start":"2026-08-12T04:56:04.990814Z","end":"2026-08-12T04:56:05.387446Z","steps":["trace[1356000081] 'process raft request' (duration: 396.431869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:05.387489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:56:04.990555Z","time spent":"396.870661ms","remote":"127.0.0.1:53778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T04:56:05.387631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:56:04.990779Z","time spent":"396.756318ms","remote":"127.0.0.1:53778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T04:56:05.387616Z","caller":"traceutil/trace.go:171","msg":"trace[830486589] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"397.399854ms","start":"2026-08-12T04:56:04.990183Z","end":"2026-08-12T04:56:05.387583Z","steps":["trace[830486589] 'process raft request' (duration: 396.892581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:05.387673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:56:04.990246Z","time spent":"397.264161ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T04:56:05.387796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:56:04.990168Z","time spent":"397.528218ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T04:56:05.387965Z","caller":"traceutil/trace.go:171","msg":"trace[1067687922] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"396.317037ms","start":"2026-08-12T04:56:04.991635Z","end":"2026-08-12T04:56:05.387952Z","steps":["trace[1067687922] 'process raft request' (duration: 395.645139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:05.388013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T04:56:04.991626Z","time spent":"396.364558ms","remote":"127.0.0.1:53876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T04:56:05.388023Z","caller":"traceutil/trace.go:171","msg":"trace[345855731] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"270.085109ms","start":"2026-08-12T04:56:05.11792Z","end":"2026-08-12T04:56:05.388006Z","steps":["trace[345855731] 'process raft request' (duration: 269.878924ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:56:05.38814Z","caller":"traceutil/trace.go:171","msg":"trace[1530137085] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"246.329776ms","start":"2026-08-12T04:56:05.141796Z","end":"2026-08-12T04:56:05.388126Z","steps":["trace[1530137085] 'process raft request' (duration: 246.157072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:05.38827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.35247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T04:56:05.388369Z","caller":"traceutil/trace.go:171","msg":"trace[184279802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1883; }","duration":"250.509634ms","start":"2026-08-12T04:56:05.137839Z","end":"2026-08-12T04:56:05.388348Z","steps":["trace[184279802] 'agreement among raft nodes before linearized reading' (duration: 250.287018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:05.388442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.590904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-12T04:56:05.388542Z","caller":"traceutil/trace.go:171","msg":"trace[1927881706] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1883; }","duration":"179.772488ms","start":"2026-08-12T04:56:05.208749Z","end":"2026-08-12T04:56:05.388521Z","steps":["trace[1927881706] 'agreement among raft nodes before linearized reading' (duration: 179.385268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:56:49.004684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.8479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-12T04:56:49.004812Z","caller":"traceutil/trace.go:171","msg":"trace[746728695] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2169; }","duration":"143.999125ms","start":"2026-08-12T04:56:48.860779Z","end":"2026-08-12T04:56:49.004778Z","steps":["trace[746728695] 'range keys from in-memory index tree' (duration: 143.702197ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:57:48.013794Z","caller":"traceutil/trace.go:171","msg":"trace[51952171] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"135.287159ms","start":"2026-08-12T04:57:47.878471Z","end":"2026-08-12T04:57:48.013759Z","steps":["trace[51952171] 'process raft request' (duration: 135.079374ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:57:48.033734Z","caller":"traceutil/trace.go:171","msg":"trace[339341875] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"152.579953ms","start":"2026-08-12T04:57:47.881128Z","end":"2026-08-12T04:57:48.033708Z","steps":["trace[339341875] 'process raft request' (duration: 152.384358ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:57:48.03395Z","caller":"traceutil/trace.go:171","msg":"trace[1979099705] linearizableReadLoop","detail":"{readStateIndex:2757; appliedIndex:2755; }","duration":"149.538306ms","start":"2026-08-12T04:57:47.884365Z","end":"2026-08-12T04:57:48.033903Z","steps":["trace[1979099705] 'read index received' (duration: 129.238066ms)","trace[1979099705] 'applied index is now lower than readState.Index' (duration: 20.29682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T04:57:48.034211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.823924ms","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-12T04:57:48.034268Z","caller":"traceutil/trace.go:171","msg":"trace[2093565660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2658; }","duration":"149.939427ms","start":"2026-08-12T04:57:47.884313Z","end":"2026-08-12T04:57:48.034252Z","steps":["trace[2093565660] 'agreement among raft nodes before linearized reading' (duration: 149.714761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:57:48.034305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.693629ms","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-12T04:57:48.03439Z","caller":"traceutil/trace.go:171","msg":"trace[957595433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2658; }","duration":"137.830292ms","start":"2026-08-12T04:57:47.89654Z","end":"2026-08-12T04:57:48.03437Z","steps":["trace[957595433] 'agreement among raft nodes before linearized reading' (duration: 137.594997ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:58:22.342077Z","caller":"traceutil/trace.go:171","msg":"trace[1245528126] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"149.225832ms","start":"2026-08-12T04:58:22.19281Z","end":"2026-08-12T04:58:22.342036Z","steps":["trace[1245528126] 'process raft request' (duration: 149.001736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:58:22.342195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.514833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T04:58:22.342264Z","caller":"traceutil/trace.go:171","msg":"trace[1104831568] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2862; }","duration":"140.678237ms","start":"2026-08-12T04:58:22.201575Z","end":"2026-08-12T04:58:22.342253Z","steps":["trace[1104831568] 'agreement among raft nodes before linearized reading' (duration: 140.544164ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:58:22.342063Z","caller":"traceutil/trace.go:171","msg":"trace[1550486021] linearizableReadLoop","detail":"{readStateIndex:2969; appliedIndex:2968; }","duration":"140.341109ms","start":"2026-08-12T04:58:22.201658Z","end":"2026-08-12T04:58:22.341999Z","steps":["trace[1550486021] 'read index received' (duration: 140.239296ms)","trace[1550486021] 'applied index is now lower than readState.Index' (duration: 100.173µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T04:58:22.372525Z","caller":"traceutil/trace.go:171","msg":"trace[2041534934] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"125.241273ms","start":"2026-08-12T04:58:22.247257Z","end":"2026-08-12T04:58:22.372498Z","steps":["trace[2041534934] 'process raft request' (duration: 125.154091ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T04:58:22.37261Z","caller":"traceutil/trace.go:171","msg":"trace[214389409] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"125.733755ms","start":"2026-08-12T04:58:22.246852Z","end":"2026-08-12T04:58:22.372586Z","steps":["trace[214389409] 'process raft request' (duration: 125.416397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T04:58:22.37306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.063124ms","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-12T04:58:22.373149Z","caller":"traceutil/trace.go:171","msg":"trace[1990767729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2864; }","duration":"118.624629ms","start":"2026-08-12T04:58:22.2545Z","end":"2026-08-12T04:58:22.373124Z","steps":["trace[1990767729] 'agreement among raft nodes before linearized reading' (duration: 117.962062ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:00:59.376028Z","caller":"traceutil/trace.go:171","msg":"trace[2105383408] transaction","detail":"{read_only:false; response_revision:3895; number_of_response:1; }","duration":"163.01902ms","start":"2026-08-12T05:00:59.212976Z","end":"2026-08-12T05:00:59.375995Z","steps":["trace[2105383408] 'process raft request' (duration: 152.093911ms)","trace[2105383408] 'compare' (duration: 10.538308ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:00:59.422632Z","caller":"traceutil/trace.go:171","msg":"trace[341184313] transaction","detail":"{read_only:false; response_revision:3896; number_of_response:1; }","duration":"110.06614ms","start":"2026-08-12T05:00:59.312542Z","end":"2026-08-12T05:00:59.422608Z","steps":["trace[341184313] 'process raft request' (duration: 109.973257ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:02:26.331994Z","caller":"traceutil/trace.go:171","msg":"trace[1692262845] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"152.46191ms","start":"2026-08-12T05:02:26.179474Z","end":"2026-08-12T05:02:26.331936Z","steps":["trace[1692262845] 'process raft request' (duration: 152.133442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:02:29.322324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.18626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T05:02:29.322424Z","caller":"traceutil/trace.go:171","msg":"trace[72560827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5091; }","duration":"184.348554ms","start":"2026-08-12T05:02:29.138055Z","end":"2026-08-12T05:02:29.322403Z","steps":["trace[72560827] 'range keys from in-memory index tree' (duration: 184.093047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:02:29.322694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.430335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-12T05:02:29.323097Z","caller":"traceutil/trace.go:171","msg":"trace[555949652] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5091; }","duration":"131.913217ms","start":"2026-08-12T05:02:29.191159Z","end":"2026-08-12T05:02:29.323072Z","steps":["trace[555949652] 'range keys from in-memory index tree' (duration: 131.048405ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:02:43.059646Z","caller":"traceutil/trace.go:171","msg":"trace[794020139] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"116.52189ms","start":"2026-08-12T05:02:42.9431Z","end":"2026-08-12T05:02:43.059622Z","steps":["trace[794020139] 'process raft request' (duration: 116.312055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:02:43.686942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.372206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T05:02:43.687201Z","caller":"traceutil/trace.go:171","msg":"trace[199189501] linearizableReadLoop","detail":"{readStateIndex:5586; appliedIndex:5583; }","duration":"115.759311ms","start":"2026-08-12T05:02:43.571426Z","end":"2026-08-12T05:02:43.687185Z","steps":["trace[199189501] 'read index received' (duration: 38.153809ms)","trace[199189501] 'applied index is now lower than readState.Index' (duration: 77.604102ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:02:43.687176Z","caller":"traceutil/trace.go:171","msg":"trace[275851718] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"234.233053ms","start":"2026-08-12T05:02:43.452899Z","end":"2026-08-12T05:02:43.687132Z","steps":["trace[275851718] 'process raft request' (duration: 58.478986ms)","trace[275851718] 'compare' (duration: 175.066488ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:02:43.687299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.867314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T05:02:43.687323Z","caller":"traceutil/trace.go:171","msg":"trace[2113455734] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5387; }","duration":"115.896184ms","start":"2026-08-12T05:02:43.571418Z","end":"2026-08-12T05:02:43.687314Z","steps":["trace[2113455734] 'agreement among raft nodes before linearized reading' (duration: 115.817592ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:02:43.687603Z","caller":"traceutil/trace.go:171","msg":"trace[700688717] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"222.953075ms","start":"2026-08-12T05:02:43.464635Z","end":"2026-08-12T05:02:43.687588Z","steps":["trace[700688717] 'process raft request' (duration: 222.515013ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:02:43.687726Z","caller":"traceutil/trace.go:171","msg":"trace[223540750] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"223.14315ms","start":"2026-08-12T05:02:43.464571Z","end":"2026-08-12T05:02:43.687714Z","steps":["trace[223540750] 'process raft request' (duration: 222.489183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:02:51.264012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.559363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T05:02:51.264147Z","caller":"traceutil/trace.go:171","msg":"trace[1501037593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5552; }","duration":"127.764398ms","start":"2026-08-12T05:02:51.13636Z","end":"2026-08-12T05:02:51.264124Z","steps":["trace[1501037593] 'range keys from in-memory index tree' (duration: 127.450089ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:02:51.435465Z","caller":"traceutil/trace.go:171","msg":"trace[1618548385] linearizableReadLoop","detail":"{readStateIndex:5756; appliedIndex:5755; }","duration":"120.618549ms","start":"2026-08-12T05:02:51.314814Z","end":"2026-08-12T05:02:51.435433Z","steps":["trace[1618548385] 'read index received' (duration: 120.394553ms)","trace[1618548385] 'applied index is now lower than readState.Index' (duration: 222.616µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:02:51.435592Z","caller":"traceutil/trace.go:171","msg":"trace[2003853336] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"160.036971ms","start":"2026-08-12T05:02:51.275502Z","end":"2026-08-12T05:02:51.435539Z","steps":["trace[2003853336] 'process raft request' (duration: 159.698373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:02:51.435741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.925787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f3ba3dd-5574-4a09-89e8-d4328cc53d85\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-12T05:02:51.435802Z","caller":"traceutil/trace.go:171","msg":"trace[1520022839] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f3ba3dd-5574-4a09-89e8-d4328cc53d85; range_end:; response_count:1; response_revision:5553; }","duration":"121.009359ms","start":"2026-08-12T05:02:51.314774Z","end":"2026-08-12T05:02:51.435783Z","steps":["trace[1520022839] 'agreement among raft nodes before linearized reading' (duration: 120.795663ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:02:51.581219Z","caller":"traceutil/trace.go:171","msg":"trace[327754649] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"131.419174ms","start":"2026-08-12T05:02:51.449766Z","end":"2026-08-12T05:02:51.581185Z","steps":["trace[327754649] 'process raft request' (duration: 131.336932ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:02:51.58123Z","caller":"traceutil/trace.go:171","msg":"trace[884866459] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"137.68385ms","start":"2026-08-12T05:02:51.443511Z","end":"2026-08-12T05:02:51.581195Z","steps":["trace[884866459] 'process raft request' (duration: 137.409173ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:03:49.11107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-08-12T05:03:49.197534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"84.417871ms","hash":3897700537,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":31596544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T05:03:49.19762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3897700537,"revision":3014,"compact-revision":-1} {"level":"info","ts":"2026-08-12T05:08:49.118801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6087} {"level":"info","ts":"2026-08-12T05:08:49.215724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6087,"took":"94.412864ms","hash":3295931226,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":21716992,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-12T05:08:49.215809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3295931226,"revision":6087,"compact-revision":3014} {"level":"info","ts":"2026-08-12T05:09:08.98719Z","caller":"traceutil/trace.go:171","msg":"trace[1127671476] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"286.581858ms","start":"2026-08-12T05:09:08.700569Z","end":"2026-08-12T05:09:08.987151Z","steps":["trace[1127671476] 'process raft request' (duration: 286.351912ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:09:09.124726Z","caller":"traceutil/trace.go:171","msg":"trace[152306026] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"423.740381ms","start":"2026-08-12T05:09:08.700958Z","end":"2026-08-12T05:09:09.124699Z","steps":["trace[152306026] 'process raft request' (duration: 422.393676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:09:09.12491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:09:08.70094Z","time spent":"423.878395ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:09:09.125095Z","caller":"traceutil/trace.go:171","msg":"trace[266233381] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"330.884658ms","start":"2026-08-12T05:09:08.794171Z","end":"2026-08-12T05:09:09.125056Z","steps":["trace[266233381] 'process raft request' (duration: 330.440157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:09:09.125288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:09:08.79413Z","time spent":"331.063564ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:09:46.145536Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022fe.wal"} {"level":"info","ts":"2026-08-12T05:09:50.35475Z","caller":"traceutil/trace.go:171","msg":"trace[739218765] linearizableReadLoop","detail":"{readStateIndex:8992; appliedIndex:8991; }","duration":"216.805126ms","start":"2026-08-12T05:09:50.137909Z","end":"2026-08-12T05:09:50.354714Z","steps":["trace[739218765] 'read index received' (duration: 216.619801ms)","trace[739218765] 'applied index is now lower than readState.Index' (duration: 184.315µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:09:50.354905Z","caller":"traceutil/trace.go:171","msg":"trace[1725397697] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"246.844159ms","start":"2026-08-12T05:09:50.108007Z","end":"2026-08-12T05:09:50.354852Z","steps":["trace[1725397697] 'process raft request' (duration: 246.521351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:09:50.355034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.057312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T05:09:50.3551Z","caller":"traceutil/trace.go:171","msg":"trace[402054341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8687; }","duration":"217.214247ms","start":"2026-08-12T05:09:50.137875Z","end":"2026-08-12T05:09:50.355089Z","steps":["trace[402054341] 'agreement among raft nodes before linearized reading' (duration: 217.039702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:09:50.355137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.553316ms","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-12T05:09:50.355181Z","caller":"traceutil/trace.go:171","msg":"trace[1237706170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8687; }","duration":"203.638898ms","start":"2026-08-12T05:09:50.15153Z","end":"2026-08-12T05:09:50.355169Z","steps":["trace[1237706170] 'agreement among raft nodes before linearized reading' (duration: 203.397791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:09:50.552134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.220557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T05:09:50.552255Z","caller":"traceutil/trace.go:171","msg":"trace[2004019454] linearizableReadLoop","detail":"{readStateIndex:8993; appliedIndex:8992; }","duration":"182.723895ms","start":"2026-08-12T05:09:50.369511Z","end":"2026-08-12T05:09:50.552235Z","steps":["trace[2004019454] 'read index received' (duration: 18.318554ms)","trace[2004019454] 'applied index is now lower than readState.Index' (duration: 164.403081ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:09:50.552313Z","caller":"traceutil/trace.go:171","msg":"trace[481438497] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"192.410541ms","start":"2026-08-12T05:09:50.359866Z","end":"2026-08-12T05:09:50.552276Z","steps":["trace[481438497] 'process raft request' (duration: 27.955188ms)","trace[481438497] 'compare' (duration: 163.990511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:09:50.552483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.975943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7hf6k\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-08-12T05:09:50.55251Z","caller":"traceutil/trace.go:171","msg":"trace[885509910] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7hf6k; range_end:; response_count:1; response_revision:8688; }","duration":"183.048484ms","start":"2026-08-12T05:09:50.369453Z","end":"2026-08-12T05:09:50.552501Z","steps":["trace[885509910] 'agreement among raft nodes before linearized reading' (duration: 182.89087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:09:50.55267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.816328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-77d84b5-8ggsl.18caf69948d62cff\" ","response":"range_response_count:1 size:1126"} {"level":"info","ts":"2026-08-12T05:09:50.552742Z","caller":"traceutil/trace.go:171","msg":"trace[2048476910] range","detail":"{range_begin:/registry/events/openstack/barbican-api-77d84b5-8ggsl.18caf69948d62cff; range_end:; response_count:1; response_revision:8688; }","duration":"132.92409ms","start":"2026-08-12T05:09:50.419796Z","end":"2026-08-12T05:09:50.552721Z","steps":["trace[2048476910] 'agreement among raft nodes before linearized reading' (duration: 132.732955ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:10:03.577086Z","caller":"traceutil/trace.go:171","msg":"trace[2038443929] linearizableReadLoop","detail":"{readStateIndex:9111; appliedIndex:9110; }","duration":"158.338942ms","start":"2026-08-12T05:10:03.418718Z","end":"2026-08-12T05:10:03.577057Z","steps":["trace[2038443929] 'read index received' (duration: 158.127446ms)","trace[2038443929] 'applied index is now lower than readState.Index' (duration: 210.446µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:10:03.57746Z","caller":"traceutil/trace.go:171","msg":"trace[1879395290] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"162.763468ms","start":"2026-08-12T05:10:03.414682Z","end":"2026-08-12T05:10:03.577445Z","steps":["trace[1879395290] 'process raft request' (duration: 162.230994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:10:03.577857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.121372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-12T05:10:03.578253Z","caller":"traceutil/trace.go:171","msg":"trace[922322863] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8799; }","duration":"159.586174ms","start":"2026-08-12T05:10:03.418654Z","end":"2026-08-12T05:10:03.57824Z","steps":["trace[922322863] 'agreement among raft nodes before linearized reading' (duration: 159.05396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:10:03.578095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.682899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T05:10:03.578444Z","caller":"traceutil/trace.go:171","msg":"trace[2001556289] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8799; }","duration":"129.125821ms","start":"2026-08-12T05:10:03.449278Z","end":"2026-08-12T05:10:03.578404Z","steps":["trace[2001556289] 'agreement among raft nodes before linearized reading' (duration: 128.661948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:10:03.763805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.546783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-12T05:10:03.763896Z","caller":"traceutil/trace.go:171","msg":"trace[1582604320] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8799; }","duration":"181.714048ms","start":"2026-08-12T05:10:03.582163Z","end":"2026-08-12T05:10:03.763877Z","steps":["trace[1582604320] 'range keys from in-memory index tree' (duration: 181.293267ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:10:03.79222Z","caller":"traceutil/trace.go:171","msg":"trace[401326393] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"110.482388ms","start":"2026-08-12T05:10:03.681696Z","end":"2026-08-12T05:10:03.792179Z","steps":["trace[401326393] 'process raft request' (duration: 110.21511ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:10:04.768893Z","caller":"traceutil/trace.go:171","msg":"trace[1783747368] linearizableReadLoop","detail":"{readStateIndex:9121; appliedIndex:9120; }","duration":"110.908369ms","start":"2026-08-12T05:10:04.657946Z","end":"2026-08-12T05:10:04.768854Z","steps":["trace[1783747368] 'read index received' (duration: 64.720729ms)","trace[1783747368] 'applied index is now lower than readState.Index' (duration: 46.18549ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:10:04.76956Z","caller":"traceutil/trace.go:171","msg":"trace[273767753] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"114.543815ms","start":"2026-08-12T05:10:04.654426Z","end":"2026-08-12T05:10:04.76897Z","steps":["trace[273767753] 'process raft request' (duration: 68.225092ms)","trace[273767753] 'compare' (duration: 45.880791ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:10:04.769879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.898495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:227426"} {"level":"info","ts":"2026-08-12T05:10:04.769935Z","caller":"traceutil/trace.go:171","msg":"trace[493901292] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8809; }","duration":"111.996678ms","start":"2026-08-12T05:10:04.657925Z","end":"2026-08-12T05:10:04.769922Z","steps":["trace[493901292] 'agreement among raft nodes before linearized reading' (duration: 111.086694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:10:04.796654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.151217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-12T05:10:04.796746Z","caller":"traceutil/trace.go:171","msg":"trace[1771939088] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:8810; }","duration":"133.26767ms","start":"2026-08-12T05:10:04.663454Z","end":"2026-08-12T05:10:04.796722Z","steps":["trace[1771939088] 'agreement among raft nodes before linearized reading' (duration: 133.024713ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:10:04.796766Z","caller":"traceutil/trace.go:171","msg":"trace[1407943823] transaction","detail":"{read_only:false; response_revision:8810; number_of_response:1; }","duration":"104.327585ms","start":"2026-08-12T05:10:04.692419Z","end":"2026-08-12T05:10:04.796747Z","steps":["trace[1407943823] 'process raft request' (duration: 103.975146ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:11:35.169519Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T05:11:35.176545Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-12T05:11:35.176659Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-12T05:13:49.125968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8131} {"level":"info","ts":"2026-08-12T05:13:49.193412Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8131,"took":"64.434802ms","hash":775372396,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":28323840,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-12T05:13:49.193491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":775372396,"revision":8131,"compact-revision":6087} {"level":"info","ts":"2026-08-12T05:16:41.699161Z","caller":"traceutil/trace.go:171","msg":"trace[645165845] transaction","detail":"{read_only:false; response_revision:11884; number_of_response:1; }","duration":"114.00955ms","start":"2026-08-12T05:16:41.585118Z","end":"2026-08-12T05:16:41.699127Z","steps":["trace[645165845] 'process raft request' (duration: 91.084417ms)","trace[645165845] 'compare' (duration: 22.328718ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:17:04.070506Z","caller":"traceutil/trace.go:171","msg":"trace[160811087] transaction","detail":"{read_only:false; response_revision:12007; number_of_response:1; }","duration":"116.210479ms","start":"2026-08-12T05:17:03.954269Z","end":"2026-08-12T05:17:04.07048Z","steps":["trace[160811087] 'process raft request' (duration: 116.045175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:17:05.234035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.18395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T05:17:05.23413Z","caller":"traceutil/trace.go:171","msg":"trace[992586433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12010; }","duration":"110.307093ms","start":"2026-08-12T05:17:05.1238Z","end":"2026-08-12T05:17:05.234107Z","steps":["trace[992586433] 'range keys from in-memory index tree' (duration: 110.146168ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:18:49.133036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10539} {"level":"info","ts":"2026-08-12T05:18:49.219147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10539,"took":"82.698027ms","hash":358855797,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":26316800,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-12T05:18:49.21921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":358855797,"revision":10539,"compact-revision":8131} {"level":"warn","ts":"2026-08-12T05:23:33.183511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.55829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-12T05:23:33.183643Z","caller":"traceutil/trace.go:171","msg":"trace[187030358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14604; }","duration":"148.706724ms","start":"2026-08-12T05:23:33.034911Z","end":"2026-08-12T05:23:33.183618Z","steps":["trace[187030358] 'range keys from in-memory index tree' (duration: 148.114349ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:23:49.143178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12700} {"level":"info","ts":"2026-08-12T05:23:49.224502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12700,"took":"78.452594ms","hash":3766504713,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":27074560,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-12T05:23:49.224586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3766504713,"revision":12700,"compact-revision":10539} {"level":"info","ts":"2026-08-12T05:24:09.191735Z","caller":"traceutil/trace.go:171","msg":"trace[1876577224] transaction","detail":"{read_only:false; response_revision:14800; number_of_response:1; }","duration":"119.981028ms","start":"2026-08-12T05:24:09.071728Z","end":"2026-08-12T05:24:09.191709Z","steps":["trace[1876577224] 'process raft request' (duration: 119.823534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:24:09.625334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.13191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7hf6k\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-08-12T05:24:09.625458Z","caller":"traceutil/trace.go:171","msg":"trace[1159872258] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7hf6k; range_end:; response_count:1; response_revision:14806; }","duration":"104.297865ms","start":"2026-08-12T05:24:09.521113Z","end":"2026-08-12T05:24:09.625411Z","steps":["trace[1159872258] 'range keys from in-memory index tree' (duration: 103.945686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:24:09.625451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.8437ms","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-12T05:24:09.625594Z","caller":"traceutil/trace.go:171","msg":"trace[1425573107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14806; }","duration":"246.052495ms","start":"2026-08-12T05:24:09.379513Z","end":"2026-08-12T05:24:09.625566Z","steps":["trace[1425573107] 'range keys from in-memory index tree' (duration: 245.643695ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:24:09.772683Z","caller":"traceutil/trace.go:171","msg":"trace[2866873] transaction","detail":"{read_only:false; response_revision:14807; number_of_response:1; }","duration":"142.557411ms","start":"2026-08-12T05:24:09.630098Z","end":"2026-08-12T05:24:09.772656Z","steps":["trace[2866873] 'process raft request' (duration: 142.357996ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:24:09.775982Z","caller":"traceutil/trace.go:171","msg":"trace[1960122949] transaction","detail":"{read_only:false; response_revision:14808; number_of_response:1; }","duration":"140.155288ms","start":"2026-08-12T05:24:09.635792Z","end":"2026-08-12T05:24:09.775947Z","steps":["trace[1960122949] 'process raft request' (duration: 139.979404ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:24:32.959456Z","caller":"traceutil/trace.go:171","msg":"trace[88930605] transaction","detail":"{read_only:false; response_revision:15124; number_of_response:1; }","duration":"293.890135ms","start":"2026-08-12T05:24:32.665538Z","end":"2026-08-12T05:24:32.959428Z","steps":["trace[88930605] 'process raft request' (duration: 293.68782ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:25:25.372685Z","caller":"traceutil/trace.go:171","msg":"trace[659456631] transaction","detail":"{read_only:false; response_revision:15754; number_of_response:1; }","duration":"100.640309ms","start":"2026-08-12T05:25:25.271988Z","end":"2026-08-12T05:25:25.372628Z","steps":["trace[659456631] 'process raft request' (duration: 100.354322ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:25:53.988524Z","caller":"traceutil/trace.go:171","msg":"trace[1517942237] transaction","detail":"{read_only:false; response_revision:15909; number_of_response:1; }","duration":"103.991977ms","start":"2026-08-12T05:25:53.884504Z","end":"2026-08-12T05:25:53.988496Z","steps":["trace[1517942237] 'process raft request' (duration: 102.386965ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:26:00.574667Z","caller":"traceutil/trace.go:171","msg":"trace[1095368643] transaction","detail":"{read_only:false; response_revision:15941; number_of_response:1; }","duration":"146.571027ms","start":"2026-08-12T05:26:00.428071Z","end":"2026-08-12T05:26:00.574642Z","steps":["trace[1095368643] 'process raft request' (duration: 146.339911ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:26:00.71232Z","caller":"traceutil/trace.go:171","msg":"trace[279708149] transaction","detail":"{read_only:false; response_revision:15942; number_of_response:1; }","duration":"131.764408ms","start":"2026-08-12T05:26:00.580517Z","end":"2026-08-12T05:26:00.712282Z","steps":["trace[279708149] 'process raft request' (duration: 47.12694ms)","trace[279708149] 'compare' (duration: 83.380105ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:28:49.149871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14688} {"level":"info","ts":"2026-08-12T05:28:49.231258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14688,"took":"77.075795ms","hash":1252287061,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":31236096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T05:28:49.231357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1252287061,"revision":14688,"compact-revision":12700} {"level":"warn","ts":"2026-08-12T05:31:56.892257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.087353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:31:56.8924Z","caller":"traceutil/trace.go:171","msg":"trace[1609884556] linearizableReadLoop","detail":"{readStateIndex:19195; appliedIndex:19194; }","duration":"114.102581ms","start":"2026-08-12T05:31:56.778275Z","end":"2026-08-12T05:31:56.892378Z","steps":["trace[1609884556] 'read index received' (duration: 44.981µs)","trace[1609884556] 'applied index is now lower than readState.Index' (duration: 114.05568ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:31:56.892703Z","caller":"traceutil/trace.go:171","msg":"trace[1671911266] transaction","detail":"{read_only:false; response_revision:18561; number_of_response:1; }","duration":"198.166011ms","start":"2026-08-12T05:31:56.694495Z","end":"2026-08-12T05:31:56.892661Z","steps":["trace[1671911266] 'process raft request' (duration: 25.554164ms)","trace[1671911266] 'compare' (duration: 171.931699ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:31:56.892737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.412279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-12T05:31:56.892753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.963038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:31:56.892867Z","caller":"traceutil/trace.go:171","msg":"trace[1247529600] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:18561; }","duration":"114.601765ms","start":"2026-08-12T05:31:56.778242Z","end":"2026-08-12T05:31:56.892844Z","steps":["trace[1247529600] 'agreement among raft nodes before linearized reading' (duration: 114.379589ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:31:56.89288Z","caller":"traceutil/trace.go:171","msg":"trace[1293095898] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:18561; }","duration":"114.164813ms","start":"2026-08-12T05:31:56.778691Z","end":"2026-08-12T05:31:56.892856Z","steps":["trace[1293095898] 'agreement among raft nodes before linearized reading' (duration: 113.956098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:32:02.721556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.765896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ql696\" ","response":"range_response_count:1 size:20668"} {"level":"info","ts":"2026-08-12T05:32:02.721658Z","caller":"traceutil/trace.go:171","msg":"trace[993916967] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-ql696; range_end:; response_count:1; response_revision:18593; }","duration":"104.92545ms","start":"2026-08-12T05:32:02.616706Z","end":"2026-08-12T05:32:02.721631Z","steps":["trace[993916967] 'range keys from in-memory index tree' (duration: 104.520509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:32:03.141617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.491953ms","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-12T05:32:03.141822Z","caller":"traceutil/trace.go:171","msg":"trace[2136469683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18595; }","duration":"225.78122ms","start":"2026-08-12T05:32:02.916014Z","end":"2026-08-12T05:32:03.141795Z","steps":["trace[2136469683] 'range keys from in-memory index tree' (duration: 225.255247ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:32:03.269129Z","caller":"traceutil/trace.go:171","msg":"trace[2133309530] transaction","detail":"{read_only:false; response_revision:18596; number_of_response:1; }","duration":"121.99601ms","start":"2026-08-12T05:32:03.147103Z","end":"2026-08-12T05:32:03.269099Z","steps":["trace[2133309530] 'process raft request' (duration: 121.801175ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:32:03.275102Z","caller":"traceutil/trace.go:171","msg":"trace[415198808] transaction","detail":"{read_only:false; response_revision:18597; number_of_response:1; }","duration":"127.732602ms","start":"2026-08-12T05:32:03.147353Z","end":"2026-08-12T05:32:03.275085Z","steps":["trace[415198808] 'process raft request' (duration: 127.491525ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:32:03.275572Z","caller":"traceutil/trace.go:171","msg":"trace[1125225575] transaction","detail":"{read_only:false; response_revision:18598; number_of_response:1; }","duration":"105.722271ms","start":"2026-08-12T05:32:03.169836Z","end":"2026-08-12T05:32:03.275559Z","steps":["trace[1125225575] 'process raft request' (duration: 105.124105ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:32:03.275836Z","caller":"traceutil/trace.go:171","msg":"trace[1347846575] transaction","detail":"{read_only:false; response_revision:18600; number_of_response:1; }","duration":"103.01138ms","start":"2026-08-12T05:32:03.172755Z","end":"2026-08-12T05:32:03.275766Z","steps":["trace[1347846575] 'process raft request' (duration: 102.722712ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:32:03.276147Z","caller":"traceutil/trace.go:171","msg":"trace[1427848455] transaction","detail":"{read_only:false; response_revision:18599; number_of_response:1; }","duration":"103.453941ms","start":"2026-08-12T05:32:03.172677Z","end":"2026-08-12T05:32:03.276131Z","steps":["trace[1427848455] 'process raft request' (duration: 102.693821ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:33:11.883002Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T05:33:11.888601Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-12T05:33:11.888736Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-12T05:33:49.157925Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17144} {"level":"info","ts":"2026-08-12T05:33:49.258107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17144,"took":"96.280252ms","hash":1803905740,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":33529856,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T05:33:49.258187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1803905740,"revision":17144,"compact-revision":14688} {"level":"info","ts":"2026-08-12T05:38:25.36993Z","caller":"traceutil/trace.go:171","msg":"trace[1351190557] transaction","detail":"{read_only:false; response_revision:21469; number_of_response:1; }","duration":"105.415223ms","start":"2026-08-12T05:38:25.264485Z","end":"2026-08-12T05:38:25.3699Z","steps":["trace[1351190557] 'process raft request' (duration: 104.92555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:38:25.553356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.327588ms","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-12T05:38:25.553478Z","caller":"traceutil/trace.go:171","msg":"trace[407983692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21469; }","duration":"103.529244ms","start":"2026-08-12T05:38:25.449922Z","end":"2026-08-12T05:38:25.553452Z","steps":["trace[407983692] 'range keys from in-memory index tree' (duration: 103.040581ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:38:25.689202Z","caller":"traceutil/trace.go:171","msg":"trace[926162216] transaction","detail":"{read_only:false; response_revision:21470; number_of_response:1; }","duration":"130.29535ms","start":"2026-08-12T05:38:25.558853Z","end":"2026-08-12T05:38:25.689149Z","steps":["trace[926162216] 'process raft request' (duration: 130.027933ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:38:26.034607Z","caller":"traceutil/trace.go:171","msg":"trace[632907281] linearizableReadLoop","detail":"{readStateIndex:22205; appliedIndex:22204; }","duration":"234.203564ms","start":"2026-08-12T05:38:25.800371Z","end":"2026-08-12T05:38:26.034575Z","steps":["trace[632907281] 'read index received' (duration: 233.968238ms)","trace[632907281] 'applied index is now lower than readState.Index' (duration: 234.116µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:38:26.03509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.686387ms","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-12T05:38:26.035146Z","caller":"traceutil/trace.go:171","msg":"trace[1245881596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21471; }","duration":"234.78969ms","start":"2026-08-12T05:38:25.800341Z","end":"2026-08-12T05:38:26.035131Z","steps":["trace[1245881596] 'agreement among raft nodes before linearized reading' (duration: 234.351908ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:38:26.035647Z","caller":"traceutil/trace.go:171","msg":"trace[604902320] transaction","detail":"{read_only:false; response_revision:21471; number_of_response:1; }","duration":"296.401287ms","start":"2026-08-12T05:38:25.739232Z","end":"2026-08-12T05:38:26.035633Z","steps":["trace[604902320] 'process raft request' (duration: 295.174665ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:38:26.223706Z","caller":"traceutil/trace.go:171","msg":"trace[1095721075] linearizableReadLoop","detail":"{readStateIndex:22206; appliedIndex:22205; }","duration":"157.147069ms","start":"2026-08-12T05:38:26.066531Z","end":"2026-08-12T05:38:26.223678Z","steps":["trace[1095721075] 'read index received' (duration: 116.603999ms)","trace[1095721075] 'applied index is now lower than readState.Index' (duration: 40.54141ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:38:26.223968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.399666ms","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-12T05:38:26.224173Z","caller":"traceutil/trace.go:171","msg":"trace[882506386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21472; }","duration":"157.700104ms","start":"2026-08-12T05:38:26.066459Z","end":"2026-08-12T05:38:26.224159Z","steps":["trace[882506386] 'agreement among raft nodes before linearized reading' (duration: 157.339494ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:38:26.223828Z","caller":"traceutil/trace.go:171","msg":"trace[223682439] transaction","detail":"{read_only:false; response_revision:21472; number_of_response:1; }","duration":"183.981748ms","start":"2026-08-12T05:38:26.039805Z","end":"2026-08-12T05:38:26.223787Z","steps":["trace[223682439] 'process raft request' (duration: 143.399067ms)","trace[223682439] 'compare' (duration: 40.315004ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:38:26.521554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.865401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:38:26.522084Z","caller":"traceutil/trace.go:171","msg":"trace[438289893] linearizableReadLoop","detail":"{readStateIndex:22208; appliedIndex:22206; }","duration":"275.216637ms","start":"2026-08-12T05:38:26.246845Z","end":"2026-08-12T05:38:26.522061Z","steps":["trace[438289893] 'read index received' (duration: 86.72719ms)","trace[438289893] 'applied index is now lower than readState.Index' (duration: 188.488217ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:38:26.522096Z","caller":"traceutil/trace.go:171","msg":"trace[41152335] transaction","detail":"{read_only:false; response_revision:21473; number_of_response:1; }","duration":"294.073765ms","start":"2026-08-12T05:38:26.227979Z","end":"2026-08-12T05:38:26.522053Z","steps":["trace[41152335] 'process raft request' (duration: 105.592378ms)","trace[41152335] 'compare' (duration: 187.715037ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:38:26.522298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.401242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:38:26.522364Z","caller":"traceutil/trace.go:171","msg":"trace[227852757] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:21474; }","duration":"275.530446ms","start":"2026-08-12T05:38:26.246817Z","end":"2026-08-12T05:38:26.522348Z","steps":["trace[227852757] 'agreement among raft nodes before linearized reading' (duration: 275.364491ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:38:26.522389Z","caller":"traceutil/trace.go:171","msg":"trace[4793259] transaction","detail":"{read_only:false; response_revision:21474; number_of_response:1; }","duration":"284.890863ms","start":"2026-08-12T05:38:26.237193Z","end":"2026-08-12T05:38:26.522083Z","steps":["trace[4793259] 'process raft request' (duration: 284.78509ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:38:27.20846Z","caller":"traceutil/trace.go:171","msg":"trace[1704306492] transaction","detail":"{read_only:false; response_revision:21476; number_of_response:1; }","duration":"319.410144ms","start":"2026-08-12T05:38:26.889026Z","end":"2026-08-12T05:38:27.208436Z","steps":["trace[1704306492] 'process raft request' (duration: 250.399422ms)","trace[1704306492] 'compare' (duration: 68.679663ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:38:27.208601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:38:26.889001Z","time spent":"319.528697ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:38:27.208865Z","caller":"traceutil/trace.go:171","msg":"trace[1434743446] transaction","detail":"{read_only:false; response_revision:21477; number_of_response:1; }","duration":"307.681964ms","start":"2026-08-12T05:38:26.9011Z","end":"2026-08-12T05:38:27.208782Z","steps":["trace[1434743446] 'process raft request' (duration: 307.245452ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:38:27.20897Z","caller":"traceutil/trace.go:171","msg":"trace[427129279] transaction","detail":"{read_only:false; response_revision:21478; number_of_response:1; }","duration":"305.127297ms","start":"2026-08-12T05:38:26.903801Z","end":"2026-08-12T05:38:27.208929Z","steps":["trace[427129279] 'process raft request' (duration: 304.894141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:38:27.209126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:38:26.90107Z","time spent":"307.91949ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:38:27.209219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:38:26.903771Z","time spent":"305.311182ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:38:27.209493Z","caller":"traceutil/trace.go:171","msg":"trace[1084188917] transaction","detail":"{read_only:false; response_revision:21479; number_of_response:1; }","duration":"304.128061ms","start":"2026-08-12T05:38:26.905322Z","end":"2026-08-12T05:38:27.20945Z","steps":["trace[1084188917] 'process raft request' (duration: 303.542735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:38:27.209954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:38:26.905292Z","time spent":"304.352567ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:38:49.166636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19641} {"level":"info","ts":"2026-08-12T05:38:49.292088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19641,"took":"120.78297ms","hash":3025378206,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":28598272,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-12T05:38:49.292188Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3025378206,"revision":19641,"compact-revision":17144} {"level":"info","ts":"2026-08-12T05:43:49.17353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21594} {"level":"info","ts":"2026-08-12T05:43:49.279529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21594,"took":"99.829545ms","hash":2823127688,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":43106304,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-12T05:43:49.279642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2823127688,"revision":21594,"compact-revision":19641} {"level":"info","ts":"2026-08-12T05:45:49.382968Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000689f.wal"} {"level":"info","ts":"2026-08-12T05:46:15.260866Z","caller":"traceutil/trace.go:171","msg":"trace[1608044303] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"186.297456ms","start":"2026-08-12T05:46:15.074542Z","end":"2026-08-12T05:46:15.260839Z","steps":["trace[1608044303] 'process raft request' (duration: 186.237245ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:15.260926Z","caller":"traceutil/trace.go:171","msg":"trace[819928447] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"192.007775ms","start":"2026-08-12T05:46:15.068852Z","end":"2026-08-12T05:46:15.26086Z","steps":["trace[819928447] 'process raft request' (duration: 191.762308ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:15.260985Z","caller":"traceutil/trace.go:171","msg":"trace[2077630020] linearizableReadLoop","detail":"{readStateIndex:27106; appliedIndex:27102; }","duration":"123.934553ms","start":"2026-08-12T05:46:15.137018Z","end":"2026-08-12T05:46:15.260952Z","steps":["trace[2077630020] 'read index received' (duration: 123.509162ms)","trace[2077630020] 'applied index is now lower than readState.Index' (duration: 422.631µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:15.261023Z","caller":"traceutil/trace.go:171","msg":"trace[2003677913] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"170.424401ms","start":"2026-08-12T05:46:15.090591Z","end":"2026-08-12T05:46:15.261015Z","steps":["trace[2003677913] 'process raft request' (duration: 170.217875ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:15.260945Z","caller":"traceutil/trace.go:171","msg":"trace[1108950976] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"188.580986ms","start":"2026-08-12T05:46:15.072289Z","end":"2026-08-12T05:46:15.26087Z","steps":["trace[1108950976] 'process raft request' (duration: 188.462093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:15.261163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.130419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:15.261185Z","caller":"traceutil/trace.go:171","msg":"trace[68466064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26241; }","duration":"124.209801ms","start":"2026-08-12T05:46:15.136968Z","end":"2026-08-12T05:46:15.261178Z","steps":["trace[68466064] 'agreement among raft nodes before linearized reading' (duration: 124.120359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:17.751857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.03011ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T05:46:17.752384Z","caller":"traceutil/trace.go:171","msg":"trace[796267501] linearizableReadLoop","detail":"{readStateIndex:27123; appliedIndex:27122; }","duration":"194.757137ms","start":"2026-08-12T05:46:17.557551Z","end":"2026-08-12T05:46:17.752308Z","steps":["trace[796267501] 'read index received' (duration: 58.193023ms)","trace[796267501] 'applied index is now lower than readState.Index' (duration: 136.562954ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:17.752743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.188459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T05:46:17.752773Z","caller":"traceutil/trace.go:171","msg":"trace[405498119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26258; }","duration":"195.262501ms","start":"2026-08-12T05:46:17.557503Z","end":"2026-08-12T05:46:17.752765Z","steps":["trace[405498119] 'agreement among raft nodes before linearized reading' (duration: 195.128897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:17.752891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.738685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:17.752912Z","caller":"traceutil/trace.go:171","msg":"trace[1854075214] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:26258; }","duration":"163.783847ms","start":"2026-08-12T05:46:17.58912Z","end":"2026-08-12T05:46:17.752904Z","steps":["trace[1854075214] 'agreement among raft nodes before linearized reading' (duration: 163.745926ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:17.983572Z","caller":"traceutil/trace.go:171","msg":"trace[223067050] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"225.561613ms","start":"2026-08-12T05:46:17.757953Z","end":"2026-08-12T05:46:17.983515Z","steps":["trace[223067050] 'process raft request' (duration: 218.797236ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:17.995442Z","caller":"traceutil/trace.go:171","msg":"trace[616036448] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"137.770005ms","start":"2026-08-12T05:46:17.857638Z","end":"2026-08-12T05:46:17.995408Z","steps":["trace[616036448] 'process raft request' (duration: 137.54551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:18.223653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.908679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:18.223745Z","caller":"traceutil/trace.go:171","msg":"trace[2012617500] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:26260; }","duration":"125.062813ms","start":"2026-08-12T05:46:18.098664Z","end":"2026-08-12T05:46:18.223727Z","steps":["trace[2012617500] 'count revisions from in-memory index tree' (duration: 124.730415ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:18.915848Z","caller":"traceutil/trace.go:171","msg":"trace[1585743018] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"147.129631ms","start":"2026-08-12T05:46:18.768688Z","end":"2026-08-12T05:46:18.915818Z","steps":["trace[1585743018] 'process raft request' (duration: 146.865554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:19.228078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.426624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:46:19.228325Z","caller":"traceutil/trace.go:171","msg":"trace[1948402202] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"308.302449ms","start":"2026-08-12T05:46:18.920003Z","end":"2026-08-12T05:46:19.228305Z","steps":["trace[1948402202] 'process raft request' (duration: 308.201587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:19.228391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:18.919971Z","time spent":"308.389632ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:19.228872Z","caller":"traceutil/trace.go:171","msg":"trace[763367364] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"331.907737ms","start":"2026-08-12T05:46:18.896948Z","end":"2026-08-12T05:46:19.228855Z","steps":["trace[763367364] 'process raft request' (duration: 174.593889ms)","trace[763367364] 'compare' (duration: 156.155207ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:19.228947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:18.896921Z","time spent":"331.992669ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:19.530436Z","caller":"traceutil/trace.go:171","msg":"trace[950133252] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"356.162341ms","start":"2026-08-12T05:46:19.17423Z","end":"2026-08-12T05:46:19.530392Z","steps":["trace[950133252] 'process raft request' (duration: 355.889164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:19.530709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:19.174198Z","time spent":"356.364557ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:19.565677Z","caller":"traceutil/trace.go:171","msg":"trace[436415845] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"291.5623ms","start":"2026-08-12T05:46:19.274092Z","end":"2026-08-12T05:46:19.565654Z","steps":["trace[436415845] 'process raft request' (duration: 291.239862ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:19.565663Z","caller":"traceutil/trace.go:171","msg":"trace[48956510] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"289.568379ms","start":"2026-08-12T05:46:19.276062Z","end":"2026-08-12T05:46:19.565631Z","steps":["trace[48956510] 'process raft request' (duration: 289.477576ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:19.565947Z","caller":"traceutil/trace.go:171","msg":"trace[7100893] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"289.671242ms","start":"2026-08-12T05:46:19.276262Z","end":"2026-08-12T05:46:19.565934Z","steps":["trace[7100893] 'process raft request' (duration: 289.327983ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:19.5661Z","caller":"traceutil/trace.go:171","msg":"trace[808412256] linearizableReadLoop","detail":"{readStateIndex:27135; appliedIndex:27130; }","duration":"176.807887ms","start":"2026-08-12T05:46:19.389258Z","end":"2026-08-12T05:46:19.566066Z","steps":["trace[808412256] 'read index received' (duration: 140.910598ms)","trace[808412256] 'applied index is now lower than readState.Index' (duration: 35.895719ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:19.566176Z","caller":"traceutil/trace.go:171","msg":"trace[1572542085] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"289.118157ms","start":"2026-08-12T05:46:19.277022Z","end":"2026-08-12T05:46:19.566141Z","steps":["trace[1572542085] 'process raft request' (duration: 288.826819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:19.56635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.036674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T05:46:19.566405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.288601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-12T05:46:19.566469Z","caller":"traceutil/trace.go:171","msg":"trace[1832497804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26269; }","duration":"101.389183ms","start":"2026-08-12T05:46:19.465061Z","end":"2026-08-12T05:46:19.56645Z","steps":["trace[1832497804] 'agreement among raft nodes before linearized reading' (duration: 101.205478ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:19.566471Z","caller":"traceutil/trace.go:171","msg":"trace[2053745461] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26269; }","duration":"177.260379ms","start":"2026-08-12T05:46:19.389179Z","end":"2026-08-12T05:46:19.56644Z","steps":["trace[2053745461] 'agreement among raft nodes before linearized reading' (duration: 177.039693ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:19.984782Z","caller":"traceutil/trace.go:171","msg":"trace[97929716] linearizableReadLoop","detail":"{readStateIndex:27136; appliedIndex:27135; }","duration":"280.393577ms","start":"2026-08-12T05:46:19.704352Z","end":"2026-08-12T05:46:19.984745Z","steps":["trace[97929716] 'read index received' (duration: 280.090229ms)","trace[97929716] 'applied index is now lower than readState.Index' (duration: 300.988µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:19.984829Z","caller":"traceutil/trace.go:171","msg":"trace[1514803766] transaction","detail":"{read_only:false; response_revision:26270; number_of_response:1; }","duration":"412.658419ms","start":"2026-08-12T05:46:19.572118Z","end":"2026-08-12T05:46:19.984777Z","steps":["trace[1514803766] 'process raft request' (duration: 412.219807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:19.985051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:19.572085Z","time spent":"412.846364ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:46:19.985094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.700456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-12T05:46:19.985171Z","caller":"traceutil/trace.go:171","msg":"trace[1593964966] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26270; }","duration":"280.86239ms","start":"2026-08-12T05:46:19.704289Z","end":"2026-08-12T05:46:19.985151Z","steps":["trace[1593964966] 'agreement among raft nodes before linearized reading' (duration: 280.532511ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:20.000454Z","caller":"traceutil/trace.go:171","msg":"trace[1639246799] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"238.224464ms","start":"2026-08-12T05:46:19.762207Z","end":"2026-08-12T05:46:20.000432Z","steps":["trace[1639246799] 'process raft request' (duration: 237.960177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:20.165836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.095915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:46:20.166103Z","caller":"traceutil/trace.go:171","msg":"trace[728572896] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"159.853634ms","start":"2026-08-12T05:46:20.006223Z","end":"2026-08-12T05:46:20.166076Z","steps":["trace[728572896] 'process raft request' (duration: 159.763092ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:20.166231Z","caller":"traceutil/trace.go:171","msg":"trace[1922231861] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"163.819837ms","start":"2026-08-12T05:46:20.002382Z","end":"2026-08-12T05:46:20.166202Z","steps":["trace[1922231861] 'process raft request' (duration: 12.220349ms)","trace[1922231861] 'compare' (duration: 150.956042ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:20.166221Z","caller":"traceutil/trace.go:171","msg":"trace[101910890] linearizableReadLoop","detail":"{readStateIndex:27138; appliedIndex:27137; }","duration":"163.478429ms","start":"2026-08-12T05:46:20.002698Z","end":"2026-08-12T05:46:20.166176Z","steps":["trace[101910890] 'read index received' (duration: 11.894131ms)","trace[101910890] 'applied index is now lower than readState.Index' (duration: 151.580778ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:20.166438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.722925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18353"} {"level":"info","ts":"2026-08-12T05:46:20.166548Z","caller":"traceutil/trace.go:171","msg":"trace[2034459659] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26273; }","duration":"163.864809ms","start":"2026-08-12T05:46:20.002657Z","end":"2026-08-12T05:46:20.166522Z","steps":["trace[2034459659] 'agreement among raft nodes before linearized reading' (duration: 163.639533ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:20.613668Z","caller":"traceutil/trace.go:171","msg":"trace[805195554] linearizableReadLoop","detail":"{readStateIndex:27140; appliedIndex:27139; }","duration":"103.177631ms","start":"2026-08-12T05:46:20.510467Z","end":"2026-08-12T05:46:20.613644Z","steps":["trace[805195554] 'read index received' (duration: 102.968785ms)","trace[805195554] 'applied index is now lower than readState.Index' (duration: 206.986µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:20.613888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.384106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:20.613931Z","caller":"traceutil/trace.go:171","msg":"trace[1089373472] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26274; }","duration":"103.469178ms","start":"2026-08-12T05:46:20.510448Z","end":"2026-08-12T05:46:20.613918Z","steps":["trace[1089373472] 'agreement among raft nodes before linearized reading' (duration: 103.298764ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:20.614145Z","caller":"traceutil/trace.go:171","msg":"trace[1325162375] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"106.851866ms","start":"2026-08-12T05:46:20.507265Z","end":"2026-08-12T05:46:20.614117Z","steps":["trace[1325162375] 'process raft request' (duration: 106.171638ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:20.801121Z","caller":"traceutil/trace.go:171","msg":"trace[940552254] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"223.067959ms","start":"2026-08-12T05:46:20.578017Z","end":"2026-08-12T05:46:20.801085Z","steps":["trace[940552254] 'process raft request' (duration: 222.820762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:21.821383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.630158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:46:21.821921Z","caller":"traceutil/trace.go:171","msg":"trace[828365556] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"250.513697ms","start":"2026-08-12T05:46:21.571378Z","end":"2026-08-12T05:46:21.821892Z","steps":["trace[828365556] 'process raft request' (duration: 124.252602ms)","trace[828365556] 'compare' (duration: 125.145595ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:21.916107Z","caller":"traceutil/trace.go:171","msg":"trace[1522132768] transaction","detail":"{read_only:false; response_revision:26283; number_of_response:1; }","duration":"343.231203ms","start":"2026-08-12T05:46:21.572849Z","end":"2026-08-12T05:46:21.91608Z","steps":["trace[1522132768] 'process raft request' (duration: 343.193142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:21.916297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:21.572819Z","time spent":"343.368006ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:21.916459Z","caller":"traceutil/trace.go:171","msg":"trace[1022649946] transaction","detail":"{read_only:false; response_revision:26281; number_of_response:1; }","duration":"343.675974ms","start":"2026-08-12T05:46:21.57274Z","end":"2026-08-12T05:46:21.916416Z","steps":["trace[1022649946] 'process raft request' (duration: 343.098569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:21.916644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:21.572725Z","time spent":"343.809578ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:21.916679Z","caller":"traceutil/trace.go:171","msg":"trace[974053229] transaction","detail":"{read_only:false; response_revision:26282; number_of_response:1; }","duration":"343.918661ms","start":"2026-08-12T05:46:21.572746Z","end":"2026-08-12T05:46:21.916664Z","steps":["trace[974053229] 'process raft request' (duration: 343.251053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:21.916779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:21.572717Z","time spent":"344.014494ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:46:22.270636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.995069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:46:22.270783Z","caller":"traceutil/trace.go:171","msg":"trace[729232975] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"264.585464ms","start":"2026-08-12T05:46:22.006173Z","end":"2026-08-12T05:46:22.270758Z","steps":["trace[729232975] 'process raft request' (duration: 133.334889ms)","trace[729232975] 'compare' (duration: 130.818314ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:22.419519Z","caller":"traceutil/trace.go:171","msg":"trace[1198777824] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"246.556993ms","start":"2026-08-12T05:46:22.172935Z","end":"2026-08-12T05:46:22.419492Z","steps":["trace[1198777824] 'process raft request' (duration: 246.483881ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:22.419879Z","caller":"traceutil/trace.go:171","msg":"trace[1871529107] linearizableReadLoop","detail":"{readStateIndex:27152; appliedIndex:27150; }","duration":"281.468267ms","start":"2026-08-12T05:46:22.138388Z","end":"2026-08-12T05:46:22.419856Z","steps":["trace[1871529107] 'read index received' (duration: 1.16016ms)","trace[1871529107] 'applied index is now lower than readState.Index' (duration: 280.305906ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:22.41998Z","caller":"traceutil/trace.go:171","msg":"trace[1633311710] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"316.75594ms","start":"2026-08-12T05:46:22.103172Z","end":"2026-08-12T05:46:22.419928Z","steps":["trace[1633311710] 'process raft request' (duration: 295.187946ms)","trace[1633311710] 'compare' (duration: 20.880326ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:22.420124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.713286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:22.420166Z","caller":"traceutil/trace.go:171","msg":"trace[2118975404] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26287; }","duration":"241.758027ms","start":"2026-08-12T05:46:22.178393Z","end":"2026-08-12T05:46:22.420151Z","steps":["trace[2118975404] 'agreement among raft nodes before linearized reading' (duration: 241.669895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:22.420208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:22.103132Z","time spent":"316.950845ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:46:22.420249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.585016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T05:46:22.420335Z","caller":"traceutil/trace.go:171","msg":"trace[1990280873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26287; }","duration":"248.737789ms","start":"2026-08-12T05:46:22.171574Z","end":"2026-08-12T05:46:22.420312Z","steps":["trace[1990280873] 'agreement among raft nodes before linearized reading' (duration: 248.475663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:22.42003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.636131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:22.420477Z","caller":"traceutil/trace.go:171","msg":"trace[235749606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26287; }","duration":"282.140344ms","start":"2026-08-12T05:46:22.13832Z","end":"2026-08-12T05:46:22.420461Z","steps":["trace[235749606] 'agreement among raft nodes before linearized reading' (duration: 281.648781ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:22.640033Z","caller":"traceutil/trace.go:171","msg":"trace[754090300] linearizableReadLoop","detail":"{readStateIndex:27155; appliedIndex:27154; }","duration":"134.462879ms","start":"2026-08-12T05:46:22.505536Z","end":"2026-08-12T05:46:22.639999Z","steps":["trace[754090300] 'read index received' (duration: 68.843112ms)","trace[754090300] 'applied index is now lower than readState.Index' (duration: 65.618257ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:22.640246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.681875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:22.640272Z","caller":"traceutil/trace.go:171","msg":"trace[682725705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26288; }","duration":"134.783667ms","start":"2026-08-12T05:46:22.50548Z","end":"2026-08-12T05:46:22.640264Z","steps":["trace[682725705] 'agreement among raft nodes before linearized reading' (duration: 134.695365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:23.28422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.859141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:23.284319Z","caller":"traceutil/trace.go:171","msg":"trace[1276531145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26291; }","duration":"145.028186ms","start":"2026-08-12T05:46:23.139269Z","end":"2026-08-12T05:46:23.284297Z","steps":["trace[1276531145] 'range keys from in-memory index tree' (duration: 144.720757ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:23.534749Z","caller":"traceutil/trace.go:171","msg":"trace[1767698130] linearizableReadLoop","detail":"{readStateIndex:27159; appliedIndex:27158; }","duration":"262.460448ms","start":"2026-08-12T05:46:23.272256Z","end":"2026-08-12T05:46:23.534717Z","steps":["trace[1767698130] 'read index received' (duration: 262.180621ms)","trace[1767698130] 'applied index is now lower than readState.Index' (duration: 278.557µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:23.534902Z","caller":"traceutil/trace.go:171","msg":"trace[616862581] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"263.050663ms","start":"2026-08-12T05:46:23.271798Z","end":"2026-08-12T05:46:23.534849Z","steps":["trace[616862581] 'process raft request' (duration: 262.717955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:23.535086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.797087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-12T05:46:23.53509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.69704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:23.535136Z","caller":"traceutil/trace.go:171","msg":"trace[1849879871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26292; }","duration":"262.90967ms","start":"2026-08-12T05:46:23.272213Z","end":"2026-08-12T05:46:23.535122Z","steps":["trace[1849879871] 'agreement among raft nodes before linearized reading' (duration: 262.692734ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:23.535174Z","caller":"traceutil/trace.go:171","msg":"trace[217769123] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:26292; }","duration":"172.843093ms","start":"2026-08-12T05:46:23.362307Z","end":"2026-08-12T05:46:23.53515Z","steps":["trace[217769123] 'agreement among raft nodes before linearized reading' (duration: 172.683119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:23.805961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.312706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:46:23.806465Z","caller":"traceutil/trace.go:171","msg":"trace[812464301] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"266.825083ms","start":"2026-08-12T05:46:23.539599Z","end":"2026-08-12T05:46:23.806424Z","steps":["trace[812464301] 'process raft request' (duration: 110.952094ms)","trace[812464301] 'compare' (duration: 154.919985ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:23.806737Z","caller":"traceutil/trace.go:171","msg":"trace[1236248750] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"248.712119ms","start":"2026-08-12T05:46:23.558009Z","end":"2026-08-12T05:46:23.806721Z","steps":["trace[1236248750] 'process raft request' (duration: 248.415041ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:23.80656Z","caller":"traceutil/trace.go:171","msg":"trace[448957020] linearizableReadLoop","detail":"{readStateIndex:27161; appliedIndex:27159; }","duration":"127.473316ms","start":"2026-08-12T05:46:23.679069Z","end":"2026-08-12T05:46:23.806542Z","steps":["trace[448957020] 'read index received' (duration: 9.654803ms)","trace[448957020] 'applied index is now lower than readState.Index' (duration: 117.817733ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:23.807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.929528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T05:46:23.807037Z","caller":"traceutil/trace.go:171","msg":"trace[2146582804] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26294; }","duration":"128.021871ms","start":"2026-08-12T05:46:23.679004Z","end":"2026-08-12T05:46:23.807026Z","steps":["trace[2146582804] 'agreement among raft nodes before linearized reading' (duration: 127.928109ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:23.972403Z","caller":"traceutil/trace.go:171","msg":"trace[891937603] linearizableReadLoop","detail":"{readStateIndex:27162; appliedIndex:27161; }","duration":"121.87672ms","start":"2026-08-12T05:46:23.850502Z","end":"2026-08-12T05:46:23.972379Z","steps":["trace[891937603] 'read index received' (duration: 108.453038ms)","trace[891937603] 'applied index is now lower than readState.Index' (duration: 13.422632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:23.972588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.062154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:23.972618Z","caller":"traceutil/trace.go:171","msg":"trace[445754733] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26295; }","duration":"122.151877ms","start":"2026-08-12T05:46:23.850459Z","end":"2026-08-12T05:46:23.97261Z","steps":["trace[445754733] 'agreement among raft nodes before linearized reading' (duration: 122.060854ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:23.972828Z","caller":"traceutil/trace.go:171","msg":"trace[172158036] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"144.101491ms","start":"2026-08-12T05:46:23.828336Z","end":"2026-08-12T05:46:23.972438Z","steps":["trace[172158036] 'process raft request' (duration: 130.597128ms)","trace[172158036] 'compare' (duration: 13.343429ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:24.270419Z","caller":"traceutil/trace.go:171","msg":"trace[2130388867] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"349.068696ms","start":"2026-08-12T05:46:23.921325Z","end":"2026-08-12T05:46:24.270394Z","steps":["trace[2130388867] 'process raft request' (duration: 348.880541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:24.270557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:23.921313Z","time spent":"349.171739ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:24.270765Z","caller":"traceutil/trace.go:171","msg":"trace[271436224] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"349.120378ms","start":"2026-08-12T05:46:23.921612Z","end":"2026-08-12T05:46:24.270733Z","steps":["trace[271436224] 'process raft request' (duration: 348.746228ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:24.27083Z","caller":"traceutil/trace.go:171","msg":"trace[1843813218] linearizableReadLoop","detail":"{readStateIndex:27165; appliedIndex:27162; }","duration":"256.086323ms","start":"2026-08-12T05:46:24.014692Z","end":"2026-08-12T05:46:24.270778Z","steps":["trace[1843813218] 'read index received' (duration: 255.522488ms)","trace[1843813218] 'applied index is now lower than readState.Index' (duration: 562.505µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:24.270896Z","caller":"traceutil/trace.go:171","msg":"trace[40125394] transaction","detail":"{read_only:false; response_revision:26298; number_of_response:1; }","duration":"348.47688ms","start":"2026-08-12T05:46:23.922392Z","end":"2026-08-12T05:46:24.270869Z","steps":["trace[40125394] 'process raft request' (duration: 348.210263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:24.27095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:23.921606Z","time spent":"349.249441ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:46:24.27107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.37613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-12T05:46:24.271077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:23.922386Z","time spent":"348.570614ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:24.271115Z","caller":"traceutil/trace.go:171","msg":"trace[2121846929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26298; }","duration":"256.475202ms","start":"2026-08-12T05:46:24.014625Z","end":"2026-08-12T05:46:24.271101Z","steps":["trace[2121846929] 'agreement among raft nodes before linearized reading' (duration: 256.316578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:24.354495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.637396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:24.354579Z","caller":"traceutil/trace.go:171","msg":"trace[1217577513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26299; }","duration":"217.782009ms","start":"2026-08-12T05:46:24.136778Z","end":"2026-08-12T05:46:24.35456Z","steps":["trace[1217577513] 'agreement among raft nodes before linearized reading' (duration: 217.602295ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:24.354485Z","caller":"traceutil/trace.go:171","msg":"trace[45336928] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"180.150775ms","start":"2026-08-12T05:46:24.174303Z","end":"2026-08-12T05:46:24.354453Z","steps":["trace[45336928] 'process raft request' (duration: 179.928409ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:24.438127Z","caller":"traceutil/trace.go:171","msg":"trace[175355467] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"161.558058ms","start":"2026-08-12T05:46:24.276545Z","end":"2026-08-12T05:46:24.438103Z","steps":["trace[175355467] 'process raft request' (duration: 159.775051ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:24.438367Z","caller":"traceutil/trace.go:171","msg":"trace[1280445345] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"161.410464ms","start":"2026-08-12T05:46:24.276939Z","end":"2026-08-12T05:46:24.438349Z","steps":["trace[1280445345] 'process raft request' (duration: 161.106776ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:24.597723Z","caller":"traceutil/trace.go:171","msg":"trace[2120054266] linearizableReadLoop","detail":"{readStateIndex:27170; appliedIndex:27169; }","duration":"152.251475ms","start":"2026-08-12T05:46:24.445446Z","end":"2026-08-12T05:46:24.597697Z","steps":["trace[2120054266] 'read index received' (duration: 152.028719ms)","trace[2120054266] 'applied index is now lower than readState.Index' (duration: 221.856µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:24.597852Z","caller":"traceutil/trace.go:171","msg":"trace[2005525880] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"154.630757ms","start":"2026-08-12T05:46:24.443183Z","end":"2026-08-12T05:46:24.597814Z","steps":["trace[2005525880] 'process raft request' (duration: 154.34661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:24.597958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.482041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:24.598053Z","caller":"traceutil/trace.go:171","msg":"trace[857414026] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26303; }","duration":"152.633585ms","start":"2026-08-12T05:46:24.445399Z","end":"2026-08-12T05:46:24.598033Z","steps":["trace[857414026] 'agreement among raft nodes before linearized reading' (duration: 152.44873ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:24.923548Z","caller":"traceutil/trace.go:171","msg":"trace[145885029] linearizableReadLoop","detail":"{readStateIndex:27171; appliedIndex:27170; }","duration":"124.114718ms","start":"2026-08-12T05:46:24.7994Z","end":"2026-08-12T05:46:24.923515Z","steps":["trace[145885029] 'read index received' (duration: 123.882242ms)","trace[145885029] 'applied index is now lower than readState.Index' (duration: 231.226µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:24.923876Z","caller":"traceutil/trace.go:171","msg":"trace[2035542862] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"165.879762ms","start":"2026-08-12T05:46:24.757957Z","end":"2026-08-12T05:46:24.923837Z","steps":["trace[2035542862] 'process raft request' (duration: 165.397479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:24.923915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.485028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-12T05:46:24.924182Z","caller":"traceutil/trace.go:171","msg":"trace[758015940] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:26304; }","duration":"124.839017ms","start":"2026-08-12T05:46:24.799328Z","end":"2026-08-12T05:46:24.924167Z","steps":["trace[758015940] 'agreement among raft nodes before linearized reading' (duration: 124.331474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:27.77508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.046783ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T05:46:27.775636Z","caller":"traceutil/trace.go:171","msg":"trace[616057763] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"225.476171ms","start":"2026-08-12T05:46:27.550125Z","end":"2026-08-12T05:46:27.775601Z","steps":["trace[616057763] 'process raft request' (duration: 225.196334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:28.203774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.528773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:46:28.203927Z","caller":"traceutil/trace.go:171","msg":"trace[1620276125] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"388.510278ms","start":"2026-08-12T05:46:27.81539Z","end":"2026-08-12T05:46:28.203901Z","steps":["trace[1620276125] 'process raft request' (duration: 157.705847ms)","trace[1620276125] 'compare' (duration: 230.267537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:28.204011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:27.815359Z","time spent":"388.618131ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:28.363967Z","caller":"traceutil/trace.go:171","msg":"trace[1015769432] linearizableReadLoop","detail":"{readStateIndex:27192; appliedIndex:27190; }","duration":"543.497634ms","start":"2026-08-12T05:46:27.820446Z","end":"2026-08-12T05:46:28.363944Z","steps":["trace[1015769432] 'read index received' (duration: 152.591363ms)","trace[1015769432] 'applied index is now lower than readState.Index' (duration: 390.904961ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:28.364079Z","caller":"traceutil/trace.go:171","msg":"trace[1069223882] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"544.8513ms","start":"2026-08-12T05:46:27.81919Z","end":"2026-08-12T05:46:28.364042Z","steps":["trace[1069223882] 'process raft request' (duration: 544.604114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:28.36426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.804873ms","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-12T05:46:28.364303Z","caller":"traceutil/trace.go:171","msg":"trace[744064038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26324; }","duration":"543.919456ms","start":"2026-08-12T05:46:27.820372Z","end":"2026-08-12T05:46:28.364291Z","steps":["trace[744064038] 'agreement among raft nodes before linearized reading' (duration: 543.765251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:28.364337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:27.820341Z","time spent":"543.988147ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T05:46:28.364733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:27.819156Z","time spent":"544.992924ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:28.364815Z","caller":"traceutil/trace.go:171","msg":"trace[1491164264] transaction","detail":"{read_only:false; response_revision:26325; number_of_response:1; }","duration":"365.928517ms","start":"2026-08-12T05:46:27.998856Z","end":"2026-08-12T05:46:28.364784Z","steps":["trace[1491164264] 'process raft request' (duration: 365.684611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:28.364925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:27.998818Z","time spent":"366.03479ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:46:28.364958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.663042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:28.365041Z","caller":"traceutil/trace.go:171","msg":"trace[1669950878] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26328; }","duration":"260.826117ms","start":"2026-08-12T05:46:28.104196Z","end":"2026-08-12T05:46:28.365022Z","steps":["trace[1669950878] 'agreement among raft nodes before linearized reading' (duration: 260.643562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:28.365068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.361593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:28.365133Z","caller":"traceutil/trace.go:171","msg":"trace[1355682393] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26328; }","duration":"221.443776ms","start":"2026-08-12T05:46:28.143671Z","end":"2026-08-12T05:46:28.365115Z","steps":["trace[1355682393] 'agreement among raft nodes before linearized reading' (duration: 221.348434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:28.36522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.600096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"warn","ts":"2026-08-12T05:46:28.365298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.043427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"warn","ts":"2026-08-12T05:46:28.365358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.446205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:28.365382Z","caller":"traceutil/trace.go:171","msg":"trace[2041704616] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26328; }","duration":"202.183371ms","start":"2026-08-12T05:46:28.163178Z","end":"2026-08-12T05:46:28.365361Z","steps":["trace[2041704616] 'agreement among raft nodes before linearized reading' (duration: 201.805852ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:28.365396Z","caller":"traceutil/trace.go:171","msg":"trace[209032019] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:26328; }","duration":"169.486936ms","start":"2026-08-12T05:46:28.195897Z","end":"2026-08-12T05:46:28.365384Z","steps":["trace[209032019] 'agreement among raft nodes before linearized reading' (duration: 169.417084ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:28.365272Z","caller":"traceutil/trace.go:171","msg":"trace[549105527] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:26328; }","duration":"227.676789ms","start":"2026-08-12T05:46:28.137578Z","end":"2026-08-12T05:46:28.365255Z","steps":["trace[549105527] 'agreement among raft nodes before linearized reading' (duration: 227.524685ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:39.240615Z","caller":"traceutil/trace.go:171","msg":"trace[236684694] linearizableReadLoop","detail":"{readStateIndex:27337; appliedIndex:27336; }","duration":"103.509499ms","start":"2026-08-12T05:46:39.137085Z","end":"2026-08-12T05:46:39.240594Z","steps":["trace[236684694] 'read index received' (duration: 103.285353ms)","trace[236684694] 'applied index is now lower than readState.Index' (duration: 223.496µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:39.24088Z","caller":"traceutil/trace.go:171","msg":"trace[1156645298] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"225.524242ms","start":"2026-08-12T05:46:39.015346Z","end":"2026-08-12T05:46:39.24087Z","steps":["trace[1156645298] 'process raft request' (duration: 225.090741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:39.241066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.958181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:39.241095Z","caller":"traceutil/trace.go:171","msg":"trace[1653341083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26466; }","duration":"104.048123ms","start":"2026-08-12T05:46:39.13704Z","end":"2026-08-12T05:46:39.241088Z","steps":["trace[1653341083] 'agreement among raft nodes before linearized reading' (duration: 103.968041ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:39.748601Z","caller":"traceutil/trace.go:171","msg":"trace[148620656] linearizableReadLoop","detail":"{readStateIndex:27339; appliedIndex:27338; }","duration":"160.436859ms","start":"2026-08-12T05:46:39.588135Z","end":"2026-08-12T05:46:39.748572Z","steps":["trace[148620656] 'read index received' (duration: 160.240094ms)","trace[148620656] 'applied index is now lower than readState.Index' (duration: 195.615µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:39.748709Z","caller":"traceutil/trace.go:171","msg":"trace[269531220] transaction","detail":"{read_only:false; response_revision:26468; number_of_response:1; }","duration":"208.368804ms","start":"2026-08-12T05:46:39.540304Z","end":"2026-08-12T05:46:39.748673Z","steps":["trace[269531220] 'process raft request' (duration: 208.097547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:39.748871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.711836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-08-12T05:46:39.748924Z","caller":"traceutil/trace.go:171","msg":"trace[1939881795] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26468; }","duration":"160.801459ms","start":"2026-08-12T05:46:39.588105Z","end":"2026-08-12T05:46:39.748906Z","steps":["trace[1939881795] 'agreement among raft nodes before linearized reading' (duration: 160.637835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:39.74906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.484133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1adf062a-2c17-4dcf-9f9f-5777d0781949\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-12T05:46:39.74918Z","caller":"traceutil/trace.go:171","msg":"trace[336684933] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1adf062a-2c17-4dcf-9f9f-5777d0781949; range_end:; response_count:1; response_revision:26468; }","duration":"128.666097ms","start":"2026-08-12T05:46:39.620483Z","end":"2026-08-12T05:46:39.749149Z","steps":["trace[336684933] 'agreement among raft nodes before linearized reading' (duration: 128.410041ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:39.899332Z","caller":"traceutil/trace.go:171","msg":"trace[1955325035] linearizableReadLoop","detail":"{readStateIndex:27340; appliedIndex:27339; }","duration":"110.694307ms","start":"2026-08-12T05:46:39.788606Z","end":"2026-08-12T05:46:39.8993Z","steps":["trace[1955325035] 'read index received' (duration: 110.516482ms)","trace[1955325035] 'applied index is now lower than readState.Index' (duration: 176.315µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:39.899436Z","caller":"traceutil/trace.go:171","msg":"trace[1096367914] transaction","detail":"{read_only:false; response_revision:26469; number_of_response:1; }","duration":"112.929756ms","start":"2026-08-12T05:46:39.786467Z","end":"2026-08-12T05:46:39.899397Z","steps":["trace[1096367914] 'process raft request' (duration: 112.620347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:39.899598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.968394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-08-12T05:46:39.899649Z","caller":"traceutil/trace.go:171","msg":"trace[1937871414] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26469; }","duration":"111.071197ms","start":"2026-08-12T05:46:39.788563Z","end":"2026-08-12T05:46:39.899634Z","steps":["trace[1937871414] 'agreement among raft nodes before linearized reading' (duration: 110.864341ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:40.187366Z","caller":"traceutil/trace.go:171","msg":"trace[53047908] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"257.440327ms","start":"2026-08-12T05:46:39.929883Z","end":"2026-08-12T05:46:40.187323Z","steps":["trace[53047908] 'process raft request' (duration: 257.109939ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:40.745843Z","caller":"traceutil/trace.go:171","msg":"trace[135304813] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"324.706778ms","start":"2026-08-12T05:46:40.421107Z","end":"2026-08-12T05:46:40.745814Z","steps":["trace[135304813] 'process raft request' (duration: 322.907981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:40.746018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:40.421078Z","time spent":"324.843472ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:40.74631Z","caller":"traceutil/trace.go:171","msg":"trace[1483991715] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"325.015116ms","start":"2026-08-12T05:46:40.421242Z","end":"2026-08-12T05:46:40.746257Z","steps":["trace[1483991715] 'process raft request' (duration: 324.490622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:40.746567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:40.421203Z","time spent":"325.210822ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:40.746939Z","caller":"traceutil/trace.go:171","msg":"trace[896489139] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"325.467978ms","start":"2026-08-12T05:46:40.421408Z","end":"2026-08-12T05:46:40.746876Z","steps":["trace[896489139] 'process raft request' (duration: 324.794441ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:40.747125Z","caller":"traceutil/trace.go:171","msg":"trace[443521197] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"324.634307ms","start":"2026-08-12T05:46:40.422444Z","end":"2026-08-12T05:46:40.747078Z","steps":["trace[443521197] 'process raft request' (duration: 324.268757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:40.74716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:40.421398Z","time spent":"325.647173ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:46:40.747215Z","caller":"traceutil/trace.go:171","msg":"trace[1613955456] linearizableReadLoop","detail":"{readStateIndex:27348; appliedIndex:27344; }","duration":"318.246729ms","start":"2026-08-12T05:46:40.428919Z","end":"2026-08-12T05:46:40.747166Z","steps":["trace[1613955456] 'read index received' (duration: 315.081246ms)","trace[1613955456] 'applied index is now lower than readState.Index' (duration: 3.157403ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:46:40.747315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:40.422438Z","time spent":"324.77603ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:46:40.747404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.480036ms","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-12T05:46:40.747469Z","caller":"traceutil/trace.go:171","msg":"trace[1718987100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26477; }","duration":"318.570177ms","start":"2026-08-12T05:46:40.428884Z","end":"2026-08-12T05:46:40.747454Z","steps":["trace[1718987100] 'agreement among raft nodes before linearized reading' (duration: 318.363182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:40.747505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:46:40.428876Z","time spent":"318.62295ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T05:46:40.747672Z","caller":"traceutil/trace.go:171","msg":"trace[1214107371] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"255.160649ms","start":"2026-08-12T05:46:40.492495Z","end":"2026-08-12T05:46:40.747655Z","steps":["trace[1214107371] 'process raft request' (duration: 254.613024ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:40.748055Z","caller":"traceutil/trace.go:171","msg":"trace[1349750227] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"249.790518ms","start":"2026-08-12T05:46:40.498252Z","end":"2026-08-12T05:46:40.748043Z","steps":["trace[1349750227] 'process raft request' (duration: 249.292435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:40.74857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.389705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-12T05:46:40.748616Z","caller":"traceutil/trace.go:171","msg":"trace[4083738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26480; }","duration":"239.489518ms","start":"2026-08-12T05:46:40.509117Z","end":"2026-08-12T05:46:40.748606Z","steps":["trace[4083738] 'agreement among raft nodes before linearized reading' (duration: 239.296993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:40.748753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.139742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:46:40.748777Z","caller":"traceutil/trace.go:171","msg":"trace[1192544332] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26480; }","duration":"144.164983ms","start":"2026-08-12T05:46:40.604603Z","end":"2026-08-12T05:46:40.748768Z","steps":["trace[1192544332] 'agreement among raft nodes before linearized reading' (duration: 144.121692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:40.748937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.053197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-12T05:46:40.748962Z","caller":"traceutil/trace.go:171","msg":"trace[2067464035] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26480; }","duration":"150.11895ms","start":"2026-08-12T05:46:40.598836Z","end":"2026-08-12T05:46:40.748955Z","steps":["trace[2067464035] 'agreement among raft nodes before linearized reading' (duration: 150.046558ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:42.975574Z","caller":"traceutil/trace.go:171","msg":"trace[394377949] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"222.393341ms","start":"2026-08-12T05:46:42.753153Z","end":"2026-08-12T05:46:42.975547Z","steps":["trace[394377949] 'process raft request' (duration: 222.338559ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:42.975587Z","caller":"traceutil/trace.go:171","msg":"trace[820811408] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"222.543905ms","start":"2026-08-12T05:46:42.753Z","end":"2026-08-12T05:46:42.975544Z","steps":["trace[820811408] 'process raft request' (duration: 222.35796ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:42.975872Z","caller":"traceutil/trace.go:171","msg":"trace[1506639363] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"222.633987ms","start":"2026-08-12T05:46:42.75322Z","end":"2026-08-12T05:46:42.975854Z","steps":["trace[1506639363] 'process raft request' (duration: 222.296538ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:42.976018Z","caller":"traceutil/trace.go:171","msg":"trace[1145473091] linearizableReadLoop","detail":"{readStateIndex:27372; appliedIndex:27366; }","duration":"216.64434ms","start":"2026-08-12T05:46:42.759344Z","end":"2026-08-12T05:46:42.975988Z","steps":["trace[1145473091] 'read index received' (duration: 65.761371ms)","trace[1145473091] 'applied index is now lower than readState.Index' (duration: 150.881609ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:46:42.97614Z","caller":"traceutil/trace.go:171","msg":"trace[270301984] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"222.610616ms","start":"2026-08-12T05:46:42.75352Z","end":"2026-08-12T05:46:42.976131Z","steps":["trace[270301984] 'process raft request' (duration: 222.251727ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:42.976194Z","caller":"traceutil/trace.go:171","msg":"trace[680399737] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"222.536644ms","start":"2026-08-12T05:46:42.75364Z","end":"2026-08-12T05:46:42.976177Z","steps":["trace[680399737] 'process raft request' (duration: 222.292108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:42.976485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.117472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-12T05:46:42.976486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.982501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1adf062a-2c17-4dcf-9f9f-5777d0781949\" ","response":"range_response_count:1 size:3950"} {"level":"warn","ts":"2026-08-12T05:46:42.976501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.78042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T05:46:42.976526Z","caller":"traceutil/trace.go:171","msg":"trace[709843455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26500; }","duration":"217.221525ms","start":"2026-08-12T05:46:42.759293Z","end":"2026-08-12T05:46:42.976514Z","steps":["trace[709843455] 'agreement among raft nodes before linearized reading' (duration: 217.073961ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:42.976532Z","caller":"traceutil/trace.go:171","msg":"trace[896446760] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1adf062a-2c17-4dcf-9f9f-5777d0781949; range_end:; response_count:1; response_revision:26500; }","duration":"206.072513ms","start":"2026-08-12T05:46:42.770447Z","end":"2026-08-12T05:46:42.97652Z","steps":["trace[896446760] 'agreement among raft nodes before linearized reading' (duration: 205.915079ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:42.976546Z","caller":"traceutil/trace.go:171","msg":"trace[1050661429] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26500; }","duration":"136.881722ms","start":"2026-08-12T05:46:42.839651Z","end":"2026-08-12T05:46:42.976533Z","steps":["trace[1050661429] 'agreement among raft nodes before linearized reading' (duration: 136.698248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:42.976608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.145668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T05:46:42.97663Z","caller":"traceutil/trace.go:171","msg":"trace[1246999588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26500; }","duration":"213.18499ms","start":"2026-08-12T05:46:42.76344Z","end":"2026-08-12T05:46:42.976625Z","steps":["trace[1246999588] 'agreement among raft nodes before linearized reading' (duration: 213.134619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:42.976861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.669552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-12T05:46:42.976906Z","caller":"traceutil/trace.go:171","msg":"trace[66197085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26500; }","duration":"213.744694ms","start":"2026-08-12T05:46:42.763153Z","end":"2026-08-12T05:46:42.976898Z","steps":["trace[66197085] 'agreement among raft nodes before linearized reading' (duration: 213.085157ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:47.098063Z","caller":"traceutil/trace.go:171","msg":"trace[1734884784] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"101.64327ms","start":"2026-08-12T05:46:46.996384Z","end":"2026-08-12T05:46:47.098027Z","steps":["trace[1734884784] 'process raft request' (duration: 101.429904ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:47.104718Z","caller":"traceutil/trace.go:171","msg":"trace[1271725036] transaction","detail":"{read_only:false; response_revision:26551; number_of_response:1; }","duration":"108.112229ms","start":"2026-08-12T05:46:46.996577Z","end":"2026-08-12T05:46:47.10469Z","steps":["trace[1271725036] 'process raft request' (duration: 107.881313ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:47.104946Z","caller":"traceutil/trace.go:171","msg":"trace[1332142799] transaction","detail":"{read_only:false; response_revision:26552; number_of_response:1; }","duration":"108.300425ms","start":"2026-08-12T05:46:46.996633Z","end":"2026-08-12T05:46:47.104934Z","steps":["trace[1332142799] 'process raft request' (duration: 108.002547ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:47.105049Z","caller":"traceutil/trace.go:171","msg":"trace[655787670] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"108.275403ms","start":"2026-08-12T05:46:46.996763Z","end":"2026-08-12T05:46:47.105039Z","steps":["trace[655787670] 'process raft request' (duration: 108.076438ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:47.105146Z","caller":"traceutil/trace.go:171","msg":"trace[291620391] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"106.144389ms","start":"2026-08-12T05:46:46.998977Z","end":"2026-08-12T05:46:47.105122Z","steps":["trace[291620391] 'process raft request' (duration: 105.951683ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:50.570151Z","caller":"traceutil/trace.go:171","msg":"trace[504250277] transaction","detail":"{read_only:false; response_revision:26580; number_of_response:1; }","duration":"101.301132ms","start":"2026-08-12T05:46:50.468809Z","end":"2026-08-12T05:46:50.570111Z","steps":["trace[504250277] 'process raft request' (duration: 101.009334ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:53.23433Z","caller":"traceutil/trace.go:171","msg":"trace[1907653217] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"112.942676ms","start":"2026-08-12T05:46:53.121361Z","end":"2026-08-12T05:46:53.234304Z","steps":["trace[1907653217] 'process raft request' (duration: 112.435703ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:53.234579Z","caller":"traceutil/trace.go:171","msg":"trace[552989588] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"108.209732ms","start":"2026-08-12T05:46:53.126359Z","end":"2026-08-12T05:46:53.234568Z","steps":["trace[552989588] 'process raft request' (duration: 107.597656ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:53.234738Z","caller":"traceutil/trace.go:171","msg":"trace[956417437] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"106.144608ms","start":"2026-08-12T05:46:53.128584Z","end":"2026-08-12T05:46:53.234729Z","steps":["trace[956417437] 'process raft request' (duration: 105.407179ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:46:53.234855Z","caller":"traceutil/trace.go:171","msg":"trace[797549301] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"105.811389ms","start":"2026-08-12T05:46:53.129036Z","end":"2026-08-12T05:46:53.234847Z","steps":["trace[797549301] 'process raft request' (duration: 104.994148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:46:53.23506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.299705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-12T05:46:53.235099Z","caller":"traceutil/trace.go:171","msg":"trace[646249375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26602; }","duration":"100.367247ms","start":"2026-08-12T05:46:53.134722Z","end":"2026-08-12T05:46:53.235089Z","steps":["trace[646249375] 'agreement among raft nodes before linearized reading' (duration: 100.203842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:01.266945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.870399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-08-12T05:47:01.267046Z","caller":"traceutil/trace.go:171","msg":"trace[462138461] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26660; }","duration":"109.010653ms","start":"2026-08-12T05:47:01.158001Z","end":"2026-08-12T05:47:01.267012Z","steps":["trace[462138461] 'range keys from in-memory index tree' (duration: 108.717095ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:01.376027Z","caller":"traceutil/trace.go:171","msg":"trace[927605218] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"131.117172ms","start":"2026-08-12T05:47:01.244874Z","end":"2026-08-12T05:47:01.375991Z","steps":["trace[927605218] 'process raft request' (duration: 130.864356ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:01.40307Z","caller":"traceutil/trace.go:171","msg":"trace[1610225809] transaction","detail":"{read_only:false; response_revision:26662; number_of_response:1; }","duration":"132.692343ms","start":"2026-08-12T05:47:01.270292Z","end":"2026-08-12T05:47:01.402985Z","steps":["trace[1610225809] 'process raft request' (duration: 132.363794ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:01.403402Z","caller":"traceutil/trace.go:171","msg":"trace[54964302] transaction","detail":"{read_only:false; response_revision:26663; number_of_response:1; }","duration":"129.430007ms","start":"2026-08-12T05:47:01.273954Z","end":"2026-08-12T05:47:01.403384Z","steps":["trace[54964302] 'process raft request' (duration: 128.928644ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:01.403442Z","caller":"traceutil/trace.go:171","msg":"trace[1993208690] transaction","detail":"{read_only:false; response_revision:26664; number_of_response:1; }","duration":"127.680822ms","start":"2026-08-12T05:47:01.275726Z","end":"2026-08-12T05:47:01.403406Z","steps":["trace[1993208690] 'process raft request' (duration: 127.480796ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:01.403534Z","caller":"traceutil/trace.go:171","msg":"trace[153134299] linearizableReadLoop","detail":"{readStateIndex:27537; appliedIndex:27535; }","duration":"132.768135ms","start":"2026-08-12T05:47:01.270746Z","end":"2026-08-12T05:47:01.403514Z","steps":["trace[153134299] 'read index received' (duration: 105.020918ms)","trace[153134299] 'applied index is now lower than readState.Index' (duration: 27.745566ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:47:01.403817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.058623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1be9039d-d375-4280-843a-ca340cc3ad31\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-08-12T05:47:01.403871Z","caller":"traceutil/trace.go:171","msg":"trace[1254788560] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1be9039d-d375-4280-843a-ca340cc3ad31; range_end:; response_count:1; response_revision:26665; }","duration":"133.126974ms","start":"2026-08-12T05:47:01.270725Z","end":"2026-08-12T05:47:01.403852Z","steps":["trace[1254788560] 'agreement among raft nodes before linearized reading' (duration: 132.916759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:01.403868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.113598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-12T05:47:01.403973Z","caller":"traceutil/trace.go:171","msg":"trace[2062695479] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26665; }","duration":"132.234091ms","start":"2026-08-12T05:47:01.271716Z","end":"2026-08-12T05:47:01.40395Z","steps":["trace[2062695479] 'agreement among raft nodes before linearized reading' (duration: 131.980084ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:01.404028Z","caller":"traceutil/trace.go:171","msg":"trace[1540304138] transaction","detail":"{read_only:false; response_revision:26665; number_of_response:1; }","duration":"125.131144ms","start":"2026-08-12T05:47:01.278459Z","end":"2026-08-12T05:47:01.40359Z","steps":["trace[1540304138] 'process raft request' (duration: 124.890618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:01.404327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.645029ms","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-12T05:47:01.404398Z","caller":"traceutil/trace.go:171","msg":"trace[145522521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26665; }","duration":"104.741161ms","start":"2026-08-12T05:47:01.299639Z","end":"2026-08-12T05:47:01.40438Z","steps":["trace[145522521] 'agreement among raft nodes before linearized reading' (duration: 104.471104ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:12.411797Z","caller":"traceutil/trace.go:171","msg":"trace[515973185] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"152.06124ms","start":"2026-08-12T05:47:12.259707Z","end":"2026-08-12T05:47:12.411768Z","steps":["trace[515973185] 'process raft request' (duration: 151.911996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:15.383114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.540397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T05:47:15.38321Z","caller":"traceutil/trace.go:171","msg":"trace[2083099195] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26771; }","duration":"164.678731ms","start":"2026-08-12T05:47:15.218511Z","end":"2026-08-12T05:47:15.383189Z","steps":["trace[2083099195] 'count revisions from in-memory index tree' (duration: 164.435924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:15.383221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.766532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:47:15.383318Z","caller":"traceutil/trace.go:171","msg":"trace[1653075191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26771; }","duration":"245.924706ms","start":"2026-08-12T05:47:15.137373Z","end":"2026-08-12T05:47:15.383297Z","steps":["trace[1653075191] 'range keys from in-memory index tree' (duration: 245.69196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:15.383382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.425091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:47:15.383478Z","caller":"traceutil/trace.go:171","msg":"trace[135861212] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26771; }","duration":"222.584275ms","start":"2026-08-12T05:47:15.160866Z","end":"2026-08-12T05:47:15.383451Z","steps":["trace[135861212] 'count revisions from in-memory index tree' (duration: 222.294258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:15.383988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.12341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-12T05:47:15.384041Z","caller":"traceutil/trace.go:171","msg":"trace[818180804] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26771; }","duration":"296.198423ms","start":"2026-08-12T05:47:15.087828Z","end":"2026-08-12T05:47:15.384026Z","steps":["trace[818180804] 'range keys from in-memory index tree' (duration: 295.980207ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:15.384138Z","caller":"traceutil/trace.go:171","msg":"trace[239269451] transaction","detail":"{read_only:false; response_revision:26772; number_of_response:1; }","duration":"164.938777ms","start":"2026-08-12T05:47:15.219182Z","end":"2026-08-12T05:47:15.38412Z","steps":["trace[239269451] 'process raft request' (duration: 136.047331ms)","trace[239269451] 'compare' (duration: 27.844409ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:47:15.768207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.869282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:47:15.76837Z","caller":"traceutil/trace.go:171","msg":"trace[1080087862] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"331.162547ms","start":"2026-08-12T05:47:15.437177Z","end":"2026-08-12T05:47:15.768339Z","steps":["trace[1080087862] 'process raft request' (duration: 84.014159ms)","trace[1080087862] 'compare' (duration: 246.227604ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:47:15.768463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.437143Z","time spent":"331.27889ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:47:15.779705Z","caller":"traceutil/trace.go:171","msg":"trace[1053996758] transaction","detail":"{read_only:false; response_revision:26775; number_of_response:1; }","duration":"316.979136ms","start":"2026-08-12T05:47:15.462689Z","end":"2026-08-12T05:47:15.779668Z","steps":["trace[1053996758] 'process raft request' (duration: 316.883704ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:15.779769Z","caller":"traceutil/trace.go:171","msg":"trace[1814917472] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"326.149606ms","start":"2026-08-12T05:47:15.453605Z","end":"2026-08-12T05:47:15.779755Z","steps":["trace[1814917472] 'process raft request' (duration: 325.801927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:15.779889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.462679Z","time spent":"317.13623ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:47:15.77998Z","caller":"traceutil/trace.go:171","msg":"trace[1372672532] linearizableReadLoop","detail":"{readStateIndex:27655; appliedIndex:27650; }","duration":"259.681266ms","start":"2026-08-12T05:47:15.520279Z","end":"2026-08-12T05:47:15.77996Z","steps":["trace[1372672532] 'read index received' (duration: 857.292µs)","trace[1372672532] 'applied index is now lower than readState.Index' (duration: 258.822954ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:47:15.780073Z","caller":"traceutil/trace.go:171","msg":"trace[1734016823] transaction","detail":"{read_only:false; response_revision:26776; number_of_response:1; }","duration":"315.612991ms","start":"2026-08-12T05:47:15.46445Z","end":"2026-08-12T05:47:15.780063Z","steps":["trace[1734016823] 'process raft request' (duration: 315.169109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:15.780135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.464442Z","time spent":"315.648531ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:47:15.780208Z","caller":"traceutil/trace.go:171","msg":"trace[555579367] transaction","detail":"{read_only:false; response_revision:26777; number_of_response:1; }","duration":"315.341083ms","start":"2026-08-12T05:47:15.464826Z","end":"2026-08-12T05:47:15.780167Z","steps":["trace[555579367] 'process raft request' (duration: 315.035245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:15.779887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.453588Z","time spent":"326.215668ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:47:15.780366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.464819Z","time spent":"315.468757ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:47:15.780443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.162879ms","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-12T05:47:15.780487Z","caller":"traceutil/trace.go:171","msg":"trace[985700848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26777; }","duration":"260.208621ms","start":"2026-08-12T05:47:15.520268Z","end":"2026-08-12T05:47:15.780477Z","steps":["trace[985700848] 'agreement among raft nodes before linearized reading' (duration: 259.903743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:16.166949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.878948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:47:16.167165Z","caller":"traceutil/trace.go:171","msg":"trace[1522879950] transaction","detail":"{read_only:false; response_revision:26779; number_of_response:1; }","duration":"382.599644ms","start":"2026-08-12T05:47:15.784542Z","end":"2026-08-12T05:47:16.167142Z","steps":["trace[1522879950] 'process raft request' (duration: 382.534672ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:16.167185Z","caller":"traceutil/trace.go:171","msg":"trace[2041040612] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"611.765201ms","start":"2026-08-12T05:47:15.555396Z","end":"2026-08-12T05:47:16.167161Z","steps":["trace[2041040612] 'process raft request' (duration: 371.511213ms)","trace[2041040612] 'compare' (duration: 239.739835ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:47:16.167249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.784507Z","time spent":"382.704276ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T05:47:16.167293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.555383Z","time spent":"611.862334ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:47:16.167499Z","caller":"traceutil/trace.go:171","msg":"trace[946924945] linearizableReadLoop","detail":"{readStateIndex:27656; appliedIndex:27655; }","duration":"387.467301ms","start":"2026-08-12T05:47:15.780018Z","end":"2026-08-12T05:47:16.167485Z","steps":["trace[946924945] 'read index received' (duration: 147.020948ms)","trace[946924945] 'applied index is now lower than readState.Index' (duration: 240.444582ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:47:16.167714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.716655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-12T05:47:16.167752Z","caller":"traceutil/trace.go:171","msg":"trace[1013502130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26779; }","duration":"607.768576ms","start":"2026-08-12T05:47:15.559974Z","end":"2026-08-12T05:47:16.167742Z","steps":["trace[1013502130] 'agreement among raft nodes before linearized reading' (duration: 607.590502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:16.167788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.559968Z","time spent":"607.813478ms","remote":"127.0.0.1:53868","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-12T05:47:16.167878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.625039ms","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-12T05:47:16.167936Z","caller":"traceutil/trace.go:171","msg":"trace[617850408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26779; }","duration":"624.727121ms","start":"2026-08-12T05:47:15.543195Z","end":"2026-08-12T05:47:16.167923Z","steps":["trace[617850408] 'agreement among raft nodes before linearized reading' (duration: 624.598318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:16.167956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.87492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-12T05:47:16.167965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.869335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T05:47:16.167858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.931537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T05:47:16.168009Z","caller":"traceutil/trace.go:171","msg":"trace[184371066] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:26779; }","duration":"624.953587ms","start":"2026-08-12T05:47:15.543042Z","end":"2026-08-12T05:47:16.167996Z","steps":["trace[184371066] 'agreement among raft nodes before linearized reading' (duration: 624.882555ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:16.168009Z","caller":"traceutil/trace.go:171","msg":"trace[1343211016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26779; }","duration":"620.924561ms","start":"2026-08-12T05:47:15.547064Z","end":"2026-08-12T05:47:16.167988Z","steps":["trace[1343211016] 'agreement among raft nodes before linearized reading' (duration: 620.848489ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:16.16801Z","caller":"traceutil/trace.go:171","msg":"trace[1689564777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26779; }","duration":"622.131143ms","start":"2026-08-12T05:47:15.545868Z","end":"2026-08-12T05:47:16.167999Z","steps":["trace[1689564777] 'agreement among raft nodes before linearized reading' (duration: 621.848556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:16.168087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.547058Z","time spent":"621.021423ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T05:47:16.168094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.543022Z","time spent":"625.061639ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-08-12T05:47:16.168107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.545856Z","time spent":"622.241916ms","remote":"127.0.0.1:53868","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-12T05:47:16.167973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.543175Z","time spent":"624.791372ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-12T05:47:16.371063Z","caller":"traceutil/trace.go:171","msg":"trace[446886139] linearizableReadLoop","detail":"{readStateIndex:27658; appliedIndex:27657; }","duration":"203.501916ms","start":"2026-08-12T05:47:16.167533Z","end":"2026-08-12T05:47:16.371035Z","steps":["trace[446886139] 'read index received' (duration: 134.932801ms)","trace[446886139] 'applied index is now lower than readState.Index' (duration: 68.567915ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:47:16.371106Z","caller":"traceutil/trace.go:171","msg":"trace[912287103] transaction","detail":"{read_only:false; response_revision:26780; number_of_response:1; }","duration":"428.357751ms","start":"2026-08-12T05:47:15.942687Z","end":"2026-08-12T05:47:16.371045Z","steps":["trace[912287103] 'process raft request' (duration: 359.761826ms)","trace[912287103] 'compare' (duration: 68.381399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:47:16.371319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.123401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:47:16.371596Z","caller":"traceutil/trace.go:171","msg":"trace[1664444015] transaction","detail":"{read_only:false; response_revision:26783; number_of_response:1; }","duration":"198.30643ms","start":"2026-08-12T05:47:16.173249Z","end":"2026-08-12T05:47:16.371556Z","steps":["trace[1664444015] 'process raft request' (duration: 198.175497ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:16.371684Z","caller":"traceutil/trace.go:171","msg":"trace[1638516401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26781; }","duration":"233.574723ms","start":"2026-08-12T05:47:16.138096Z","end":"2026-08-12T05:47:16.371671Z","steps":["trace[1638516401] 'agreement among raft nodes before linearized reading' (duration: 233.014229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:16.371311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:15.942641Z","time spent":"428.561866ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:47:16.371375Z","caller":"traceutil/trace.go:171","msg":"trace[447625118] transaction","detail":"{read_only:false; response_revision:26781; number_of_response:1; }","duration":"198.332071ms","start":"2026-08-12T05:47:16.173009Z","end":"2026-08-12T05:47:16.371341Z","steps":["trace[447625118] 'process raft request' (duration: 197.984352ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:16.371549Z","caller":"traceutil/trace.go:171","msg":"trace[925101354] transaction","detail":"{read_only:false; response_revision:26782; number_of_response:1; }","duration":"198.339641ms","start":"2026-08-12T05:47:16.173191Z","end":"2026-08-12T05:47:16.371531Z","steps":["trace[925101354] 'process raft request' (duration: 198.063763ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:16.372287Z","caller":"traceutil/trace.go:171","msg":"trace[1385220104] transaction","detail":"{read_only:false; response_revision:26784; number_of_response:1; }","duration":"198.861845ms","start":"2026-08-12T05:47:16.1734Z","end":"2026-08-12T05:47:16.372262Z","steps":["trace[1385220104] 'process raft request' (duration: 198.079694ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:18.403773Z","caller":"traceutil/trace.go:171","msg":"trace[1971476556] linearizableReadLoop","detail":"{readStateIndex:27673; appliedIndex:27672; }","duration":"207.11179ms","start":"2026-08-12T05:47:18.196633Z","end":"2026-08-12T05:47:18.403745Z","steps":["trace[1971476556] 'read index received' (duration: 206.909475ms)","trace[1971476556] 'applied index is now lower than readState.Index' (duration: 200.805µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T05:47:18.403832Z","caller":"traceutil/trace.go:171","msg":"trace[1343317596] transaction","detail":"{read_only:false; response_revision:26794; number_of_response:1; }","duration":"227.193586ms","start":"2026-08-12T05:47:18.176602Z","end":"2026-08-12T05:47:18.403796Z","steps":["trace[1343317596] 'process raft request' (duration: 226.926139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:18.403983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.319636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T05:47:18.404035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.682144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T05:47:18.404083Z","caller":"traceutil/trace.go:171","msg":"trace[314898914] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:26794; }","duration":"103.832048ms","start":"2026-08-12T05:47:18.300238Z","end":"2026-08-12T05:47:18.40407Z","steps":["trace[314898914] 'agreement among raft nodes before linearized reading' (duration: 103.692464ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:18.404076Z","caller":"traceutil/trace.go:171","msg":"trace[671954896] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:26794; }","duration":"207.45498ms","start":"2026-08-12T05:47:18.1966Z","end":"2026-08-12T05:47:18.404055Z","steps":["trace[671954896] 'agreement among raft nodes before linearized reading' (duration: 207.278415ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:18.619884Z","caller":"traceutil/trace.go:171","msg":"trace[534838273] transaction","detail":"{read_only:false; response_revision:26796; number_of_response:1; }","duration":"209.717339ms","start":"2026-08-12T05:47:18.410125Z","end":"2026-08-12T05:47:18.619843Z","steps":["trace[534838273] 'process raft request' (duration: 209.36832ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:18.619897Z","caller":"traceutil/trace.go:171","msg":"trace[368162234] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"205.509599ms","start":"2026-08-12T05:47:18.414351Z","end":"2026-08-12T05:47:18.619861Z","steps":["trace[368162234] 'process raft request' (duration: 205.365825ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:18.619928Z","caller":"traceutil/trace.go:171","msg":"trace[1695374411] transaction","detail":"{read_only:false; response_revision:26798; number_of_response:1; }","duration":"205.475418ms","start":"2026-08-12T05:47:18.414411Z","end":"2026-08-12T05:47:18.619886Z","steps":["trace[1695374411] 'process raft request' (duration: 205.356975ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:18.619923Z","caller":"traceutil/trace.go:171","msg":"trace[116961647] transaction","detail":"{read_only:false; response_revision:26799; number_of_response:1; }","duration":"147.769078ms","start":"2026-08-12T05:47:18.472101Z","end":"2026-08-12T05:47:18.61987Z","steps":["trace[116961647] 'process raft request' (duration: 147.708876ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:18.915964Z","caller":"traceutil/trace.go:171","msg":"trace[806604600] transaction","detail":"{read_only:false; response_revision:26800; number_of_response:1; }","duration":"137.631862ms","start":"2026-08-12T05:47:18.778249Z","end":"2026-08-12T05:47:18.91588Z","steps":["trace[806604600] 'process raft request' (duration: 130.067364ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:21.563235Z","caller":"traceutil/trace.go:171","msg":"trace[180984178] transaction","detail":"{read_only:false; response_revision:26816; number_of_response:1; }","duration":"174.868127ms","start":"2026-08-12T05:47:21.388336Z","end":"2026-08-12T05:47:21.563204Z","steps":["trace[180984178] 'process raft request' (duration: 174.711213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T05:47:21.864595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.656068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T05:47:21.864732Z","caller":"traceutil/trace.go:171","msg":"trace[1379038346] transaction","detail":"{read_only:false; response_revision:26817; number_of_response:1; }","duration":"459.489236ms","start":"2026-08-12T05:47:21.405208Z","end":"2026-08-12T05:47:21.864697Z","steps":["trace[1379038346] 'process raft request' (duration: 270.615652ms)","trace[1379038346] 'compare' (duration: 188.34171ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:47:21.864905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T05:47:21.405186Z","time spent":"459.61958ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T05:47:21.865001Z","caller":"traceutil/trace.go:171","msg":"trace[305501362] linearizableReadLoop","detail":"{readStateIndex:27696; appliedIndex:27695; }","duration":"261.066583ms","start":"2026-08-12T05:47:21.603914Z","end":"2026-08-12T05:47:21.864981Z","steps":["trace[305501362] 'read index received' (duration: 71.878482ms)","trace[305501362] 'applied index is now lower than readState.Index' (duration: 189.186731ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T05:47:21.865116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.189346ms","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:6"} {"level":"info","ts":"2026-08-12T05:47:21.865156Z","caller":"traceutil/trace.go:171","msg":"trace[553289657] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26818; }","duration":"261.269438ms","start":"2026-08-12T05:47:21.603874Z","end":"2026-08-12T05:47:21.865143Z","steps":["trace[553289657] 'agreement among raft nodes before linearized reading' (duration: 261.166856ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:21.967345Z","caller":"traceutil/trace.go:171","msg":"trace[1417792928] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"165.733768ms","start":"2026-08-12T05:47:21.801583Z","end":"2026-08-12T05:47:21.967316Z","steps":["trace[1417792928] 'process raft request' (duration: 163.222792ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:21.967605Z","caller":"traceutil/trace.go:171","msg":"trace[914413163] transaction","detail":"{read_only:false; response_revision:26820; number_of_response:1; }","duration":"164.845625ms","start":"2026-08-12T05:47:21.802747Z","end":"2026-08-12T05:47:21.967593Z","steps":["trace[914413163] 'process raft request' (duration: 164.487715ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:21.967848Z","caller":"traceutil/trace.go:171","msg":"trace[1323478055] transaction","detail":"{read_only:false; response_revision:26821; number_of_response:1; }","duration":"164.972308ms","start":"2026-08-12T05:47:21.802853Z","end":"2026-08-12T05:47:21.967825Z","steps":["trace[1323478055] 'process raft request' (duration: 164.708531ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:47:21.967962Z","caller":"traceutil/trace.go:171","msg":"trace[256640049] transaction","detail":"{read_only:false; response_revision:26822; number_of_response:1; }","duration":"162.744999ms","start":"2026-08-12T05:47:21.80518Z","end":"2026-08-12T05:47:21.967925Z","steps":["trace[256640049] 'process raft request' (duration: 162.559265ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T05:48:49.180351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24857} {"level":"info","ts":"2026-08-12T05:48:49.348713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24857,"took":"163.383566ms","hash":4120878761,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":36245504,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-12T05:48:49.348781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4120878761,"revision":24857,"compact-revision":21594} {"level":"info","ts":"2026-08-12T05:51:05.702653Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T05:51:05.713543Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-12T05:51:05.713683Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-12T05:53:49.187271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27698} {"level":"info","ts":"2026-08-12T05:53:49.339604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27698,"took":"147.039808ms","hash":2416766712,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":34205696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T05:53:49.339721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2416766712,"revision":27698,"compact-revision":24857} {"level":"info","ts":"2026-08-12T05:58:49.194824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30350} {"level":"info","ts":"2026-08-12T05:58:49.336477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30350,"took":"135.742957ms","hash":1573490995,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":30416896,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T05:58:49.336575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1573490995,"revision":30350,"compact-revision":27698} {"level":"info","ts":"2026-08-12T06:03:49.203092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32604} {"level":"info","ts":"2026-08-12T06:03:49.361887Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32604,"took":"153.496367ms","hash":3790796577,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":30437376,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T06:03:49.361985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3790796577,"revision":32604,"compact-revision":30350} {"level":"info","ts":"2026-08-12T06:08:49.21141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34901} {"level":"info","ts":"2026-08-12T06:08:49.361694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34901,"took":"144.922702ms","hash":826906600,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":30859264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T06:08:49.36179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":826906600,"revision":34901,"compact-revision":32604} {"level":"info","ts":"2026-08-12T06:12:12.535102Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T06:12:12.53978Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-12T06:12:12.540013Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-12T06:13:49.219071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37222} {"level":"info","ts":"2026-08-12T06:13:49.350641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37222,"took":"126.375605ms","hash":3184227810,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":32399360,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T06:13:49.350722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3184227810,"revision":37222,"compact-revision":34901} {"level":"info","ts":"2026-08-12T06:18:49.227024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39502} {"level":"info","ts":"2026-08-12T06:18:49.354845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39502,"took":"123.473078ms","hash":2880248293,"current-db-size-bytes":58839040,"current-db-size":"59 MB","current-db-size-in-use-bytes":31113216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T06:18:49.354932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2880248293,"revision":39502,"compact-revision":37222}