{"level":"warn","ts":"2026-09-04T00:23:41.133011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-04T00:23:41.133106Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.135: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://162.253.55.135:2380","--initial-cluster=instance=https://162.253.55.135:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.135:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.135: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-09-04T00:23:41.133177Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-04T00:23:41.133191Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.135:2380"]} {"level":"info","ts":"2026-09-04T00:23:41.133218Z","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-09-04T00:23:41.134036Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.135:2379"]} {"level":"info","ts":"2026-09-04T00:23:41.134137Z","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://162.253.55.135:2380"],"listen-peer-urls":["https://162.253.55.135:2380"],"advertise-client-urls":["https://162.253.55.135:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.135:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.135: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-09-04T00:23:41.138913Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.528531ms"} {"level":"info","ts":"2026-09-04T00:23:41.147265Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"36725369a67988c9","cluster-id":"458f7f83a0fed211"} {"level":"info","ts":"2026-09-04T00:23:41.14734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-04T00:23:41.147358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 became follower at term 0"} {"level":"info","ts":"2026-09-04T00:23:41.147376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 36725369a67988c9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-04T00:23:41.147386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 became follower at term 1"} {"level":"info","ts":"2026-09-04T00:23:41.147419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 switched to configuration voters=(3923289938622777545)"} {"level":"warn","ts":"2026-09-04T00:23:41.16288Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-04T00:23:41.164994Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-04T00:23:41.166966Z","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-09-04T00:23:41.170592Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"36725369a67988c9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-04T00:23:41.171134Z","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-09-04T00:23:41.171296Z","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-09-04T00:23:41.171332Z","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-09-04T00:23:41.171145Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"36725369a67988c9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-04T00:23:41.174009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 switched to configuration voters=(3923289938622777545)"} {"level":"info","ts":"2026-09-04T00:23:41.174199Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"458f7f83a0fed211","local-member-id":"36725369a67988c9","added-peer-id":"36725369a67988c9","added-peer-peer-urls":["https://162.253.55.135:2380"]} {"level":"info","ts":"2026-09-04T00:23:41.176024Z","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-09-04T00:23:41.17615Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.135:2380"} {"level":"info","ts":"2026-09-04T00:23:41.176216Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.135:2380"} {"level":"info","ts":"2026-09-04T00:23:41.176424Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"36725369a67988c9","initial-advertise-peer-urls":["https://162.253.55.135:2380"],"listen-peer-urls":["https://162.253.55.135:2380"],"advertise-client-urls":["https://162.253.55.135:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.135:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-04T00:23:41.176509Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-04T00:23:41.348181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-04T00:23:41.348241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-04T00:23:41.348276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 received MsgPreVoteResp from 36725369a67988c9 at term 1"} {"level":"info","ts":"2026-09-04T00:23:41.348295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 became candidate at term 2"} {"level":"info","ts":"2026-09-04T00:23:41.348306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 received MsgVoteResp from 36725369a67988c9 at term 2"} {"level":"info","ts":"2026-09-04T00:23:41.34833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36725369a67988c9 became leader at term 2"} {"level":"info","ts":"2026-09-04T00:23:41.348345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 36725369a67988c9 elected leader 36725369a67988c9 at term 2"} {"level":"info","ts":"2026-09-04T00:23:41.349491Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:23:41.350423Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"36725369a67988c9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.135:2379]}","request-path":"/0/members/36725369a67988c9/attributes","cluster-id":"458f7f83a0fed211","publish-timeout":"7s"} {"level":"info","ts":"2026-09-04T00:23:41.350437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T00:23:41.350512Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T00:23:41.35082Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-04T00:23:41.351028Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-04T00:23:41.353574Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-04T00:23:41.354235Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"458f7f83a0fed211","local-member-id":"36725369a67988c9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:23:41.354341Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:23:41.35438Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:23:41.35463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.135:2379"} {"level":"info","ts":"2026-09-04T00:23:57.444329Z","caller":"traceutil/trace.go:171","msg":"trace[1187396282] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"217.082631ms","start":"2026-09-04T00:23:57.227187Z","end":"2026-09-04T00:23:57.44427Z","steps":["trace[1187396282] 'process raft request' (duration: 216.943927ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:02.795557Z","caller":"traceutil/trace.go:171","msg":"trace[505737458] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"190.642914ms","start":"2026-09-04T00:24:02.604892Z","end":"2026-09-04T00:24:02.795535Z","steps":["trace[505737458] 'process raft request' (duration: 190.452468ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:08.204959Z","caller":"traceutil/trace.go:171","msg":"trace[1463939857] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"122.233518ms","start":"2026-09-04T00:24:08.082704Z","end":"2026-09-04T00:24:08.204937Z","steps":["trace[1463939857] 'process raft request' (duration: 122.048153ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:26.597399Z","caller":"traceutil/trace.go:171","msg":"trace[711756022] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"160.004822ms","start":"2026-09-04T00:24:26.437361Z","end":"2026-09-04T00:24:26.597365Z","steps":["trace[711756022] 'read index received' (duration: 157.371735ms)","trace[711756022] 'applied index is now lower than readState.Index' (duration: 2.631147ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:24:26.597465Z","caller":"traceutil/trace.go:171","msg":"trace[2074199990] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"199.409193ms","start":"2026-09-04T00:24:26.398021Z","end":"2026-09-04T00:24:26.59743Z","steps":["trace[2074199990] 'process raft request' (duration: 197.297751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:24:26.597633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.248469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:24:26.597805Z","caller":"traceutil/trace.go:171","msg":"trace[671245241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"160.466216ms","start":"2026-09-04T00:24:26.437311Z","end":"2026-09-04T00:24:26.597777Z","steps":["trace[671245241] 'agreement among raft nodes before linearized reading' (duration: 160.179297ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:27.027476Z","caller":"traceutil/trace.go:171","msg":"trace[753316093] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"345.534671ms","start":"2026-09-04T00:24:26.681897Z","end":"2026-09-04T00:24:27.027432Z","steps":["trace[753316093] 'process raft request' (duration: 345.332145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:24:27.027958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:24:26.681862Z","time spent":"345.700506ms","remote":"127.0.0.1:54872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-04T00:24:27.144067Z","caller":"traceutil/trace.go:171","msg":"trace[1643859246] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"104.033052ms","start":"2026-09-04T00:24:27.040004Z","end":"2026-09-04T00:24:27.144038Z","steps":["trace[1643859246] 'read index received' (duration: 14.199421ms)","trace[1643859246] 'applied index is now lower than readState.Index' (duration: 89.832641ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:24:27.144215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.185296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:24:27.144253Z","caller":"traceutil/trace.go:171","msg":"trace[543770411] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:640; }","duration":"104.281498ms","start":"2026-09-04T00:24:27.039959Z","end":"2026-09-04T00:24:27.14424Z","steps":["trace[543770411] 'agreement among raft nodes before linearized reading' (duration: 104.181166ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:27.144364Z","caller":"traceutil/trace.go:171","msg":"trace[569761529] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"112.445735ms","start":"2026-09-04T00:24:27.031878Z","end":"2026-09-04T00:24:27.144323Z","steps":["trace[569761529] 'process raft request' (duration: 22.416818ms)","trace[569761529] 'compare' (duration: 89.631895ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:25:12.680904Z","caller":"traceutil/trace.go:171","msg":"trace[543164018] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"103.328841ms","start":"2026-09-04T00:25:12.577554Z","end":"2026-09-04T00:25:12.680883Z","steps":["trace[543164018] 'process raft request' (duration: 103.184717ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:01.27776Z","caller":"traceutil/trace.go:171","msg":"trace[64971614] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"468.725239ms","start":"2026-09-04T00:26:00.809011Z","end":"2026-09-04T00:26:01.277737Z","steps":["trace[64971614] 'process raft request' (duration: 468.569534ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:01.277865Z","caller":"traceutil/trace.go:171","msg":"trace[1086392459] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1956; }","duration":"398.916936ms","start":"2026-09-04T00:26:00.878862Z","end":"2026-09-04T00:26:01.277779Z","steps":["trace[1086392459] 'read index received' (duration: 398.907566ms)","trace[1086392459] 'applied index is now lower than readState.Index' (duration: 7.29µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:26:01.278044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.160833ms","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":"warn","ts":"2026-09-04T00:26:01.278073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:00.808974Z","time spent":"468.861983ms","remote":"127.0.0.1:54706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10964,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:01.278134Z","caller":"traceutil/trace.go:171","msg":"trace[2092150458] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1886; }","duration":"399.295797ms","start":"2026-09-04T00:26:00.878817Z","end":"2026-09-04T00:26:01.278113Z","steps":["trace[2092150458] 'agreement among raft nodes before linearized reading' (duration: 399.087801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:01.278186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:00.878801Z","time spent":"399.374318ms","remote":"127.0.0.1:48108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-04T00:26:01.397971Z","caller":"traceutil/trace.go:171","msg":"trace[13867247] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"399.051129ms","start":"2026-09-04T00:26:00.998895Z","end":"2026-09-04T00:26:01.397946Z","steps":["trace[13867247] 'process raft request' (duration: 398.765681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:01.398139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:00.998869Z","time spent":"399.171083ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:01.398739Z","caller":"traceutil/trace.go:171","msg":"trace[587356078] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"191.667847ms","start":"2026-09-04T00:26:01.207004Z","end":"2026-09-04T00:26:01.398672Z","steps":["trace[587356078] 'process raft request' (duration: 190.855904ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:01.398567Z","caller":"traceutil/trace.go:171","msg":"trace[11504131] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1956; }","duration":"120.548025ms","start":"2026-09-04T00:26:01.278006Z","end":"2026-09-04T00:26:01.398554Z","steps":["trace[11504131] 'read index received' (duration: 119.596777ms)","trace[11504131] 'applied index is now lower than readState.Index' (duration: 950.318µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:26:01.399029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.713472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-04T00:26:01.399097Z","caller":"traceutil/trace.go:171","msg":"trace[1109907659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1888; }","duration":"406.854516ms","start":"2026-09-04T00:26:00.992227Z","end":"2026-09-04T00:26:01.399081Z","steps":["trace[1109907659] 'agreement among raft nodes before linearized reading' (duration: 406.63794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:01.399134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:00.992207Z","time spent":"406.918407ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-04T00:26:01.696921Z","caller":"traceutil/trace.go:171","msg":"trace[1529786806] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"411.351206ms","start":"2026-09-04T00:26:01.28553Z","end":"2026-09-04T00:26:01.696881Z","steps":["trace[1529786806] 'process raft request' (duration: 410.971825ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:01.696958Z","caller":"traceutil/trace.go:171","msg":"trace[1308099656] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1958; }","duration":"298.083212ms","start":"2026-09-04T00:26:01.398847Z","end":"2026-09-04T00:26:01.696931Z","steps":["trace[1308099656] 'read index received' (duration: 297.64323ms)","trace[1308099656] 'applied index is now lower than readState.Index' (duration: 438.032µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:01.697031Z","caller":"traceutil/trace.go:171","msg":"trace[1319231707] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"410.639195ms","start":"2026-09-04T00:26:01.286361Z","end":"2026-09-04T00:26:01.697Z","steps":["trace[1319231707] 'process raft request' (duration: 410.519242ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:01.697066Z","caller":"traceutil/trace.go:171","msg":"trace[963224638] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"411.212822ms","start":"2026-09-04T00:26:01.285819Z","end":"2026-09-04T00:26:01.697032Z","steps":["trace[963224638] 'process raft request' (duration: 411.010066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:01.697111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:01.285475Z","time spent":"411.530912ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1470,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:01.697199Z","caller":"traceutil/trace.go:171","msg":"trace[383391145] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"411.410468ms","start":"2026-09-04T00:26:01.285767Z","end":"2026-09-04T00:26:01.697177Z","steps":["trace[383391145] 'process raft request' (duration: 410.938714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:01.697231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:01.285809Z","time spent":"411.326525ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1528,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:26:01.697241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:01.28635Z","time spent":"410.754038ms","remote":"127.0.0.1:54690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":976,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:26:01.697316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:01.285743Z","time spent":"411.516131ms","remote":"127.0.0.1:54690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:26:01.697442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.776915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-054ab2c0-ca1c-41ba-a503-865fff5ab155\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-09-04T00:26:01.697261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.250071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-054ab2c0-ca1c-41ba-a503-865fff5ab155\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-04T00:26:01.697593Z","caller":"traceutil/trace.go:171","msg":"trace[2064685906] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-054ab2c0-ca1c-41ba-a503-865fff5ab155; range_end:; response_count:1; response_revision:1892; }","duration":"414.989782ms","start":"2026-09-04T00:26:01.282573Z","end":"2026-09-04T00:26:01.697563Z","steps":["trace[2064685906] 'agreement among raft nodes before linearized reading' (duration: 414.676423ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:01.697607Z","caller":"traceutil/trace.go:171","msg":"trace[1423961961] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-054ab2c0-ca1c-41ba-a503-865fff5ab155; range_end:; response_count:1; response_revision:1892; }","duration":"373.723315ms","start":"2026-09-04T00:26:01.323862Z","end":"2026-09-04T00:26:01.697585Z","steps":["trace[1423961961] 'agreement among raft nodes before linearized reading' (duration: 373.19914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:01.697685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:01.282543Z","time spent":"415.122386ms","remote":"127.0.0.1:54706","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-054ab2c0-ca1c-41ba-a503-865fff5ab155\" "} {"level":"warn","ts":"2026-09-04T00:26:01.697692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:01.323845Z","time spent":"373.828158ms","remote":"127.0.0.1:54706","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-054ab2c0-ca1c-41ba-a503-865fff5ab155\" "} {"level":"info","ts":"2026-09-04T00:26:01.803226Z","caller":"traceutil/trace.go:171","msg":"trace[150043127] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"106.148718ms","start":"2026-09-04T00:26:01.69703Z","end":"2026-09-04T00:26:01.803179Z","steps":["trace[150043127] 'read index received' (duration: 102.831541ms)","trace[150043127] 'applied index is now lower than readState.Index' (duration: 3.315476ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:26:01.803365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.6305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T00:26:01.803432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.487856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:26:01.803466Z","caller":"traceutil/trace.go:171","msg":"trace[373899404] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1893; }","duration":"166.838207ms","start":"2026-09-04T00:26:01.636601Z","end":"2026-09-04T00:26:01.80344Z","steps":["trace[373899404] 'agreement among raft nodes before linearized reading' (duration: 166.614041ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:01.803497Z","caller":"traceutil/trace.go:171","msg":"trace[1839820149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"366.622849ms","start":"2026-09-04T00:26:01.436857Z","end":"2026-09-04T00:26:01.80348Z","steps":["trace[1839820149] 'agreement among raft nodes before linearized reading' (duration: 366.487205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:01.803489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.84451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-04T00:26:01.803555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:01.43684Z","time spent":"366.701731ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-04T00:26:01.803589Z","caller":"traceutil/trace.go:171","msg":"trace[1651127360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1893; }","duration":"174.030226ms","start":"2026-09-04T00:26:01.629542Z","end":"2026-09-04T00:26:01.803573Z","steps":["trace[1651127360] 'agreement among raft nodes before linearized reading' (duration: 173.729627ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:01.803583Z","caller":"traceutil/trace.go:171","msg":"trace[2080610954] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"399.671407ms","start":"2026-09-04T00:26:01.403875Z","end":"2026-09-04T00:26:01.803547Z","steps":["trace[2080610954] 'process raft request' (duration: 395.9788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:01.803867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:01.403841Z","time spent":"399.907114ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:26:14.144068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.687427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:26:14.144274Z","caller":"traceutil/trace.go:171","msg":"trace[758351506] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"209.392261ms","start":"2026-09-04T00:26:13.934844Z","end":"2026-09-04T00:26:14.144236Z","steps":["trace[758351506] 'process raft request' (duration: 56.236951ms)","trace[758351506] 'compare' (duration: 152.44159ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:14.14433Z","caller":"traceutil/trace.go:171","msg":"trace[107410404] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"207.499376ms","start":"2026-09-04T00:26:13.936822Z","end":"2026-09-04T00:26:14.144322Z","steps":["trace[107410404] 'process raft request' (duration: 207.347001ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:14.144294Z","caller":"traceutil/trace.go:171","msg":"trace[294483938] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"207.374613ms","start":"2026-09-04T00:26:13.936906Z","end":"2026-09-04T00:26:14.14428Z","steps":["trace[294483938] 'process raft request' (duration: 207.323211ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:17.613978Z","caller":"traceutil/trace.go:171","msg":"trace[493405678] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"162.617784ms","start":"2026-09-04T00:26:17.451339Z","end":"2026-09-04T00:26:17.613957Z","steps":["trace[493405678] 'process raft request' (duration: 162.47122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:50.446792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.414885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-04T00:26:50.446881Z","caller":"traceutil/trace.go:171","msg":"trace[611023753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2208; }","duration":"123.546619ms","start":"2026-09-04T00:26:50.323317Z","end":"2026-09-04T00:26:50.446864Z","steps":["trace[611023753] 'agreement among raft nodes before linearized reading' (duration: 123.308862ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:50.446932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.907923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-04T00:26:50.44703Z","caller":"traceutil/trace.go:171","msg":"trace[1125852666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2208; }","duration":"120.049176ms","start":"2026-09-04T00:26:50.326961Z","end":"2026-09-04T00:26:50.44701Z","steps":["trace[1125852666] 'agreement among raft nodes before linearized reading' (duration: 119.637054ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:50.597804Z","caller":"traceutil/trace.go:171","msg":"trace[1092277702] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2290; }","duration":"151.212775ms","start":"2026-09-04T00:26:50.44657Z","end":"2026-09-04T00:26:50.597783Z","steps":["trace[1092277702] 'read index received' (duration: 132.710206ms)","trace[1092277702] 'applied index is now lower than readState.Index' (duration: 18.501629ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:50.597916Z","caller":"traceutil/trace.go:171","msg":"trace[205357706] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"159.554447ms","start":"2026-09-04T00:26:50.438349Z","end":"2026-09-04T00:26:50.597903Z","steps":["trace[205357706] 'process raft request' (duration: 140.966336ms)","trace[205357706] 'compare' (duration: 18.319283ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:26:50.598151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.618709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:26:50.598226Z","caller":"traceutil/trace.go:171","msg":"trace[328749688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2209; }","duration":"159.894317ms","start":"2026-09-04T00:26:50.438315Z","end":"2026-09-04T00:26:50.598209Z","steps":["trace[328749688] 'agreement among raft nodes before linearized reading' (duration: 159.626529ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:50.60728Z","caller":"traceutil/trace.go:171","msg":"trace[1800211659] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"155.53682ms","start":"2026-09-04T00:26:50.451728Z","end":"2026-09-04T00:26:50.607264Z","steps":["trace[1800211659] 'process raft request' (duration: 155.386076ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:50.607454Z","caller":"traceutil/trace.go:171","msg":"trace[1328143507] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"155.390826ms","start":"2026-09-04T00:26:50.452032Z","end":"2026-09-04T00:26:50.607423Z","steps":["trace[1328143507] 'process raft request' (duration: 155.168939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:50.60766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.728526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-04T00:26:50.607737Z","caller":"traceutil/trace.go:171","msg":"trace[178124857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2211; }","duration":"144.821518ms","start":"2026-09-04T00:26:50.462905Z","end":"2026-09-04T00:26:50.607726Z","steps":["trace[178124857] 'agreement among raft nodes before linearized reading' (duration: 144.52571ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:50.791101Z","caller":"traceutil/trace.go:171","msg":"trace[1607076593] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"112.721883ms","start":"2026-09-04T00:26:50.67833Z","end":"2026-09-04T00:26:50.791051Z","steps":["trace[1607076593] 'process raft request' (duration: 24.546895ms)","trace[1607076593] 'compare' (duration: 87.850899ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:51.079134Z","caller":"traceutil/trace.go:171","msg":"trace[1305572143] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"106.78322ms","start":"2026-09-04T00:26:50.972317Z","end":"2026-09-04T00:26:51.0791Z","steps":["trace[1305572143] 'process raft request' (duration: 106.599685ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:56.779153Z","caller":"traceutil/trace.go:171","msg":"trace[225982799] linearizableReadLoop","detail":"{readStateIndex:2324; appliedIndex:2323; }","duration":"149.768562ms","start":"2026-09-04T00:26:56.629301Z","end":"2026-09-04T00:26:56.779069Z","steps":["trace[225982799] 'read index received' (duration: 143.580602ms)","trace[225982799] 'applied index is now lower than readState.Index' (duration: 6.18662ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:56.779197Z","caller":"traceutil/trace.go:171","msg":"trace[332868374] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"160.360231ms","start":"2026-09-04T00:26:56.618772Z","end":"2026-09-04T00:26:56.779132Z","steps":["trace[332868374] 'process raft request' (duration: 154.090648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:56.779385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.06437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-04T00:26:56.779464Z","caller":"traceutil/trace.go:171","msg":"trace[333033192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2241; }","duration":"150.207235ms","start":"2026-09-04T00:26:56.62924Z","end":"2026-09-04T00:26:56.779447Z","steps":["trace[333033192] 'agreement among raft nodes before linearized reading' (duration: 150.013649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:56.779562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.950517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-04T00:26:56.779636Z","caller":"traceutil/trace.go:171","msg":"trace[804719685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2241; }","duration":"150.084011ms","start":"2026-09-04T00:26:56.629531Z","end":"2026-09-04T00:26:56.779615Z","steps":["trace[804719685] 'agreement among raft nodes before linearized reading' (duration: 149.814913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:56.779675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.973141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-04T00:26:56.779731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.500235ms","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-09-04T00:26:56.77977Z","caller":"traceutil/trace.go:171","msg":"trace[2006127941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2241; }","duration":"124.158116ms","start":"2026-09-04T00:26:56.655592Z","end":"2026-09-04T00:26:56.77975Z","steps":["trace[2006127941] 'agreement among raft nodes before linearized reading' (duration: 123.882148ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:56.779805Z","caller":"traceutil/trace.go:171","msg":"trace[2072820126] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2241; }","duration":"102.616359ms","start":"2026-09-04T00:26:56.677166Z","end":"2026-09-04T00:26:56.779783Z","steps":["trace[2072820126] 'agreement among raft nodes before linearized reading' (duration: 102.3065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:57.127987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.573823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:26:57.128168Z","caller":"traceutil/trace.go:171","msg":"trace[1967463731] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"344.07459ms","start":"2026-09-04T00:26:56.784064Z","end":"2026-09-04T00:26:57.128138Z","steps":["trace[1967463731] 'process raft request' (duration: 122.23185ms)","trace[1967463731] 'compare' (duration: 221.248773ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:26:57.12833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:56.784036Z","time spent":"344.176723ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:57.219152Z","caller":"traceutil/trace.go:171","msg":"trace[135036408] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"434.961728ms","start":"2026-09-04T00:26:56.784157Z","end":"2026-09-04T00:26:57.219118Z","steps":["trace[135036408] 'process raft request' (duration: 434.784283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:57.219324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:56.78413Z","time spent":"435.101372ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:57.219383Z","caller":"traceutil/trace.go:171","msg":"trace[39646250] linearizableReadLoop","detail":"{readStateIndex:2327; appliedIndex:2324; }","duration":"407.186179ms","start":"2026-09-04T00:26:56.812163Z","end":"2026-09-04T00:26:57.21935Z","steps":["trace[39646250] 'read index received' (duration: 94.148712ms)","trace[39646250] 'applied index is now lower than readState.Index' (duration: 313.035087ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:57.219418Z","caller":"traceutil/trace.go:171","msg":"trace[1844755559] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"435.03795ms","start":"2026-09-04T00:26:56.784358Z","end":"2026-09-04T00:26:57.219396Z","steps":["trace[1844755559] 'process raft request' (duration: 434.717281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:57.219628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:56.784343Z","time spent":"435.145263ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:26:57.219753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.54748ms","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-09-04T00:26:57.219851Z","caller":"traceutil/trace.go:171","msg":"trace[1694710623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2244; }","duration":"407.711015ms","start":"2026-09-04T00:26:56.812115Z","end":"2026-09-04T00:26:57.219826Z","steps":["trace[1694710623] 'agreement among raft nodes before linearized reading' (duration: 407.352304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:57.219914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:56.812099Z","time spent":"407.800207ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-04T00:27:33.110429Z","caller":"traceutil/trace.go:171","msg":"trace[924185199] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"211.559721ms","start":"2026-09-04T00:27:32.898838Z","end":"2026-09-04T00:27:33.110398Z","steps":["trace[924185199] 'process raft request' (duration: 211.390686ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:30:50.534369Z","caller":"traceutil/trace.go:171","msg":"trace[46743155] transaction","detail":"{read_only:false; response_revision:3819; number_of_response:1; }","duration":"100.412246ms","start":"2026-09-04T00:30:50.433935Z","end":"2026-09-04T00:30:50.534347Z","steps":["trace[46743155] 'process raft request' (duration: 97.211403ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:46.979251Z","caller":"traceutil/trace.go:171","msg":"trace[1849468395] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"118.584812ms","start":"2026-09-04T00:32:46.860639Z","end":"2026-09-04T00:32:46.979224Z","steps":["trace[1849468395] 'process raft request' (duration: 118.004935ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:46.999478Z","caller":"traceutil/trace.go:171","msg":"trace[1463071416] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5178; }","duration":"129.60584ms","start":"2026-09-04T00:32:46.869859Z","end":"2026-09-04T00:32:46.999465Z","steps":["trace[1463071416] 'read index received' (duration: 109.764967ms)","trace[1463071416] 'applied index is now lower than readState.Index' (duration: 19.839643ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:32:47.000069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.160196ms","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-09-04T00:32:47.000113Z","caller":"traceutil/trace.go:171","msg":"trace[1851106540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4990; }","duration":"130.269718ms","start":"2026-09-04T00:32:46.869833Z","end":"2026-09-04T00:32:47.000103Z","steps":["trace[1851106540] 'agreement among raft nodes before linearized reading' (duration: 130.091213ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:46.999712Z","caller":"traceutil/trace.go:171","msg":"trace[386928137] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"133.270765ms","start":"2026-09-04T00:32:46.866433Z","end":"2026-09-04T00:32:46.999704Z","steps":["trace[386928137] 'process raft request' (duration: 132.897104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:32:47.010978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.980538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-04T00:32:47.011034Z","caller":"traceutil/trace.go:171","msg":"trace[203310416] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4991; }","duration":"140.063031ms","start":"2026-09-04T00:32:46.870944Z","end":"2026-09-04T00:32:47.011007Z","steps":["trace[203310416] 'agreement among raft nodes before linearized reading' (duration: 139.925617ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:53.17542Z","caller":"traceutil/trace.go:171","msg":"trace[1088248052] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"104.242318ms","start":"2026-09-04T00:32:53.071153Z","end":"2026-09-04T00:32:53.175395Z","steps":["trace[1088248052] 'process raft request' (duration: 92.799568ms)","trace[1088248052] 'compare' (duration: 11.335317ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:32:53.187469Z","caller":"traceutil/trace.go:171","msg":"trace[551618457] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"116.283535ms","start":"2026-09-04T00:32:53.071167Z","end":"2026-09-04T00:32:53.187451Z","steps":["trace[551618457] 'process raft request' (duration: 116.075969ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:53.187621Z","caller":"traceutil/trace.go:171","msg":"trace[1544961276] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"115.100301ms","start":"2026-09-04T00:32:53.072483Z","end":"2026-09-04T00:32:53.187584Z","steps":["trace[1544961276] 'process raft request' (duration: 114.854254ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:53.187815Z","caller":"traceutil/trace.go:171","msg":"trace[2082352433] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5325; }","duration":"104.543637ms","start":"2026-09-04T00:32:53.083251Z","end":"2026-09-04T00:32:53.187795Z","steps":["trace[2082352433] 'read index received' (duration: 80.794842ms)","trace[2082352433] 'applied index is now lower than readState.Index' (duration: 23.747355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:32:53.188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.727432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-04T00:32:53.188068Z","caller":"traceutil/trace.go:171","msg":"trace[882076404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5135; }","duration":"104.849175ms","start":"2026-09-04T00:32:53.083208Z","end":"2026-09-04T00:32:53.188057Z","steps":["trace[882076404] 'agreement among raft nodes before linearized reading' (duration: 104.66028ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:55.928525Z","caller":"traceutil/trace.go:171","msg":"trace[570275484] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"102.466806ms","start":"2026-09-04T00:32:55.826006Z","end":"2026-09-04T00:32:55.928472Z","steps":["trace[570275484] 'process raft request' (duration: 102.25716ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:58.388981Z","caller":"traceutil/trace.go:171","msg":"trace[1798435177] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"140.138893ms","start":"2026-09-04T00:32:58.24881Z","end":"2026-09-04T00:32:58.388949Z","steps":["trace[1798435177] 'process raft request' (duration: 139.997529ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:58.560888Z","caller":"traceutil/trace.go:171","msg":"trace[1678362244] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"239.603323ms","start":"2026-09-04T00:32:58.321241Z","end":"2026-09-04T00:32:58.560845Z","steps":["trace[1678362244] 'process raft request' (duration: 139.902106ms)","trace[1678362244] 'compare' (duration: 99.438469ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:32:58.561097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.094985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1467bfcd-7481-41bb-8f51-439381bb738d\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-09-04T00:32:58.561147Z","caller":"traceutil/trace.go:171","msg":"trace[252862585] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1467bfcd-7481-41bb-8f51-439381bb738d; range_end:; response_count:1; response_revision:5286; }","duration":"207.195818ms","start":"2026-09-04T00:32:58.353941Z","end":"2026-09-04T00:32:58.561137Z","steps":["trace[252862585] 'agreement among raft nodes before linearized reading' (duration: 206.985002ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:58.560865Z","caller":"traceutil/trace.go:171","msg":"trace[1682187139] linearizableReadLoop","detail":"{readStateIndex:5484; appliedIndex:5482; }","duration":"206.836298ms","start":"2026-09-04T00:32:58.353979Z","end":"2026-09-04T00:32:58.560815Z","steps":["trace[1682187139] 'read index received' (duration: 34.824225ms)","trace[1682187139] 'applied index is now lower than readState.Index' (duration: 172.010853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:32:58.561941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.670033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787116"} {"level":"info","ts":"2026-09-04T00:32:58.561982Z","caller":"traceutil/trace.go:171","msg":"trace[1221173168] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5286; }","duration":"153.755076ms","start":"2026-09-04T00:32:58.408216Z","end":"2026-09-04T00:32:58.561971Z","steps":["trace[1221173168] 'agreement among raft nodes before linearized reading' (duration: 153.372955ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:21.359994Z","caller":"traceutil/trace.go:171","msg":"trace[8247878] linearizableReadLoop","detail":"{readStateIndex:5908; appliedIndex:5907; }","duration":"154.840867ms","start":"2026-09-04T00:33:21.205128Z","end":"2026-09-04T00:33:21.359969Z","steps":["trace[8247878] 'read index received' (duration: 154.759765ms)","trace[8247878] 'applied index is now lower than readState.Index' (duration: 79.752µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:21.360165Z","caller":"traceutil/trace.go:171","msg":"trace[1000115719] transaction","detail":"{read_only:false; response_revision:5699; number_of_response:1; }","duration":"167.977626ms","start":"2026-09-04T00:33:21.192128Z","end":"2026-09-04T00:33:21.360106Z","steps":["trace[1000115719] 'process raft request' (duration: 167.676588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:21.36024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.085955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-04T00:33:21.360275Z","caller":"traceutil/trace.go:171","msg":"trace[1740747518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5699; }","duration":"155.142727ms","start":"2026-09-04T00:33:21.205121Z","end":"2026-09-04T00:33:21.360264Z","steps":["trace[1740747518] 'agreement among raft nodes before linearized reading' (duration: 154.980682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:21.535162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.105841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:33:21.535392Z","caller":"traceutil/trace.go:171","msg":"trace[1473368749] transaction","detail":"{read_only:false; response_revision:5700; number_of_response:1; }","duration":"230.282484ms","start":"2026-09-04T00:33:21.305064Z","end":"2026-09-04T00:33:21.535346Z","steps":["trace[1473368749] 'process raft request' (duration: 88.865494ms)","trace[1473368749] 'compare' (duration: 140.956967ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:21.535487Z","caller":"traceutil/trace.go:171","msg":"trace[808057614] linearizableReadLoop","detail":"{readStateIndex:5909; appliedIndex:5908; }","duration":"171.799326ms","start":"2026-09-04T00:33:21.363652Z","end":"2026-09-04T00:33:21.535451Z","steps":["trace[808057614] 'read index received' (duration: 30.279983ms)","trace[808057614] 'applied index is now lower than readState.Index' (duration: 141.516303ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:21.535427Z","caller":"traceutil/trace.go:171","msg":"trace[1580167483] transaction","detail":"{read_only:false; response_revision:5701; number_of_response:1; }","duration":"170.87955ms","start":"2026-09-04T00:33:21.364526Z","end":"2026-09-04T00:33:21.535405Z","steps":["trace[1580167483] 'process raft request' (duration: 170.807728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:21.535795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.128186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-04T00:33:21.535846Z","caller":"traceutil/trace.go:171","msg":"trace[244375059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5701; }","duration":"172.218398ms","start":"2026-09-04T00:33:21.363613Z","end":"2026-09-04T00:33:21.535832Z","steps":["trace[244375059] 'agreement among raft nodes before linearized reading' (duration: 172.025473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:21.53596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.508825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T00:33:21.535966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.815137ms","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-09-04T00:33:21.536013Z","caller":"traceutil/trace.go:171","msg":"trace[1424552727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5701; }","duration":"121.65584ms","start":"2026-09-04T00:33:21.414342Z","end":"2026-09-04T00:33:21.535998Z","steps":["trace[1424552727] 'agreement among raft nodes before linearized reading' (duration: 121.534256ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:21.536024Z","caller":"traceutil/trace.go:171","msg":"trace[554110664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5701; }","duration":"171.91676ms","start":"2026-09-04T00:33:21.364091Z","end":"2026-09-04T00:33:21.536008Z","steps":["trace[554110664] 'agreement among raft nodes before linearized reading' (duration: 171.675823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:21.536181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.07625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:33:21.536241Z","caller":"traceutil/trace.go:171","msg":"trace[2106022846] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5701; }","duration":"150.144111ms","start":"2026-09-04T00:33:21.38608Z","end":"2026-09-04T00:33:21.536224Z","steps":["trace[2106022846] 'agreement among raft nodes before linearized reading' (duration: 150.057089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:21.536515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.646203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-04T00:33:21.536596Z","caller":"traceutil/trace.go:171","msg":"trace[89979445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5701; }","duration":"170.751246ms","start":"2026-09-04T00:33:21.365831Z","end":"2026-09-04T00:33:21.536582Z","steps":["trace[89979445] 'agreement among raft nodes before linearized reading' (duration: 170.374415ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:21.798919Z","caller":"traceutil/trace.go:171","msg":"trace[150474170] transaction","detail":"{read_only:false; response_revision:5704; number_of_response:1; }","duration":"255.017628ms","start":"2026-09-04T00:33:21.543872Z","end":"2026-09-04T00:33:21.798889Z","steps":["trace[150474170] 'process raft request' (duration: 254.925336ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:21.799029Z","caller":"traceutil/trace.go:171","msg":"trace[87650298] transaction","detail":"{read_only:false; response_revision:5702; number_of_response:1; }","duration":"257.940823ms","start":"2026-09-04T00:33:21.541067Z","end":"2026-09-04T00:33:21.799007Z","steps":["trace[87650298] 'process raft request' (duration: 157.609478ms)","trace[87650298] 'compare' (duration: 99.843161ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:21.799196Z","caller":"traceutil/trace.go:171","msg":"trace[1238780193] transaction","detail":"{read_only:false; response_revision:5703; number_of_response:1; }","duration":"257.765977ms","start":"2026-09-04T00:33:21.541403Z","end":"2026-09-04T00:33:21.799169Z","steps":["trace[1238780193] 'process raft request' (duration: 257.303904ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:21.799422Z","caller":"traceutil/trace.go:171","msg":"trace[1289998] transaction","detail":"{read_only:false; response_revision:5705; number_of_response:1; }","duration":"217.745892ms","start":"2026-09-04T00:33:21.581659Z","end":"2026-09-04T00:33:21.799404Z","steps":["trace[1289998] 'process raft request' (duration: 217.190166ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:21.799822Z","caller":"traceutil/trace.go:171","msg":"trace[2104560928] linearizableReadLoop","detail":"{readStateIndex:5915; appliedIndex:5910; }","duration":"175.291977ms","start":"2026-09-04T00:33:21.624504Z","end":"2026-09-04T00:33:21.799796Z","steps":["trace[2104560928] 'read index received' (duration: 74.261752ms)","trace[2104560928] 'applied index is now lower than readState.Index' (duration: 101.028505ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:21.799865Z","caller":"traceutil/trace.go:171","msg":"trace[1671491016] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"207.305821ms","start":"2026-09-04T00:33:21.592543Z","end":"2026-09-04T00:33:21.799849Z","steps":["trace[1671491016] 'process raft request' (duration: 206.560169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:21.800098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.569846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tl6fw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-04T00:33:21.80016Z","caller":"traceutil/trace.go:171","msg":"trace[1208333026] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tl6fw; range_end:; response_count:1; response_revision:5706; }","duration":"175.680398ms","start":"2026-09-04T00:33:21.624466Z","end":"2026-09-04T00:33:21.800147Z","steps":["trace[1208333026] 'agreement among raft nodes before linearized reading' (duration: 175.424051ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:33.506523Z","caller":"traceutil/trace.go:171","msg":"trace[819837650] transaction","detail":"{read_only:false; response_revision:5853; number_of_response:1; }","duration":"116.583224ms","start":"2026-09-04T00:33:33.38991Z","end":"2026-09-04T00:33:33.506493Z","steps":["trace[819837650] 'process raft request' (duration: 38.285975ms)","trace[819837650] 'compare' (duration: 77.952409ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:33.506635Z","caller":"traceutil/trace.go:171","msg":"trace[983838213] transaction","detail":"{read_only:false; response_revision:5855; number_of_response:1; }","duration":"115.967586ms","start":"2026-09-04T00:33:33.390634Z","end":"2026-09-04T00:33:33.506602Z","steps":["trace[983838213] 'process raft request' (duration: 115.726129ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:33.506644Z","caller":"traceutil/trace.go:171","msg":"trace[1374795423] transaction","detail":"{read_only:false; response_revision:5856; number_of_response:1; }","duration":"115.862033ms","start":"2026-09-04T00:33:33.390758Z","end":"2026-09-04T00:33:33.50662Z","steps":["trace[1374795423] 'process raft request' (duration: 115.633796ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:33.50655Z","caller":"traceutil/trace.go:171","msg":"trace[1961095448] transaction","detail":"{read_only:false; response_revision:5854; number_of_response:1; }","duration":"115.973006ms","start":"2026-09-04T00:33:33.390539Z","end":"2026-09-04T00:33:33.506512Z","steps":["trace[1961095448] 'process raft request' (duration: 115.76288ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:41.949777Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3012} {"level":"info","ts":"2026-09-04T00:33:42.041729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3012,"took":"90.521252ms","hash":3908925610,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":30343168,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-04T00:33:42.041793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3908925610,"revision":3012,"compact-revision":-1} {"level":"info","ts":"2026-09-04T00:38:41.962134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5930} {"level":"info","ts":"2026-09-04T00:38:42.05031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5930,"took":"85.926332ms","hash":394614903,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":26595328,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-04T00:38:42.050387Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":394614903,"revision":5930,"compact-revision":3012} {"level":"info","ts":"2026-09-04T00:39:48.867949Z","caller":"traceutil/trace.go:171","msg":"trace[1675241920] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"102.939968ms","start":"2026-09-04T00:39:48.764985Z","end":"2026-09-04T00:39:48.867925Z","steps":["trace[1675241920] 'process raft request' (duration: 102.769643ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:39:53.142098Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002313.wal"} {"level":"warn","ts":"2026-09-04T00:40:00.340444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.988368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:40:00.340634Z","caller":"traceutil/trace.go:171","msg":"trace[1598594712] linearizableReadLoop","detail":"{readStateIndex:9017; appliedIndex:9015; }","duration":"179.677502ms","start":"2026-09-04T00:40:00.16094Z","end":"2026-09-04T00:40:00.340618Z","steps":["trace[1598594712] 'read index received' (duration: 67.322µs)","trace[1598594712] 'applied index is now lower than readState.Index' (duration: 179.60909ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:40:00.340743Z","caller":"traceutil/trace.go:171","msg":"trace[476380887] transaction","detail":"{read_only:false; response_revision:8713; number_of_response:1; }","duration":"329.276839ms","start":"2026-09-04T00:40:00.011429Z","end":"2026-09-04T00:40:00.340706Z","steps":["trace[476380887] 'process raft request' (duration: 329.124985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:00.340778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.842238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tl6fw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-04T00:40:00.340752Z","caller":"traceutil/trace.go:171","msg":"trace[792940362] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"329.30781ms","start":"2026-09-04T00:40:00.01141Z","end":"2026-09-04T00:40:00.340718Z","steps":["trace[792940362] 'process raft request' (duration: 41.946982ms)","trace[792940362] 'compare' (duration: 286.695379ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:40:00.340821Z","caller":"traceutil/trace.go:171","msg":"trace[1274136838] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tl6fw; range_end:; response_count:1; response_revision:8713; }","duration":"179.92386ms","start":"2026-09-04T00:40:00.160885Z","end":"2026-09-04T00:40:00.340809Z","steps":["trace[1274136838] 'agreement among raft nodes before linearized reading' (duration: 179.780716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:00.340903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:40:00.011402Z","time spent":"329.417303ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:40:00.340909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:40:00.011384Z","time spent":"329.450275ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:40:00.355682Z","caller":"traceutil/trace.go:171","msg":"trace[957871366] transaction","detail":"{read_only:false; response_revision:8714; number_of_response:1; }","duration":"166.840688ms","start":"2026-09-04T00:40:00.188823Z","end":"2026-09-04T00:40:00.355664Z","steps":["trace[957871366] 'process raft request' (duration: 166.746456ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:00.498059Z","caller":"traceutil/trace.go:171","msg":"trace[1139925103] linearizableReadLoop","detail":"{readStateIndex:9019; appliedIndex:9017; }","duration":"151.925265ms","start":"2026-09-04T00:40:00.34611Z","end":"2026-09-04T00:40:00.498036Z","steps":["trace[1139925103] 'read index received' (duration: 9.458426ms)","trace[1139925103] 'applied index is now lower than readState.Index' (duration: 142.465359ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:40:00.49857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.44128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224134"} {"level":"info","ts":"2026-09-04T00:40:00.498646Z","caller":"traceutil/trace.go:171","msg":"trace[735417066] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8714; }","duration":"152.532622ms","start":"2026-09-04T00:40:00.346096Z","end":"2026-09-04T00:40:00.498629Z","steps":["trace[735417066] 'agreement among raft nodes before linearized reading' (duration: 152.066318ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:01.243752Z","caller":"traceutil/trace.go:171","msg":"trace[132730528] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"172.509764ms","start":"2026-09-04T00:40:01.071219Z","end":"2026-09-04T00:40:01.243729Z","steps":["trace[132730528] 'process raft request' (duration: 172.36609ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:01.524719Z","caller":"traceutil/trace.go:171","msg":"trace[1193541055] transaction","detail":"{read_only:false; response_revision:8717; number_of_response:1; }","duration":"190.616481ms","start":"2026-09-04T00:40:01.334042Z","end":"2026-09-04T00:40:01.524659Z","steps":["trace[1193541055] 'process raft request' (duration: 153.878952ms)","trace[1193541055] 'compare' (duration: 36.39961ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:40:01.524765Z","caller":"traceutil/trace.go:171","msg":"trace[1676700256] transaction","detail":"{read_only:false; response_revision:8718; number_of_response:1; }","duration":"182.918587ms","start":"2026-09-04T00:40:01.341822Z","end":"2026-09-04T00:40:01.52474Z","steps":["trace[1676700256] 'process raft request' (duration: 182.827554ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:01.524961Z","caller":"traceutil/trace.go:171","msg":"trace[992393555] linearizableReadLoop","detail":"{readStateIndex:9022; appliedIndex:9021; }","duration":"186.270904ms","start":"2026-09-04T00:40:01.338661Z","end":"2026-09-04T00:40:01.524932Z","steps":["trace[992393555] 'read index received' (duration: 149.252736ms)","trace[992393555] 'applied index is now lower than readState.Index' (duration: 37.016548ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:40:01.525155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.473031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:40:01.525217Z","caller":"traceutil/trace.go:171","msg":"trace[6496343] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8718; }","duration":"186.565494ms","start":"2026-09-04T00:40:01.338617Z","end":"2026-09-04T00:40:01.525183Z","steps":["trace[6496343] 'agreement among raft nodes before linearized reading' (duration: 186.478741ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:01.714403Z","caller":"traceutil/trace.go:171","msg":"trace[1313111620] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"100.60603ms","start":"2026-09-04T00:40:01.613751Z","end":"2026-09-04T00:40:01.714357Z","steps":["trace[1313111620] 'process raft request' (duration: 31.400435ms)","trace[1313111620] 'compare' (duration: 68.998409ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:40:02.393824Z","caller":"traceutil/trace.go:171","msg":"trace[155154550] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"107.307225ms","start":"2026-09-04T00:40:02.286489Z","end":"2026-09-04T00:40:02.393796Z","steps":["trace[155154550] 'process raft request' (duration: 107.075798ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:02.472139Z","caller":"traceutil/trace.go:171","msg":"trace[939152734] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"111.449415ms","start":"2026-09-04T00:40:02.360652Z","end":"2026-09-04T00:40:02.472101Z","steps":["trace[939152734] 'process raft request' (duration: 65.309391ms)","trace[939152734] 'compare' (duration: 45.910658ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:40:02.472427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.480603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-04T00:40:02.472499Z","caller":"traceutil/trace.go:171","msg":"trace[1314825889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8723; }","duration":"127.603836ms","start":"2026-09-04T00:40:02.344878Z","end":"2026-09-04T00:40:02.472482Z","steps":["trace[1314825889] 'agreement among raft nodes before linearized reading' (duration: 127.38318ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:27.731263Z","caller":"traceutil/trace.go:171","msg":"trace[737822854] linearizableReadLoop","detail":"{readStateIndex:9225; appliedIndex:9223; }","duration":"101.069053ms","start":"2026-09-04T00:40:27.630162Z","end":"2026-09-04T00:40:27.731231Z","steps":["trace[737822854] 'read index received' (duration: 31.93749ms)","trace[737822854] 'applied index is now lower than readState.Index' (duration: 69.130263ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:40:27.731315Z","caller":"traceutil/trace.go:171","msg":"trace[2055115482] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"110.049855ms","start":"2026-09-04T00:40:27.621227Z","end":"2026-09-04T00:40:27.731277Z","steps":["trace[2055115482] 'process raft request' (duration: 109.845769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:27.731515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.30796ms","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-09-04T00:40:27.731617Z","caller":"traceutil/trace.go:171","msg":"trace[184671728] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8912; }","duration":"101.475805ms","start":"2026-09-04T00:40:27.630118Z","end":"2026-09-04T00:40:27.731593Z","steps":["trace[184671728] 'agreement among raft nodes before linearized reading' (duration: 101.28068ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:30.43666Z","caller":"traceutil/trace.go:171","msg":"trace[1263930070] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"239.389151ms","start":"2026-09-04T00:40:30.19724Z","end":"2026-09-04T00:40:30.436629Z","steps":["trace[1263930070] 'process raft request' (duration: 239.226337ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:30.688549Z","caller":"traceutil/trace.go:171","msg":"trace[1840630939] linearizableReadLoop","detail":"{readStateIndex:9240; appliedIndex:9239; }","duration":"250.895557ms","start":"2026-09-04T00:40:30.437629Z","end":"2026-09-04T00:40:30.688525Z","steps":["trace[1840630939] 'read index received' (duration: 248.91908ms)","trace[1840630939] 'applied index is now lower than readState.Index' (duration: 1.974847ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:40:30.688757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.106713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:40:30.688863Z","caller":"traceutil/trace.go:171","msg":"trace[641202672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8926; }","duration":"251.265127ms","start":"2026-09-04T00:40:30.437566Z","end":"2026-09-04T00:40:30.688831Z","steps":["trace[641202672] 'agreement among raft nodes before linearized reading' (duration: 251.074722ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:30.688944Z","caller":"traceutil/trace.go:171","msg":"trace[638248491] transaction","detail":"{read_only:false; response_revision:8927; number_of_response:1; }","duration":"107.274554ms","start":"2026-09-04T00:40:30.581652Z","end":"2026-09-04T00:40:30.688926Z","steps":["trace[638248491] 'process raft request' (duration: 106.742849ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:33.81746Z","caller":"traceutil/trace.go:171","msg":"trace[1648311093] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"131.899601ms","start":"2026-09-04T00:40:33.685542Z","end":"2026-09-04T00:40:33.817441Z","steps":["trace[1648311093] 'process raft request' (duration: 131.781267ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:34.080035Z","caller":"traceutil/trace.go:171","msg":"trace[1885296433] linearizableReadLoop","detail":"{readStateIndex:9258; appliedIndex:9257; }","duration":"222.472019ms","start":"2026-09-04T00:40:33.857533Z","end":"2026-09-04T00:40:34.080005Z","steps":["trace[1885296433] 'read index received' (duration: 124.372352ms)","trace[1885296433] 'applied index is now lower than readState.Index' (duration: 98.098107ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:40:34.080263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.710946ms","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-09-04T00:40:34.080248Z","caller":"traceutil/trace.go:171","msg":"trace[1896652696] transaction","detail":"{read_only:false; response_revision:8944; number_of_response:1; }","duration":"332.443132ms","start":"2026-09-04T00:40:33.74775Z","end":"2026-09-04T00:40:34.080193Z","steps":["trace[1896652696] 'process raft request' (duration: 234.16642ms)","trace[1896652696] 'compare' (duration: 97.912051ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:40:34.080303Z","caller":"traceutil/trace.go:171","msg":"trace[378535949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8944; }","duration":"222.824589ms","start":"2026-09-04T00:40:33.857467Z","end":"2026-09-04T00:40:34.080291Z","steps":["trace[378535949] 'agreement among raft nodes before linearized reading' (duration: 222.641073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:34.08039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:40:33.747726Z","time spent":"332.588985ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:40:42.258901Z","caller":"traceutil/trace.go:171","msg":"trace[818277409] transaction","detail":"{read_only:false; response_revision:8988; number_of_response:1; }","duration":"153.110175ms","start":"2026-09-04T00:40:42.105738Z","end":"2026-09-04T00:40:42.258848Z","steps":["trace[818277409] 'process raft request' (duration: 152.798956ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:42.384375Z","caller":"traceutil/trace.go:171","msg":"trace[1613834996] transaction","detail":"{read_only:false; response_revision:8989; number_of_response:1; }","duration":"121.833706ms","start":"2026-09-04T00:40:42.262475Z","end":"2026-09-04T00:40:42.384309Z","steps":["trace[1613834996] 'process raft request' (duration: 85.754769ms)","trace[1613834996] 'compare' (duration: 35.689296ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:40:42.894583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.357436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:40:42.894887Z","caller":"traceutil/trace.go:171","msg":"trace[583917031] linearizableReadLoop","detail":"{readStateIndex:9310; appliedIndex:9308; }","duration":"125.015918ms","start":"2026-09-04T00:40:42.769848Z","end":"2026-09-04T00:40:42.894864Z","steps":["trace[583917031] 'read index received' (duration: 6.221211ms)","trace[583917031] 'applied index is now lower than readState.Index' (duration: 118.792897ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:40:42.894917Z","caller":"traceutil/trace.go:171","msg":"trace[685916749] transaction","detail":"{read_only:false; response_revision:8993; number_of_response:1; }","duration":"126.728358ms","start":"2026-09-04T00:40:42.76815Z","end":"2026-09-04T00:40:42.894878Z","steps":["trace[685916749] 'compare' (duration: 118.113288ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:42.894959Z","caller":"traceutil/trace.go:171","msg":"trace[1869838871] transaction","detail":"{read_only:false; response_revision:8994; number_of_response:1; }","duration":"126.44702ms","start":"2026-09-04T00:40:42.76847Z","end":"2026-09-04T00:40:42.894917Z","steps":["trace[1869838871] 'process raft request' (duration: 126.267555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:42.895088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.221564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tl6fw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-04T00:40:42.895165Z","caller":"traceutil/trace.go:171","msg":"trace[1784663464] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tl6fw; range_end:; response_count:1; response_revision:8994; }","duration":"125.344448ms","start":"2026-09-04T00:40:42.769804Z","end":"2026-09-04T00:40:42.895148Z","steps":["trace[1784663464] 'agreement among raft nodes before linearized reading' (duration: 125.118151ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:46.536455Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"36725369a67988c9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T00:41:46.544861Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-04T00:41:46.544961Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-04T00:42:13.595533Z","caller":"traceutil/trace.go:171","msg":"trace[1600045756] linearizableReadLoop","detail":"{readStateIndex:10196; appliedIndex:10195; }","duration":"175.888835ms","start":"2026-09-04T00:42:13.41962Z","end":"2026-09-04T00:42:13.595509Z","steps":["trace[1600045756] 'read index received' (duration: 175.655488ms)","trace[1600045756] 'applied index is now lower than readState.Index' (duration: 231.987µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:42:13.595602Z","caller":"traceutil/trace.go:171","msg":"trace[1106529351] transaction","detail":"{read_only:false; response_revision:9856; number_of_response:1; }","duration":"179.366726ms","start":"2026-09-04T00:42:13.416201Z","end":"2026-09-04T00:42:13.595568Z","steps":["trace[1106529351] 'process raft request' (duration: 179.126199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:42:13.595763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.117682ms","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-09-04T00:42:13.596319Z","caller":"traceutil/trace.go:171","msg":"trace[970218869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9856; }","duration":"176.216885ms","start":"2026-09-04T00:42:13.419576Z","end":"2026-09-04T00:42:13.595793Z","steps":["trace[970218869] 'agreement among raft nodes before linearized reading' (duration: 176.067891ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:43:41.97062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8087} {"level":"info","ts":"2026-09-04T00:43:42.041472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8087,"took":"68.828391ms","hash":1251808239,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":27967488,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T00:43:42.041635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1251808239,"revision":8087,"compact-revision":5930} {"level":"info","ts":"2026-09-04T00:47:51.594157Z","caller":"traceutil/trace.go:171","msg":"trace[1049161703] transaction","detail":"{read_only:false; response_revision:12284; number_of_response:1; }","duration":"116.642316ms","start":"2026-09-04T00:47:51.477486Z","end":"2026-09-04T00:47:51.594128Z","steps":["trace[1049161703] 'process raft request' (duration: 58.136307ms)","trace[1049161703] 'compare' (duration: 58.381765ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:47:52.483769Z","caller":"traceutil/trace.go:171","msg":"trace[349813379] transaction","detail":"{read_only:false; response_revision:12286; number_of_response:1; }","duration":"145.674678ms","start":"2026-09-04T00:47:52.338069Z","end":"2026-09-04T00:47:52.483744Z","steps":["trace[349813379] 'process raft request' (duration: 145.483442ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:41.977219Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10453} {"level":"info","ts":"2026-09-04T00:48:42.063114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10453,"took":"83.354059ms","hash":3710816690,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":25841664,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-04T00:48:42.06323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3710816690,"revision":10453,"compact-revision":8087} {"level":"info","ts":"2026-09-04T00:53:41.984255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12632} {"level":"info","ts":"2026-09-04T00:53:42.064619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12632,"took":"77.38276ms","hash":2230619040,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":27697152,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T00:53:42.064687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2230619040,"revision":12632,"compact-revision":10453} {"level":"info","ts":"2026-09-04T00:54:36.936344Z","caller":"traceutil/trace.go:171","msg":"trace[755620310] transaction","detail":"{read_only:false; response_revision:14964; number_of_response:1; }","duration":"130.008262ms","start":"2026-09-04T00:54:36.806301Z","end":"2026-09-04T00:54:36.93631Z","steps":["trace[755620310] 'process raft request' (duration: 129.807936ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:54:37.085928Z","caller":"traceutil/trace.go:171","msg":"trace[999119931] transaction","detail":"{read_only:false; response_revision:14965; number_of_response:1; }","duration":"164.747642ms","start":"2026-09-04T00:54:36.921147Z","end":"2026-09-04T00:54:37.085895Z","steps":["trace[999119931] 'process raft request' (duration: 164.534146ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:54:46.20555Z","caller":"traceutil/trace.go:171","msg":"trace[1112088548] transaction","detail":"{read_only:false; response_revision:15012; number_of_response:1; }","duration":"101.698878ms","start":"2026-09-04T00:54:46.103784Z","end":"2026-09-04T00:54:46.205483Z","steps":["trace[1112088548] 'process raft request' (duration: 101.4199ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:54:55.914516Z","caller":"traceutil/trace.go:171","msg":"trace[1423743336] linearizableReadLoop","detail":"{readStateIndex:15595; appliedIndex:15594; }","duration":"155.249425ms","start":"2026-09-04T00:54:55.759237Z","end":"2026-09-04T00:54:55.914486Z","steps":["trace[1423743336] 'read index received' (duration: 155.0575ms)","trace[1423743336] 'applied index is now lower than readState.Index' (duration: 190.675µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:54:55.914612Z","caller":"traceutil/trace.go:171","msg":"trace[960314740] transaction","detail":"{read_only:false; response_revision:15076; number_of_response:1; }","duration":"357.627453ms","start":"2026-09-04T00:54:55.556948Z","end":"2026-09-04T00:54:55.914575Z","steps":["trace[960314740] 'process raft request' (duration: 357.304273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:54:55.914778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:54:55.556907Z","time spent":"357.775357ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:54:55.914816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.548974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-04T00:54:55.914857Z","caller":"traceutil/trace.go:171","msg":"trace[879479355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15076; }","duration":"155.651037ms","start":"2026-09-04T00:54:55.759192Z","end":"2026-09-04T00:54:55.914843Z","steps":["trace[879479355] 'agreement among raft nodes before linearized reading' (duration: 155.459141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:54:55.914914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.656487ms","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-09-04T00:54:55.915014Z","caller":"traceutil/trace.go:171","msg":"trace[1026748670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15076; }","duration":"155.812561ms","start":"2026-09-04T00:54:55.759181Z","end":"2026-09-04T00:54:55.914993Z","steps":["trace[1026748670] 'agreement among raft nodes before linearized reading' (duration: 155.44717ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:54:56.138648Z","caller":"traceutil/trace.go:171","msg":"trace[2013667767] transaction","detail":"{read_only:false; response_revision:15079; number_of_response:1; }","duration":"218.549988ms","start":"2026-09-04T00:54:55.920073Z","end":"2026-09-04T00:54:56.138623Z","steps":["trace[2013667767] 'process raft request' (duration: 218.469955ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:54:56.138925Z","caller":"traceutil/trace.go:171","msg":"trace[402754212] transaction","detail":"{read_only:false; response_revision:15078; number_of_response:1; }","duration":"218.885958ms","start":"2026-09-04T00:54:55.920028Z","end":"2026-09-04T00:54:56.138914Z","steps":["trace[402754212] 'process raft request' (duration: 218.463776ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:54:56.138929Z","caller":"traceutil/trace.go:171","msg":"trace[2004881620] linearizableReadLoop","detail":"{readStateIndex:15596; appliedIndex:15595; }","duration":"224.280204ms","start":"2026-09-04T00:54:55.914609Z","end":"2026-09-04T00:54:56.138889Z","steps":["trace[2004881620] 'read index received' (duration: 206.25524ms)","trace[2004881620] 'applied index is now lower than readState.Index' (duration: 18.021544ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:54:56.138979Z","caller":"traceutil/trace.go:171","msg":"trace[439205082] transaction","detail":"{read_only:false; response_revision:15077; number_of_response:1; }","duration":"329.955108ms","start":"2026-09-04T00:54:55.808966Z","end":"2026-09-04T00:54:56.138921Z","steps":["trace[439205082] 'process raft request' (duration: 311.882963ms)","trace[439205082] 'compare' (duration: 17.465588ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:54:56.139195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.767071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tl6fw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-04T00:54:56.139237Z","caller":"traceutil/trace.go:171","msg":"trace[158520144] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tl6fw; range_end:; response_count:1; response_revision:15079; }","duration":"287.870224ms","start":"2026-09-04T00:54:55.851354Z","end":"2026-09-04T00:54:56.139224Z","steps":["trace[158520144] 'agreement among raft nodes before linearized reading' (duration: 287.635037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:54:56.139236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:54:55.80893Z","time spent":"330.159554ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:54:56.139254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.463887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-04T00:54:56.139326Z","caller":"traceutil/trace.go:171","msg":"trace[171865232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15079; }","duration":"282.58267ms","start":"2026-09-04T00:54:55.856719Z","end":"2026-09-04T00:54:56.139302Z","steps":["trace[171865232] 'agreement among raft nodes before linearized reading' (duration: 282.318073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:55:07.473431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.888172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-wvxtt.18d1f8173ce4686e\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-09-04T00:55:07.473477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.131243ms","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-09-04T00:55:07.473544Z","caller":"traceutil/trace.go:171","msg":"trace[496837873] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-wvxtt.18d1f8173ce4686e; range_end:; response_count:1; response_revision:15256; }","duration":"213.063067ms","start":"2026-09-04T00:55:07.260461Z","end":"2026-09-04T00:55:07.473524Z","steps":["trace[496837873] 'range keys from in-memory index tree' (duration: 212.745688ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:55:07.473581Z","caller":"traceutil/trace.go:171","msg":"trace[1806841550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15256; }","duration":"363.280968ms","start":"2026-09-04T00:55:07.11028Z","end":"2026-09-04T00:55:07.473561Z","steps":["trace[1806841550] 'range keys from in-memory index tree' (duration: 362.946438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:55:07.473633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:55:07.110262Z","time spent":"363.362289ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-04T00:55:07.473836Z","caller":"traceutil/trace.go:171","msg":"trace[1314352757] transaction","detail":"{read_only:false; response_revision:15257; number_of_response:1; }","duration":"211.589875ms","start":"2026-09-04T00:55:07.262218Z","end":"2026-09-04T00:55:07.473808Z","steps":["trace[1314352757] 'process raft request' (duration: 186.623869ms)","trace[1314352757] 'compare' (duration: 24.344268ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:55:07.473836Z","caller":"traceutil/trace.go:171","msg":"trace[1649401220] linearizableReadLoop","detail":"{readStateIndex:15779; appliedIndex:15778; }","duration":"183.735105ms","start":"2026-09-04T00:55:07.290043Z","end":"2026-09-04T00:55:07.473779Z","steps":["trace[1649401220] 'read index received' (duration: 158.82981ms)","trace[1649401220] 'applied index is now lower than readState.Index' (duration: 24.903555ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:55:07.47406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.997902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-666b06f8-59f7-45ed-9525-da7639a66b24\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-09-04T00:55:07.474124Z","caller":"traceutil/trace.go:171","msg":"trace[72921506] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-666b06f8-59f7-45ed-9525-da7639a66b24; range_end:; response_count:1; response_revision:15257; }","duration":"184.112025ms","start":"2026-09-04T00:55:07.289993Z","end":"2026-09-04T00:55:07.474105Z","steps":["trace[72921506] 'agreement among raft nodes before linearized reading' (duration: 183.897979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:15.573685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.729231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-04T00:56:15.573773Z","caller":"traceutil/trace.go:171","msg":"trace[689800219] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16175; }","duration":"103.871625ms","start":"2026-09-04T00:56:15.469883Z","end":"2026-09-04T00:56:15.573755Z","steps":["trace[689800219] 'range keys from in-memory index tree' (duration: 103.549305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:15.573839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.620898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:56:15.573926Z","caller":"traceutil/trace.go:171","msg":"trace[463680449] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16175; }","duration":"103.758952ms","start":"2026-09-04T00:56:15.470145Z","end":"2026-09-04T00:56:15.573904Z","steps":["trace[463680449] 'range keys from in-memory index tree' (duration: 103.499724ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:49.977154Z","caller":"traceutil/trace.go:171","msg":"trace[766460871] transaction","detail":"{read_only:false; response_revision:16353; number_of_response:1; }","duration":"106.296415ms","start":"2026-09-04T00:56:49.870833Z","end":"2026-09-04T00:56:49.977129Z","steps":["trace[766460871] 'process raft request' (duration: 106.087839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:50.150697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.115398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-04T00:56:50.150851Z","caller":"traceutil/trace.go:171","msg":"trace[1519897259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16353; }","duration":"143.336245ms","start":"2026-09-04T00:56:50.007468Z","end":"2026-09-04T00:56:50.150804Z","steps":["trace[1519897259] 'range keys from in-memory index tree' (duration: 142.83439ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:50.343314Z","caller":"traceutil/trace.go:171","msg":"trace[148758083] transaction","detail":"{read_only:false; response_revision:16354; number_of_response:1; }","duration":"188.339484ms","start":"2026-09-04T00:56:50.154943Z","end":"2026-09-04T00:56:50.343283Z","steps":["trace[148758083] 'process raft request' (duration: 188.153268ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:50.344232Z","caller":"traceutil/trace.go:171","msg":"trace[1533924147] transaction","detail":"{read_only:false; response_revision:16355; number_of_response:1; }","duration":"110.804536ms","start":"2026-09-04T00:56:50.233401Z","end":"2026-09-04T00:56:50.344205Z","steps":["trace[1533924147] 'process raft request' (duration: 110.678072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:50.741587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.169455ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-04T00:56:50.783363Z","caller":"traceutil/trace.go:171","msg":"trace[1787497011] transaction","detail":"{read_only:false; response_revision:16357; number_of_response:1; }","duration":"119.541732ms","start":"2026-09-04T00:56:50.663793Z","end":"2026-09-04T00:56:50.783335Z","steps":["trace[1787497011] 'process raft request' (duration: 119.357026ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:50.911136Z","caller":"traceutil/trace.go:171","msg":"trace[103205024] transaction","detail":"{read_only:false; response_revision:16360; number_of_response:1; }","duration":"111.276021ms","start":"2026-09-04T00:56:50.799843Z","end":"2026-09-04T00:56:50.911119Z","steps":["trace[103205024] 'process raft request' (duration: 111.190248ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:50.911128Z","caller":"traceutil/trace.go:171","msg":"trace[91989939] transaction","detail":"{read_only:false; response_revision:16359; number_of_response:1; }","duration":"114.69083ms","start":"2026-09-04T00:56:50.79641Z","end":"2026-09-04T00:56:50.911101Z","steps":["trace[91989939] 'process raft request' (duration: 114.501005ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:56.511992Z","caller":"traceutil/trace.go:171","msg":"trace[1609382927] linearizableReadLoop","detail":"{readStateIndex:16944; appliedIndex:16943; }","duration":"270.744834ms","start":"2026-09-04T00:56:56.241222Z","end":"2026-09-04T00:56:56.511967Z","steps":["trace[1609382927] 'read index received' (duration: 270.658992ms)","trace[1609382927] 'applied index is now lower than readState.Index' (duration: 84.442µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:56:56.512031Z","caller":"traceutil/trace.go:171","msg":"trace[1349690145] transaction","detail":"{read_only:false; response_revision:16386; number_of_response:1; }","duration":"272.814474ms","start":"2026-09-04T00:56:56.239184Z","end":"2026-09-04T00:56:56.511999Z","steps":["trace[1349690145] 'process raft request' (duration: 272.608398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.512165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.926179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T00:56:56.512231Z","caller":"traceutil/trace.go:171","msg":"trace[698495463] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16386; }","duration":"271.042803ms","start":"2026-09-04T00:56:56.241175Z","end":"2026-09-04T00:56:56.512218Z","steps":["trace[698495463] 'agreement among raft nodes before linearized reading' (duration: 270.900509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.685761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.610606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T00:56:56.686008Z","caller":"traceutil/trace.go:171","msg":"trace[1937075557] linearizableReadLoop","detail":"{readStateIndex:16946; appliedIndex:16944; }","duration":"173.930335ms","start":"2026-09-04T00:56:56.512055Z","end":"2026-09-04T00:56:56.685985Z","steps":["trace[1937075557] 'read index received' (duration: 56.829935ms)","trace[1937075557] 'applied index is now lower than readState.Index' (duration: 117.09903ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:56:56.686034Z","caller":"traceutil/trace.go:171","msg":"trace[1093742950] transaction","detail":"{read_only:false; response_revision:16388; number_of_response:1; }","duration":"193.748683ms","start":"2026-09-04T00:56:56.492259Z","end":"2026-09-04T00:56:56.686008Z","steps":["trace[1093742950] 'process raft request' (duration: 193.633639ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:56.68608Z","caller":"traceutil/trace.go:171","msg":"trace[1687038442] transaction","detail":"{read_only:false; response_revision:16387; number_of_response:1; }","duration":"414.385347ms","start":"2026-09-04T00:56:56.271674Z","end":"2026-09-04T00:56:56.68606Z","steps":["trace[1687038442] 'process raft request' (duration: 297.355559ms)","trace[1687038442] 'compare' (duration: 116.339508ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:56:56.686159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.467506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-04T00:56:56.686173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:56:56.271649Z","time spent":"414.456759ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:56:56.686214Z","caller":"traceutil/trace.go:171","msg":"trace[699284635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16388; }","duration":"247.563399ms","start":"2026-09-04T00:56:56.438634Z","end":"2026-09-04T00:56:56.686197Z","steps":["trace[699284635] 'agreement among raft nodes before linearized reading' (duration: 247.393614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.686346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.151317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-csxsk\" ","response":"range_response_count:1 size:9789"} {"level":"warn","ts":"2026-09-04T00:56:56.686384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.504096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-04T00:56:56.686407Z","caller":"traceutil/trace.go:171","msg":"trace[2120969858] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-csxsk; range_end:; response_count:1; response_revision:16388; }","duration":"145.24036ms","start":"2026-09-04T00:56:56.541149Z","end":"2026-09-04T00:56:56.68639Z","steps":["trace[2120969858] 'agreement among raft nodes before linearized reading' (duration: 145.017843ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:56.686482Z","caller":"traceutil/trace.go:171","msg":"trace[1559423930] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16388; }","duration":"168.657012ms","start":"2026-09-04T00:56:56.517804Z","end":"2026-09-04T00:56:56.686461Z","steps":["trace[1559423930] 'agreement among raft nodes before linearized reading' (duration: 168.349113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.686961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.457149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-04T00:56:56.687057Z","caller":"traceutil/trace.go:171","msg":"trace[1756654443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16388; }","duration":"313.580332ms","start":"2026-09-04T00:56:56.373427Z","end":"2026-09-04T00:56:56.687008Z","steps":["trace[1756654443] 'agreement among raft nodes before linearized reading' (duration: 312.631074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.687152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:56:56.373409Z","time spent":"313.706136ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-04T00:56:56.920258Z","caller":"traceutil/trace.go:171","msg":"trace[1972666067] transaction","detail":"{read_only:false; response_revision:16390; number_of_response:1; }","duration":"115.690239ms","start":"2026-09-04T00:56:56.804538Z","end":"2026-09-04T00:56:56.920228Z","steps":["trace[1972666067] 'process raft request' (duration: 30.928371ms)","trace[1972666067] 'compare' (duration: 84.539672ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:58:41.991755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14633} {"level":"info","ts":"2026-09-04T00:58:42.080943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14633,"took":"86.369825ms","hash":2716283145,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":31526912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-04T00:58:42.081017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2716283145,"revision":14633,"compact-revision":12632} {"level":"info","ts":"2026-09-04T00:59:55.742787Z","caller":"traceutil/trace.go:171","msg":"trace[2094429211] transaction","detail":"{read_only:false; response_revision:17807; number_of_response:1; }","duration":"102.579527ms","start":"2026-09-04T00:59:55.640181Z","end":"2026-09-04T00:59:55.74276Z","steps":["trace[2094429211] 'process raft request' (duration: 102.290939ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:25.020586Z","caller":"traceutil/trace.go:171","msg":"trace[506732038] transaction","detail":"{read_only:false; response_revision:18846; number_of_response:1; }","duration":"188.470124ms","start":"2026-09-04T01:02:24.832076Z","end":"2026-09-04T01:02:25.020546Z","steps":["trace[506732038] 'process raft request' (duration: 116.881163ms)","trace[506732038] 'compare' (duration: 71.336064ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:02:25.020588Z","caller":"traceutil/trace.go:171","msg":"trace[886140453] linearizableReadLoop","detail":"{readStateIndex:19484; appliedIndex:19483; }","duration":"187.284129ms","start":"2026-09-04T01:02:24.83325Z","end":"2026-09-04T01:02:25.020534Z","steps":["trace[886140453] 'read index received' (duration: 115.579985ms)","trace[886140453] 'applied index is now lower than readState.Index' (duration: 71.702324ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:02:25.021962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.67974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:955917"} {"level":"info","ts":"2026-09-04T01:02:25.022065Z","caller":"traceutil/trace.go:171","msg":"trace[1775067252] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:18846; }","duration":"188.875806ms","start":"2026-09-04T01:02:24.83317Z","end":"2026-09-04T01:02:25.022046Z","steps":["trace[1775067252] 'agreement among raft nodes before linearized reading' (duration: 187.424404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:25.232886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.348113ms","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-09-04T01:02:25.233361Z","caller":"traceutil/trace.go:171","msg":"trace[554943403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18846; }","duration":"108.871099ms","start":"2026-09-04T01:02:25.124459Z","end":"2026-09-04T01:02:25.23333Z","steps":["trace[554943403] 'range keys from in-memory index tree' (duration: 108.191379ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:32.655897Z","caller":"traceutil/trace.go:171","msg":"trace[309938545] linearizableReadLoop","detail":"{readStateIndex:19551; appliedIndex:19550; }","duration":"173.493897ms","start":"2026-09-04T01:02:32.482378Z","end":"2026-09-04T01:02:32.655872Z","steps":["trace[309938545] 'read index received' (duration: 119.035946ms)","trace[309938545] 'applied index is now lower than readState.Index' (duration: 54.456971ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:02:32.655977Z","caller":"traceutil/trace.go:171","msg":"trace[1455561951] transaction","detail":"{read_only:false; response_revision:18911; number_of_response:1; }","duration":"176.908156ms","start":"2026-09-04T01:02:32.479034Z","end":"2026-09-04T01:02:32.655943Z","steps":["trace[1455561951] 'process raft request' (duration: 122.382933ms)","trace[1455561951] 'compare' (duration: 54.328307ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:02:32.656105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.918608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-04T01:02:32.656138Z","caller":"traceutil/trace.go:171","msg":"trace[98106132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18911; }","duration":"149.98827ms","start":"2026-09-04T01:02:32.506141Z","end":"2026-09-04T01:02:32.656129Z","steps":["trace[98106132] 'agreement among raft nodes before linearized reading' (duration: 149.936259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:32.65605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.653631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T01:02:32.656322Z","caller":"traceutil/trace.go:171","msg":"trace[417740124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18911; }","duration":"173.94411ms","start":"2026-09-04T01:02:32.482339Z","end":"2026-09-04T01:02:32.656283Z","steps":["trace[417740124] 'agreement among raft nodes before linearized reading' (duration: 173.662611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:33.197309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.698012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T01:02:33.197401Z","caller":"traceutil/trace.go:171","msg":"trace[754495264] linearizableReadLoop","detail":"{readStateIndex:19554; appliedIndex:19553; }","duration":"140.569935ms","start":"2026-09-04T01:02:33.056816Z","end":"2026-09-04T01:02:33.197386Z","steps":["trace[754495264] 'read index received' (duration: 34.781µs)","trace[754495264] 'applied index is now lower than readState.Index' (duration: 140.534064ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:02:33.197461Z","caller":"traceutil/trace.go:171","msg":"trace[972934813] transaction","detail":"{read_only:false; response_revision:18914; number_of_response:1; }","duration":"334.421616ms","start":"2026-09-04T01:02:32.863028Z","end":"2026-09-04T01:02:33.19745Z","steps":["trace[972934813] 'process raft request' (duration: 47.476036ms)","trace[972934813] 'compare' (duration: 286.353373ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:02:33.197628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:02:32.863015Z","time spent":"334.543689ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T01:02:33.197637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.815102ms","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-09-04T01:02:33.197686Z","caller":"traceutil/trace.go:171","msg":"trace[757778177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18914; }","duration":"140.897334ms","start":"2026-09-04T01:02:33.056774Z","end":"2026-09-04T01:02:33.197671Z","steps":["trace[757778177] 'agreement among raft nodes before linearized reading' (duration: 140.809052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:33.681574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.319963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T01:02:33.6817Z","caller":"traceutil/trace.go:171","msg":"trace[211846138] linearizableReadLoop","detail":"{readStateIndex:19557; appliedIndex:19556; }","duration":"412.216948ms","start":"2026-09-04T01:02:33.26945Z","end":"2026-09-04T01:02:33.681667Z","steps":["trace[211846138] 'read index received' (duration: 276.988428ms)","trace[211846138] 'applied index is now lower than readState.Index' (duration: 135.22652ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:02:33.681738Z","caller":"traceutil/trace.go:171","msg":"trace[423511469] transaction","detail":"{read_only:false; response_revision:18917; number_of_response:1; }","duration":"413.731042ms","start":"2026-09-04T01:02:33.267965Z","end":"2026-09-04T01:02:33.681696Z","steps":["trace[423511469] 'process raft request' (duration: 278.720349ms)","trace[423511469] 'compare' (duration: 134.183219ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:02:33.681871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:02:33.267949Z","time spent":"413.863546ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T01:02:33.681912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.454025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-33c13aa1-0464-40be-8c35-26719c169e39\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-09-04T01:02:33.681949Z","caller":"traceutil/trace.go:171","msg":"trace[1410762661] range","detail":"{range_begin:/registry/persistentvolumes/pvc-33c13aa1-0464-40be-8c35-26719c169e39; range_end:; response_count:1; response_revision:18917; }","duration":"412.522587ms","start":"2026-09-04T01:02:33.269412Z","end":"2026-09-04T01:02:33.681934Z","steps":["trace[1410762661] 'agreement among raft nodes before linearized reading' (duration: 412.368163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:33.68199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:02:33.269395Z","time spent":"412.586059ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-33c13aa1-0464-40be-8c35-26719c169e39\" "} {"level":"warn","ts":"2026-09-04T01:02:33.68219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.784146ms","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-09-04T01:02:33.68227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.83471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T01:02:33.682307Z","caller":"traceutil/trace.go:171","msg":"trace[1914192149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18917; }","duration":"244.905862ms","start":"2026-09-04T01:02:33.43739Z","end":"2026-09-04T01:02:33.682296Z","steps":["trace[1914192149] 'agreement among raft nodes before linearized reading' (duration: 244.84351ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:33.682294Z","caller":"traceutil/trace.go:171","msg":"trace[795396236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18917; }","duration":"410.93281ms","start":"2026-09-04T01:02:33.271342Z","end":"2026-09-04T01:02:33.682275Z","steps":["trace[795396236] 'agreement among raft nodes before linearized reading' (duration: 410.678873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:33.682366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:02:33.271323Z","time spent":"411.030774ms","remote":"127.0.0.1:54786","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-09-04T01:02:33.682361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.981706ms","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-09-04T01:02:33.684116Z","caller":"traceutil/trace.go:171","msg":"trace[1028148988] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:18917; }","duration":"406.785229ms","start":"2026-09-04T01:02:33.277309Z","end":"2026-09-04T01:02:33.684095Z","steps":["trace[1028148988] 'agreement among raft nodes before linearized reading' (duration: 405.003817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:33.684179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:02:33.277293Z","time spent":"406.863881ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-09-04T01:02:33.927333Z","caller":"traceutil/trace.go:171","msg":"trace[220880044] linearizableReadLoop","detail":"{readStateIndex:19559; appliedIndex:19559; }","duration":"194.000735ms","start":"2026-09-04T01:02:33.733307Z","end":"2026-09-04T01:02:33.927308Z","steps":["trace[220880044] 'read index received' (duration: 193.990064ms)","trace[220880044] 'applied index is now lower than readState.Index' (duration: 8.671µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:02:33.927486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.160729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-04T01:02:33.927511Z","caller":"traceutil/trace.go:171","msg":"trace[419703355] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:18919; }","duration":"194.247101ms","start":"2026-09-04T01:02:33.733254Z","end":"2026-09-04T01:02:33.927501Z","steps":["trace[419703355] 'agreement among raft nodes before linearized reading' (duration: 194.152419ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:33.927639Z","caller":"traceutil/trace.go:171","msg":"trace[1643853340] transaction","detail":"{read_only:false; response_revision:18919; number_of_response:1; }","duration":"226.117882ms","start":"2026-09-04T01:02:33.700768Z","end":"2026-09-04T01:02:33.926886Z","steps":["trace[1643853340] 'process raft request' (duration: 225.933667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:33.960395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.307869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T01:02:33.960482Z","caller":"traceutil/trace.go:171","msg":"trace[572710499] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:18920; }","duration":"212.468514ms","start":"2026-09-04T01:02:33.74799Z","end":"2026-09-04T01:02:33.960459Z","steps":["trace[572710499] 'agreement among raft nodes before linearized reading' (duration: 212.274388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:02:33.960818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.164074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-09-04T01:02:33.960903Z","caller":"traceutil/trace.go:171","msg":"trace[1459258886] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18920; }","duration":"188.283527ms","start":"2026-09-04T01:02:33.7726Z","end":"2026-09-04T01:02:33.960884Z","steps":["trace[1459258886] 'agreement among raft nodes before linearized reading' (duration: 188.098572ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:41.992771Z","caller":"traceutil/trace.go:171","msg":"trace[1817278670] transaction","detail":"{read_only:false; response_revision:19001; number_of_response:1; }","duration":"113.327899ms","start":"2026-09-04T01:02:41.879422Z","end":"2026-09-04T01:02:41.992749Z","steps":["trace[1817278670] 'process raft request' (duration: 113.163765ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:42.001729Z","caller":"traceutil/trace.go:171","msg":"trace[2032282072] transaction","detail":"{read_only:false; response_revision:19002; number_of_response:1; }","duration":"121.612522ms","start":"2026-09-04T01:02:41.880088Z","end":"2026-09-04T01:02:42.0017Z","steps":["trace[2032282072] 'process raft request' (duration: 121.342204ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:42.001755Z","caller":"traceutil/trace.go:171","msg":"trace[1113741637] transaction","detail":"{read_only:false; response_revision:19003; number_of_response:1; }","duration":"121.570351ms","start":"2026-09-04T01:02:41.880164Z","end":"2026-09-04T01:02:42.001734Z","steps":["trace[1113741637] 'process raft request' (duration: 121.423957ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:42.001807Z","caller":"traceutil/trace.go:171","msg":"trace[383778411] transaction","detail":"{read_only:false; response_revision:19004; number_of_response:1; }","duration":"121.542549ms","start":"2026-09-04T01:02:41.880242Z","end":"2026-09-04T01:02:42.001785Z","steps":["trace[383778411] 'process raft request' (duration: 121.384325ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:42.001935Z","caller":"traceutil/trace.go:171","msg":"trace[596416465] transaction","detail":"{read_only:false; response_revision:19005; number_of_response:1; }","duration":"121.137618ms","start":"2026-09-04T01:02:41.880781Z","end":"2026-09-04T01:02:42.001919Z","steps":["trace[596416465] 'process raft request' (duration: 120.884311ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:42.001942Z","caller":"traceutil/trace.go:171","msg":"trace[1418049111] transaction","detail":"{read_only:false; response_revision:19006; number_of_response:1; }","duration":"121.062526ms","start":"2026-09-04T01:02:41.880869Z","end":"2026-09-04T01:02:42.001932Z","steps":["trace[1418049111] 'process raft request' (duration: 120.828429ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:02:42.001934Z","caller":"traceutil/trace.go:171","msg":"trace[1314675350] transaction","detail":"{read_only:false; response_revision:19007; number_of_response:1; }","duration":"116.213464ms","start":"2026-09-04T01:02:41.88569Z","end":"2026-09-04T01:02:42.001903Z","steps":["trace[1314675350] 'process raft request' (duration: 116.039869ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:03:17.457319Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"36725369a67988c9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T01:03:17.516933Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-04T01:03:17.517074Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-04T01:03:41.999227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17062} {"level":"info","ts":"2026-09-04T01:03:42.104766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17062,"took":"102.38118ms","hash":2803205914,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":34254848,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-04T01:03:42.104857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2803205914,"revision":17062,"compact-revision":14633} {"level":"info","ts":"2026-09-04T01:08:42.007226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19698} {"level":"info","ts":"2026-09-04T01:08:42.129482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19698,"took":"118.177468ms","hash":3838058124,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":30777344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-04T01:08:42.129568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3838058124,"revision":19698,"compact-revision":17062} {"level":"warn","ts":"2026-09-04T01:08:51.941928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.265169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-04T01:08:51.942036Z","caller":"traceutil/trace.go:171","msg":"trace[637126318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21842; }","duration":"259.441264ms","start":"2026-09-04T01:08:51.682571Z","end":"2026-09-04T01:08:51.942012Z","steps":["trace[637126318] 'range keys from in-memory index tree' (duration: 259.091933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:08:51.942051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.952922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T01:08:51.942175Z","caller":"traceutil/trace.go:171","msg":"trace[202703572] linearizableReadLoop","detail":"{readStateIndex:22578; appliedIndex:22577; }","duration":"131.859275ms","start":"2026-09-04T01:08:51.810298Z","end":"2026-09-04T01:08:51.942157Z","steps":["trace[202703572] 'read index received' (duration: 15.723488ms)","trace[202703572] 'applied index is now lower than readState.Index' (duration: 116.134127ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:08:51.942298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.99487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-04T01:08:51.942332Z","caller":"traceutil/trace.go:171","msg":"trace[18425379] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:21843; }","duration":"132.087902ms","start":"2026-09-04T01:08:51.810232Z","end":"2026-09-04T01:08:51.942319Z","steps":["trace[18425379] 'agreement among raft nodes before linearized reading' (duration: 131.976979ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:08:51.942361Z","caller":"traceutil/trace.go:171","msg":"trace[1188930381] transaction","detail":"{read_only:false; response_revision:21843; number_of_response:1; }","duration":"150.13438ms","start":"2026-09-04T01:08:51.792153Z","end":"2026-09-04T01:08:51.942287Z","steps":["trace[1188930381] 'process raft request' (duration: 33.851988ms)","trace[1188930381] 'compare' (duration: 115.817508ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:08:53.309788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.923832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T01:08:53.310178Z","caller":"traceutil/trace.go:171","msg":"trace[1841458042] linearizableReadLoop","detail":"{readStateIndex:22583; appliedIndex:22582; }","duration":"144.516332ms","start":"2026-09-04T01:08:53.165636Z","end":"2026-09-04T01:08:53.310153Z","steps":["trace[1841458042] 'read index received' (duration: 30.36µs)","trace[1841458042] 'applied index is now lower than readState.Index' (duration: 144.483242ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:08:53.310166Z","caller":"traceutil/trace.go:171","msg":"trace[1330554864] transaction","detail":"{read_only:false; response_revision:21848; number_of_response:1; }","duration":"239.380306ms","start":"2026-09-04T01:08:53.070747Z","end":"2026-09-04T01:08:53.310127Z","steps":["trace[1330554864] 'process raft request' (duration: 82.007401ms)","trace[1330554864] 'compare' (duration: 156.728086ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:08:53.310323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.835859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T01:08:53.310384Z","caller":"traceutil/trace.go:171","msg":"trace[1186515829] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21848; }","duration":"116.925501ms","start":"2026-09-04T01:08:53.193439Z","end":"2026-09-04T01:08:53.310365Z","steps":["trace[1186515829] 'agreement among raft nodes before linearized reading' (duration: 116.835639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:08:53.310473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.815912ms","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-09-04T01:08:53.310553Z","caller":"traceutil/trace.go:171","msg":"trace[1971275461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21848; }","duration":"144.941735ms","start":"2026-09-04T01:08:53.165588Z","end":"2026-09-04T01:08:53.31053Z","steps":["trace[1971275461] 'agreement among raft nodes before linearized reading' (duration: 144.668977ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:08:53.504373Z","caller":"traceutil/trace.go:171","msg":"trace[751059590] linearizableReadLoop","detail":"{readStateIndex:22585; appliedIndex:22583; }","duration":"185.660998ms","start":"2026-09-04T01:08:53.318685Z","end":"2026-09-04T01:08:53.504346Z","steps":["trace[751059590] 'read index received' (duration: 11.454701ms)","trace[751059590] 'applied index is now lower than readState.Index' (duration: 174.205267ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:08:53.50452Z","caller":"traceutil/trace.go:171","msg":"trace[1017883152] transaction","detail":"{read_only:false; response_revision:21850; number_of_response:1; }","duration":"188.257884ms","start":"2026-09-04T01:08:53.316199Z","end":"2026-09-04T01:08:53.504457Z","steps":["trace[1017883152] 'process raft request' (duration: 173.159874ms)","trace[1017883152] 'compare' (duration: 14.878073ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:08:53.504601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.893244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-04T01:08:53.504636Z","caller":"traceutil/trace.go:171","msg":"trace[34620056] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21850; }","duration":"185.971617ms","start":"2026-09-04T01:08:53.318654Z","end":"2026-09-04T01:08:53.504626Z","steps":["trace[34620056] 'agreement among raft nodes before linearized reading' (duration: 185.821742ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:08:53.524157Z","caller":"traceutil/trace.go:171","msg":"trace[1395170266] transaction","detail":"{read_only:false; response_revision:21851; number_of_response:1; }","duration":"107.13899ms","start":"2026-09-04T01:08:53.416989Z","end":"2026-09-04T01:08:53.524128Z","steps":["trace[1395170266] 'process raft request' (duration: 106.785719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:08:53.849725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.769393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T01:08:53.850585Z","caller":"traceutil/trace.go:171","msg":"trace[67370043] transaction","detail":"{read_only:false; response_revision:21852; number_of_response:1; }","duration":"260.828325ms","start":"2026-09-04T01:08:53.589136Z","end":"2026-09-04T01:08:53.849964Z","steps":["trace[67370043] 'process raft request' (duration: 126.720653ms)","trace[67370043] 'compare' (duration: 133.591707ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:13:16.039148Z","caller":"traceutil/trace.go:171","msg":"trace[950350379] transaction","detail":"{read_only:false; response_revision:24459; number_of_response:1; }","duration":"117.224621ms","start":"2026-09-04T01:13:15.921897Z","end":"2026-09-04T01:13:16.039121Z","steps":["trace[950350379] 'process raft request' (duration: 117.033445ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:13:42.016204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21792} {"level":"info","ts":"2026-09-04T01:13:42.141352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21792,"took":"121.604961ms","hash":36077045,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":42598400,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-04T01:13:42.141417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":36077045,"revision":21792,"compact-revision":19698} {"level":"info","ts":"2026-09-04T01:14:22.559398Z","caller":"traceutil/trace.go:171","msg":"trace[2031559390] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"219.570037ms","start":"2026-09-04T01:14:22.339796Z","end":"2026-09-04T01:14:22.559366Z","steps":["trace[2031559390] 'process raft request' (duration: 219.293319ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:15:53.641416Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068b5.wal"} {"level":"info","ts":"2026-09-04T01:18:42.023249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24764} {"level":"info","ts":"2026-09-04T01:18:42.190492Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24764,"took":"162.483163ms","hash":2462567177,"current-db-size-bytes":57618432,"current-db-size":"58 MB","current-db-size-in-use-bytes":33308672,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-04T01:18:42.190582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2462567177,"revision":24764,"compact-revision":21792} {"level":"info","ts":"2026-09-04T01:22:54.576326Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"36725369a67988c9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T01:22:54.584241Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-04T01:22:54.584346Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}