{"level":"warn","ts":"2026-10-26T21:27:59.510822Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T21:27:59.510931Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.55: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.55:2380","--initial-cluster=instance=https://162.253.55.55:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.55:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.55: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-10-26T21:27:59.511008Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T21:27:59.511023Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-10-26T21:27:59.511046Z","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-10-26T21:27:59.511983Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"]} {"level":"info","ts":"2026-10-26T21:27:59.512092Z","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.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.55: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-10-26T21:27:59.517334Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.949133ms"} {"level":"info","ts":"2026-10-26T21:27:59.524649Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"357bf0025115def9","cluster-id":"5f69314e1ae3c800"} {"level":"info","ts":"2026-10-26T21:27:59.524763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T21:27:59.52479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 0"} {"level":"info","ts":"2026-10-26T21:27:59.524809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 357bf0025115def9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T21:27:59.524821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 1"} {"level":"info","ts":"2026-10-26T21:27:59.524889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"warn","ts":"2026-10-26T21:27:59.531658Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T21:27:59.533566Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T21:27:59.535152Z","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-10-26T21:27:59.537627Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"357bf0025115def9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T21:27:59.53784Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"357bf0025115def9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T21:27:59.53794Z","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-10-26T21:27:59.538104Z","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-10-26T21:27:59.538129Z","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-10-26T21:27:59.539975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"info","ts":"2026-10-26T21:27:59.540287Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","added-peer-id":"357bf0025115def9","added-peer-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-10-26T21:27:59.543857Z","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-10-26T21:27:59.544133Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-10-26T21:27:59.54421Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-10-26T21:27:59.544233Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"357bf0025115def9","initial-advertise-peer-urls":["https://162.253.55.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T21:27:59.544282Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T21:28:00.526034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T21:28:00.526119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T21:28:00.526159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgPreVoteResp from 357bf0025115def9 at term 1"} {"level":"info","ts":"2026-10-26T21:28:00.526179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became candidate at term 2"} {"level":"info","ts":"2026-10-26T21:28:00.52619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgVoteResp from 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-10-26T21:28:00.526207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became leader at term 2"} {"level":"info","ts":"2026-10-26T21:28:00.526221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 357bf0025115def9 elected leader 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-10-26T21:28:00.527937Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"357bf0025115def9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.55:2379]}","request-path":"/0/members/357bf0025115def9/attributes","cluster-id":"5f69314e1ae3c800","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T21:28:00.527944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:28:00.528025Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:28:00.52802Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:28:00.52829Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T21:28:00.528363Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T21:28:00.529368Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:28:00.529506Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:28:00.529563Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:28:00.531265Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T21:28:00.532073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.55:2379"} {"level":"info","ts":"2026-10-26T21:28:03.142523Z","caller":"traceutil/trace.go:171","msg":"trace[558429606] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"112.493996ms","start":"2026-10-26T21:28:03.02999Z","end":"2026-10-26T21:28:03.142484Z","steps":["trace[558429606] 'process raft request' (duration: 40.131798ms)","trace[558429606] 'compare' (duration: 72.132793ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:28:18.467127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.626058ms","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-10-26T21:28:18.467338Z","caller":"traceutil/trace.go:171","msg":"trace[1420045531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"120.956247ms","start":"2026-10-26T21:28:18.346345Z","end":"2026-10-26T21:28:18.467301Z","steps":["trace[1420045531] 'range keys from in-memory index tree' (duration: 120.375523ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:18.635088Z","caller":"traceutil/trace.go:171","msg":"trace[104631318] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"162.821617ms","start":"2026-10-26T21:28:18.472206Z","end":"2026-10-26T21:28:18.635028Z","steps":["trace[104631318] 'process raft request' (duration: 162.507149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:19.11763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.423664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:28:19.11797Z","caller":"traceutil/trace.go:171","msg":"trace[91655877] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"471.760997ms","start":"2026-10-26T21:28:18.646181Z","end":"2026-10-26T21:28:19.117942Z","steps":["trace[91655877] 'process raft request' (duration: 282.261837ms)","trace[91655877] 'compare' (duration: 188.243869ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:19.118015Z","caller":"traceutil/trace.go:171","msg":"trace[1173110668] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"384.99387ms","start":"2026-10-26T21:28:18.732983Z","end":"2026-10-26T21:28:19.117977Z","steps":["trace[1173110668] 'read index received' (duration: 195.339546ms)","trace[1173110668] 'applied index is now lower than readState.Index' (duration: 189.652444ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:28:19.118081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:18.646136Z","time spent":"471.89672ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:28:19.123325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.343954ms","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-10-26T21:28:19.123383Z","caller":"traceutil/trace.go:171","msg":"trace[1596965396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"390.471726ms","start":"2026-10-26T21:28:18.732896Z","end":"2026-10-26T21:28:19.123367Z","steps":["trace[1596965396] 'agreement among raft nodes before linearized reading' (duration: 385.178034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:19.123414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:18.732866Z","time spent":"390.539488ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T21:28:19.123463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.187436ms","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-10-26T21:28:19.123541Z","caller":"traceutil/trace.go:171","msg":"trace[660342537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"130.33161ms","start":"2026-10-26T21:28:18.99319Z","end":"2026-10-26T21:28:19.123521Z","steps":["trace[660342537] 'agreement among raft nodes before linearized reading' (duration: 130.162446ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:34.014409Z","caller":"traceutil/trace.go:171","msg":"trace[620895508] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"110.698212ms","start":"2026-10-26T21:28:33.903681Z","end":"2026-10-26T21:28:34.014379Z","steps":["trace[620895508] 'process raft request' (duration: 110.369723ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:48.484291Z","caller":"traceutil/trace.go:171","msg":"trace[1022276501] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"229.331344ms","start":"2026-10-26T21:28:48.254931Z","end":"2026-10-26T21:28:48.484262Z","steps":["trace[1022276501] 'process raft request' (duration: 229.125789ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:48.498002Z","caller":"traceutil/trace.go:171","msg":"trace[1317802849] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"184.931638ms","start":"2026-10-26T21:28:48.313044Z","end":"2026-10-26T21:28:48.497976Z","steps":["trace[1317802849] 'process raft request' (duration: 184.60506ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:48.498272Z","caller":"traceutil/trace.go:171","msg":"trace[930679070] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"153.369691ms","start":"2026-10-26T21:28:48.344885Z","end":"2026-10-26T21:28:48.498254Z","steps":["trace[930679070] 'process raft request' (duration: 153.031722ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:54.938724Z","caller":"traceutil/trace.go:171","msg":"trace[1780583955] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"391.394882ms","start":"2026-10-26T21:28:54.547301Z","end":"2026-10-26T21:28:54.938695Z","steps":["trace[1780583955] 'process raft request' (duration: 391.250518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:54.938884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:54.547266Z","time spent":"391.549645ms","remote":"127.0.0.1:42388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T21:28:55.010276Z","caller":"traceutil/trace.go:171","msg":"trace[1715231326] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"163.124164ms","start":"2026-10-26T21:28:54.847122Z","end":"2026-10-26T21:28:55.010246Z","steps":["trace[1715231326] 'read index received' (duration: 91.51488ms)","trace[1715231326] 'applied index is now lower than readState.Index' (duration: 71.608224ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:55.010425Z","caller":"traceutil/trace.go:171","msg":"trace[655443678] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"386.177161ms","start":"2026-10-26T21:28:54.624222Z","end":"2026-10-26T21:28:55.010399Z","steps":["trace[655443678] 'process raft request' (duration: 385.856843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:55.010601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.442952ms","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-10-26T21:28:55.010648Z","caller":"traceutil/trace.go:171","msg":"trace[564701043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"163.592196ms","start":"2026-10-26T21:28:54.847043Z","end":"2026-10-26T21:28:55.010635Z","steps":["trace[564701043] 'agreement among raft nodes before linearized reading' (duration: 163.34701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:55.010641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:54.624199Z","time spent":"386.344866ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:28:55.644537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.405371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:28:55.644671Z","caller":"traceutil/trace.go:171","msg":"trace[2125156400] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"623.460292ms","start":"2026-10-26T21:28:55.021191Z","end":"2026-10-26T21:28:55.644651Z","steps":["trace[2125156400] 'read index received' (duration: 7.714252ms)","trace[2125156400] 'applied index is now lower than readState.Index' (duration: 615.74464ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:55.644731Z","caller":"traceutil/trace.go:171","msg":"trace[1428530832] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"629.571085ms","start":"2026-10-26T21:28:55.015108Z","end":"2026-10-26T21:28:55.644679Z","steps":["trace[1428530832] 'process raft request' (duration: 13.872206ms)","trace[1428530832] 'compare' (duration: 615.209226ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:28:55.644822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.589465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T21:28:55.644896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.720782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xtvl4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-26T21:28:55.644935Z","caller":"traceutil/trace.go:171","msg":"trace[1842860176] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:670; }","duration":"623.737999ms","start":"2026-10-26T21:28:55.021167Z","end":"2026-10-26T21:28:55.644905Z","steps":["trace[1842860176] 'agreement among raft nodes before linearized reading' (duration: 623.563805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:55.644946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.015086Z","time spent":"629.742168ms","remote":"127.0.0.1:42416","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-10-26T21:28:55.644999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.021153Z","time spent":"623.832011ms","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"info","ts":"2026-10-26T21:28:55.64493Z","caller":"traceutil/trace.go:171","msg":"trace[397470871] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xtvl4; range_end:; response_count:1; response_revision:670; }","duration":"479.794474ms","start":"2026-10-26T21:28:55.165125Z","end":"2026-10-26T21:28:55.644919Z","steps":["trace[397470871] 'agreement among raft nodes before linearized reading' (duration: 479.63503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:55.645061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.165101Z","time spent":"479.950998ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-xtvl4\" "} {"level":"warn","ts":"2026-10-26T21:28:55.64509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.768766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T21:28:55.645144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.963845ms","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-10-26T21:28:55.645179Z","caller":"traceutil/trace.go:171","msg":"trace[226277378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:670; }","duration":"268.90376ms","start":"2026-10-26T21:28:55.376254Z","end":"2026-10-26T21:28:55.645157Z","steps":["trace[226277378] 'agreement among raft nodes before linearized reading' (duration: 268.580551ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:55.645211Z","caller":"traceutil/trace.go:171","msg":"trace[2109178638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"282.036146ms","start":"2026-10-26T21:28:55.363159Z","end":"2026-10-26T21:28:55.645195Z","steps":["trace[2109178638] 'agreement among raft nodes before linearized reading' (duration: 281.78779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:55.64509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.401801ms","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-10-26T21:28:55.645278Z","caller":"traceutil/trace.go:171","msg":"trace[1431986474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"277.644797ms","start":"2026-10-26T21:28:55.36762Z","end":"2026-10-26T21:28:55.645265Z","steps":["trace[1431986474] 'agreement among raft nodes before linearized reading' (duration: 277.275258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:56.264612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.010046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:28:56.264847Z","caller":"traceutil/trace.go:171","msg":"trace[1105241697] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"614.790547ms","start":"2026-10-26T21:28:55.650015Z","end":"2026-10-26T21:28:56.264805Z","steps":["trace[1105241697] 'process raft request' (duration: 94.478913ms)","trace[1105241697] 'compare' (duration: 519.870093ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:56.26493Z","caller":"traceutil/trace.go:171","msg":"trace[1556254717] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"614.788327ms","start":"2026-10-26T21:28:55.650128Z","end":"2026-10-26T21:28:56.264917Z","steps":["trace[1556254717] 'process raft request' (duration: 614.663844ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:56.264922Z","caller":"traceutil/trace.go:171","msg":"trace[781702828] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"614.766587ms","start":"2026-10-26T21:28:55.650121Z","end":"2026-10-26T21:28:56.264888Z","steps":["trace[781702828] 'process raft request' (duration: 614.609153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:56.265025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.65009Z","time spent":"614.88256ms","remote":"127.0.0.1:42416","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-10-26T21:28:56.265035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.650101Z","time spent":"614.868589ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:28:56.265062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.650001Z","time spent":"614.936781ms","remote":"127.0.0.1:42416","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-10-26T21:28:56.265104Z","caller":"traceutil/trace.go:171","msg":"trace[447091956] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"596.423719ms","start":"2026-10-26T21:28:55.668639Z","end":"2026-10-26T21:28:56.265062Z","steps":["trace[447091956] 'process raft request' (duration: 596.203173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:56.265336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.66862Z","time spent":"596.597484ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:28:56.281567Z","caller":"traceutil/trace.go:171","msg":"trace[449182895] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"603.930046ms","start":"2026-10-26T21:28:55.677609Z","end":"2026-10-26T21:28:56.281539Z","steps":["trace[449182895] 'process raft request' (duration: 603.713881ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:56.281726Z","caller":"traceutil/trace.go:171","msg":"trace[1024540095] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"602.87363ms","start":"2026-10-26T21:28:55.67884Z","end":"2026-10-26T21:28:56.281714Z","steps":["trace[1024540095] 'process raft request' (duration: 602.708156ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:56.281858Z","caller":"traceutil/trace.go:171","msg":"trace[1148201341] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"602.100891ms","start":"2026-10-26T21:28:55.679743Z","end":"2026-10-26T21:28:56.281844Z","steps":["trace[1148201341] 'process raft request' (duration: 601.873375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:56.281941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.679729Z","time spent":"602.156322ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:28:56.281669Z","caller":"traceutil/trace.go:171","msg":"trace[1897695303] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"603.496955ms","start":"2026-10-26T21:28:55.678154Z","end":"2026-10-26T21:28:56.281651Z","steps":["trace[1897695303] 'process raft request' (duration: 603.339371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:56.28211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.678825Z","time spent":"602.938002ms","remote":"127.0.0.1:42388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:28:56.282047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.677588Z","time spent":"604.268584ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T21:28:56.282238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:55.67814Z","time spent":"603.964758ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T21:29:12.668097Z","caller":"traceutil/trace.go:171","msg":"trace[800471936] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"271.022382ms","start":"2026-10-26T21:29:12.397051Z","end":"2026-10-26T21:29:12.668073Z","steps":["trace[800471936] 'read index received' (duration: 270.789266ms)","trace[800471936] 'applied index is now lower than readState.Index' (duration: 232.006µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:29:12.668266Z","caller":"traceutil/trace.go:171","msg":"trace[107785739] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"318.572427ms","start":"2026-10-26T21:29:12.349581Z","end":"2026-10-26T21:29:12.668153Z","steps":["trace[107785739] 'process raft request' (duration: 318.331761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:12.668443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.891194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T21:29:12.668688Z","caller":"traceutil/trace.go:171","msg":"trace[273425866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"270.209562ms","start":"2026-10-26T21:29:12.398465Z","end":"2026-10-26T21:29:12.668674Z","steps":["trace[273425866] 'agreement among raft nodes before linearized reading' (duration: 269.774611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:12.668464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.33724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-26T21:29:12.668915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:29:12.349553Z","time spent":"319.049709ms","remote":"127.0.0.1:42416","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-10-26T21:29:12.668888Z","caller":"traceutil/trace.go:171","msg":"trace[770109663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"271.836353ms","start":"2026-10-26T21:29:12.397018Z","end":"2026-10-26T21:29:12.668854Z","steps":["trace[770109663] 'agreement among raft nodes before linearized reading' (duration: 271.200947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:40.297019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.80946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T21:29:40.297036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.386914ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:29:40.297203Z","caller":"traceutil/trace.go:171","msg":"trace[671779697] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1185; }","duration":"174.578889ms","start":"2026-10-26T21:29:40.122597Z","end":"2026-10-26T21:29:40.297176Z","steps":["trace[671779697] 'range keys from in-memory index tree' (duration: 174.362344ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:40.297132Z","caller":"traceutil/trace.go:171","msg":"trace[1834947074] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1185; }","duration":"228.982185ms","start":"2026-10-26T21:29:40.068126Z","end":"2026-10-26T21:29:40.297108Z","steps":["trace[1834947074] 'count revisions from in-memory index tree' (duration: 228.598636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:27.70642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.747066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T21:30:27.706524Z","caller":"traceutil/trace.go:171","msg":"trace[222908730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1864; }","duration":"170.9223ms","start":"2026-10-26T21:30:27.535577Z","end":"2026-10-26T21:30:27.706499Z","steps":["trace[222908730] 'range keys from in-memory index tree' (duration: 170.487759ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:27.865643Z","caller":"traceutil/trace.go:171","msg":"trace[1951438588] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"154.321908ms","start":"2026-10-26T21:30:27.711292Z","end":"2026-10-26T21:30:27.865614Z","steps":["trace[1951438588] 'process raft request' (duration: 154.141833ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:27.979408Z","caller":"traceutil/trace.go:171","msg":"trace[115396578] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"265.338449ms","start":"2026-10-26T21:30:27.714041Z","end":"2026-10-26T21:30:27.979379Z","steps":["trace[115396578] 'process raft request' (duration: 252.878399ms)","trace[115396578] 'compare' (duration: 12.199643ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:27.979588Z","caller":"traceutil/trace.go:171","msg":"trace[1171355284] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"237.874495ms","start":"2026-10-26T21:30:27.74168Z","end":"2026-10-26T21:30:27.979554Z","steps":["trace[1171355284] 'process raft request' (duration: 237.647729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:28.381697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.218231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d84a641-05b7-460c-b3e5-bbe8ec190705\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-10-26T21:30:28.381816Z","caller":"traceutil/trace.go:171","msg":"trace[1751711813] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d84a641-05b7-460c-b3e5-bbe8ec190705; range_end:; response_count:1; response_revision:1867; }","duration":"250.399415ms","start":"2026-10-26T21:30:28.131386Z","end":"2026-10-26T21:30:28.381786Z","steps":["trace[1751711813] 'range keys from in-memory index tree' (duration: 250.076288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:28.382173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.725433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:30:28.382291Z","caller":"traceutil/trace.go:171","msg":"trace[59376956] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"167.29076ms","start":"2026-10-26T21:30:28.214986Z","end":"2026-10-26T21:30:28.382276Z","steps":["trace[59376956] 'read index received' (duration: 52.372522ms)","trace[59376956] 'applied index is now lower than readState.Index' (duration: 114.917268ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:30:28.382416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.442744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T21:30:28.382442Z","caller":"traceutil/trace.go:171","msg":"trace[1800326863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1868; }","duration":"167.519546ms","start":"2026-10-26T21:30:28.214914Z","end":"2026-10-26T21:30:28.382433Z","steps":["trace[1800326863] 'agreement among raft nodes before linearized reading' (duration: 167.399663ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:28.382496Z","caller":"traceutil/trace.go:171","msg":"trace[1173057468] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"223.377195ms","start":"2026-10-26T21:30:28.159089Z","end":"2026-10-26T21:30:28.382466Z","steps":["trace[1173057468] 'process raft request' (duration: 108.276173ms)","trace[1173057468] 'compare' (duration: 114.62006ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:34.157958Z","caller":"traceutil/trace.go:171","msg":"trace[2035690426] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"158.41261ms","start":"2026-10-26T21:30:33.999518Z","end":"2026-10-26T21:30:34.15793Z","steps":["trace[2035690426] 'process raft request' (duration: 158.226465ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:40.929891Z","caller":"traceutil/trace.go:171","msg":"trace[469020919] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2048; }","duration":"138.170716ms","start":"2026-10-26T21:30:40.791698Z","end":"2026-10-26T21:30:40.929869Z","steps":["trace[469020919] 'read index received' (duration: 81.881566ms)","trace[469020919] 'applied index is now lower than readState.Index' (duration: 56.28822ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:40.930123Z","caller":"traceutil/trace.go:171","msg":"trace[1562284841] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"157.287922ms","start":"2026-10-26T21:30:40.772811Z","end":"2026-10-26T21:30:40.930099Z","steps":["trace[1562284841] 'process raft request' (duration: 100.745696ms)","trace[1562284841] 'compare' (duration: 55.359456ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:30:40.930276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.471413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T21:30:40.930361Z","caller":"traceutil/trace.go:171","msg":"trace[1012310338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1972; }","duration":"138.680789ms","start":"2026-10-26T21:30:40.791665Z","end":"2026-10-26T21:30:40.930346Z","steps":["trace[1012310338] 'agreement among raft nodes before linearized reading' (duration: 138.374151ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:44.723497Z","caller":"traceutil/trace.go:171","msg":"trace[2035285531] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"154.917062ms","start":"2026-10-26T21:30:44.568556Z","end":"2026-10-26T21:30:44.723473Z","steps":["trace[2035285531] 'process raft request' (duration: 154.783529ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:44.725303Z","caller":"traceutil/trace.go:171","msg":"trace[163434651] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"151.446966ms","start":"2026-10-26T21:30:44.573829Z","end":"2026-10-26T21:30:44.725276Z","steps":["trace[163434651] 'process raft request' (duration: 150.584085ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:16.974537Z","caller":"traceutil/trace.go:171","msg":"trace[314195985] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"105.948622ms","start":"2026-10-26T21:31:16.868557Z","end":"2026-10-26T21:31:16.974506Z","steps":["trace[314195985] 'process raft request' (duration: 92.22223ms)","trace[314195985] 'compare' (duration: 13.568718ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:31:17.214656Z","caller":"traceutil/trace.go:171","msg":"trace[1097538198] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"159.811255ms","start":"2026-10-26T21:31:17.054811Z","end":"2026-10-26T21:31:17.214622Z","steps":["trace[1097538198] 'process raft request' (duration: 159.63608ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:23.008607Z","caller":"traceutil/trace.go:171","msg":"trace[964010120] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"139.234202ms","start":"2026-10-26T21:31:22.869346Z","end":"2026-10-26T21:31:23.008581Z","steps":["trace[964010120] 'process raft request' (duration: 138.946215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:31:23.374658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.145713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:31:23.374878Z","caller":"traceutil/trace.go:171","msg":"trace[725198382] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2297; }","duration":"120.517855ms","start":"2026-10-26T21:31:23.254339Z","end":"2026-10-26T21:31:23.374857Z","steps":["trace[725198382] 'read index received' (duration: 92.879976ms)","trace[725198382] 'applied index is now lower than readState.Index' (duration: 27.636889ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:31:23.375127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.768741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T21:31:23.375162Z","caller":"traceutil/trace.go:171","msg":"trace[170049865] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2213; }","duration":"120.862773ms","start":"2026-10-26T21:31:23.25429Z","end":"2026-10-26T21:31:23.375152Z","steps":["trace[170049865] 'agreement among raft nodes before linearized reading' (duration: 120.623567ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:23.375311Z","caller":"traceutil/trace.go:171","msg":"trace[1721947597] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"141.008656ms","start":"2026-10-26T21:31:23.234282Z","end":"2026-10-26T21:31:23.37529Z","steps":["trace[1721947597] 'process raft request' (duration: 140.481423ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:23.375358Z","caller":"traceutil/trace.go:171","msg":"trace[1503609792] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"362.117869ms","start":"2026-10-26T21:31:23.013213Z","end":"2026-10-26T21:31:23.375331Z","steps":["trace[1503609792] 'process raft request' (duration: 195.213847ms)","trace[1503609792] 'compare' (duration: 165.895217ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:31:23.375445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:31:23.013187Z","time spent":"362.218231ms","remote":"127.0.0.1:42416","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-10-26T21:31:23.634434Z","caller":"traceutil/trace.go:171","msg":"trace[73814093] linearizableReadLoop","detail":"{readStateIndex:2300; appliedIndex:2299; }","duration":"187.018522ms","start":"2026-10-26T21:31:23.447389Z","end":"2026-10-26T21:31:23.634407Z","steps":["trace[73814093] 'read index received' (duration: 185.979386ms)","trace[73814093] 'applied index is now lower than readState.Index' (duration: 1.038046ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:31:23.634491Z","caller":"traceutil/trace.go:171","msg":"trace[154599404] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"200.006446ms","start":"2026-10-26T21:31:23.434449Z","end":"2026-10-26T21:31:23.634456Z","steps":["trace[154599404] 'process raft request' (duration: 198.987061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:31:23.634707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.29071ms","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-10-26T21:31:23.634723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.394531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T21:31:23.634769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.479374ms","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-10-26T21:31:23.634784Z","caller":"traceutil/trace.go:171","msg":"trace[2047462981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2214; }","duration":"130.496423ms","start":"2026-10-26T21:31:23.504273Z","end":"2026-10-26T21:31:23.634769Z","steps":["trace[2047462981] 'agreement among raft nodes before linearized reading' (duration: 130.290888ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:23.634785Z","caller":"traceutil/trace.go:171","msg":"trace[1602103447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2214; }","duration":"187.414472ms","start":"2026-10-26T21:31:23.447349Z","end":"2026-10-26T21:31:23.634764Z","steps":["trace[1602103447] 'agreement among raft nodes before linearized reading' (duration: 187.157466ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:23.634803Z","caller":"traceutil/trace.go:171","msg":"trace[671458737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2214; }","duration":"120.532895ms","start":"2026-10-26T21:31:23.514259Z","end":"2026-10-26T21:31:23.634792Z","steps":["trace[671458737] 'agreement among raft nodes before linearized reading' (duration: 120.402382ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:58.621429Z","caller":"traceutil/trace.go:171","msg":"trace[368117572] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"146.196499ms","start":"2026-10-26T21:31:58.475205Z","end":"2026-10-26T21:31:58.621401Z","steps":["trace[368117572] 'process raft request' (duration: 146.060126ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:32:31.704791Z","caller":"traceutil/trace.go:171","msg":"trace[2016419761] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"137.758898ms","start":"2026-10-26T21:32:31.567004Z","end":"2026-10-26T21:32:31.704763Z","steps":["trace[2016419761] 'process raft request' (duration: 137.565313ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:32:32.037111Z","caller":"traceutil/trace.go:171","msg":"trace[973084772] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"131.767067ms","start":"2026-10-26T21:32:31.905308Z","end":"2026-10-26T21:32:32.037075Z","steps":["trace[973084772] 'process raft request' (duration: 120.028653ms)","trace[973084772] 'compare' (duration: 11.358905ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:32:32.237829Z","caller":"traceutil/trace.go:171","msg":"trace[1276972257] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2890; }","duration":"195.368463ms","start":"2026-10-26T21:32:32.042436Z","end":"2026-10-26T21:32:32.237804Z","steps":["trace[1276972257] 'read index received' (duration: 177.110855ms)","trace[1276972257] 'applied index is now lower than readState.Index' (duration: 18.256638ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:32:32.238007Z","caller":"traceutil/trace.go:171","msg":"trace[1446516651] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"196.393899ms","start":"2026-10-26T21:32:32.041458Z","end":"2026-10-26T21:32:32.237852Z","steps":["trace[1446516651] 'process raft request' (duration: 178.073329ms)","trace[1446516651] 'compare' (duration: 18.091285ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:32:32.238317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.862375ms","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-10-26T21:32:32.238414Z","caller":"traceutil/trace.go:171","msg":"trace[502897814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2783; }","duration":"195.921147ms","start":"2026-10-26T21:32:32.042431Z","end":"2026-10-26T21:32:32.238353Z","steps":["trace[502897814] 'agreement among raft nodes before linearized reading' (duration: 195.461965ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:32:32.243089Z","caller":"traceutil/trace.go:171","msg":"trace[937477660] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"198.553113ms","start":"2026-10-26T21:32:32.044478Z","end":"2026-10-26T21:32:32.243031Z","steps":["trace[937477660] 'process raft request' (duration: 198.40475ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:03.131353Z","caller":"traceutil/trace.go:171","msg":"trace[1339843136] transaction","detail":"{read_only:false; response_revision:3738; number_of_response:1; }","duration":"100.340331ms","start":"2026-10-26T21:35:03.030978Z","end":"2026-10-26T21:35:03.131318Z","steps":["trace[1339843136] 'process raft request' (duration: 92.966506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:39.261048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.716579ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-26T21:35:39.26118Z","caller":"traceutil/trace.go:171","msg":"trace[1327920510] linearizableReadLoop","detail":"{readStateIndex:4125; appliedIndex:4124; }","duration":"163.989069ms","start":"2026-10-26T21:35:39.097166Z","end":"2026-10-26T21:35:39.261155Z","steps":["trace[1327920510] 'read index received' (duration: 56.107406ms)","trace[1327920510] 'applied index is now lower than readState.Index' (duration: 107.879893ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:35:39.261343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.172993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:35:39.261568Z","caller":"traceutil/trace.go:171","msg":"trace[1653070840] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3972; }","duration":"164.4444ms","start":"2026-10-26T21:35:39.097113Z","end":"2026-10-26T21:35:39.261558Z","steps":["trace[1653070840] 'agreement among raft nodes before linearized reading' (duration: 164.179683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:04.715986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.129492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:37:04.716082Z","caller":"traceutil/trace.go:171","msg":"trace[289710222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5156; }","duration":"284.271225ms","start":"2026-10-26T21:37:04.431792Z","end":"2026-10-26T21:37:04.716063Z","steps":["trace[289710222] 'range keys from in-memory index tree' (duration: 284.034159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:04.716093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.462734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1a234d9-342b-49de-b164-9310037a4e44\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-26T21:37:04.716147Z","caller":"traceutil/trace.go:171","msg":"trace[1321201309] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1a234d9-342b-49de-b164-9310037a4e44; range_end:; response_count:1; response_revision:5156; }","duration":"200.554025ms","start":"2026-10-26T21:37:04.515577Z","end":"2026-10-26T21:37:04.716131Z","steps":["trace[1321201309] 'range keys from in-memory index tree' (duration: 200.299189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:04.71647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.149583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xpnrs\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-26T21:37:04.716592Z","caller":"traceutil/trace.go:171","msg":"trace[738259844] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xpnrs; range_end:; response_count:1; response_revision:5156; }","duration":"270.325397ms","start":"2026-10-26T21:37:04.446243Z","end":"2026-10-26T21:37:04.716569Z","steps":["trace[738259844] 'range keys from in-memory index tree' (duration: 269.945507ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:07.26665Z","caller":"traceutil/trace.go:171","msg":"trace[847004636] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"230.168744ms","start":"2026-10-26T21:37:07.03645Z","end":"2026-10-26T21:37:07.266619Z","steps":["trace[847004636] 'process raft request' (duration: 230.00237ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:07.282954Z","caller":"traceutil/trace.go:171","msg":"trace[583907535] linearizableReadLoop","detail":"{readStateIndex:5396; appliedIndex:5394; }","duration":"128.984569ms","start":"2026-10-26T21:37:07.153936Z","end":"2026-10-26T21:37:07.282921Z","steps":["trace[583907535] 'read index received' (duration: 112.412536ms)","trace[583907535] 'applied index is now lower than readState.Index' (duration: 16.570683ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:37:07.283182Z","caller":"traceutil/trace.go:171","msg":"trace[271672064] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"237.507017ms","start":"2026-10-26T21:37:07.045653Z","end":"2026-10-26T21:37:07.28316Z","steps":["trace[271672064] 'process raft request' (duration: 237.081596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:07.283476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.520082ms","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-10-26T21:37:07.283572Z","caller":"traceutil/trace.go:171","msg":"trace[842533773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5200; }","duration":"129.672426ms","start":"2026-10-26T21:37:07.153876Z","end":"2026-10-26T21:37:07.283549Z","steps":["trace[842533773] 'agreement among raft nodes before linearized reading' (duration: 129.390419ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:19.213579Z","caller":"traceutil/trace.go:171","msg":"trace[1006258037] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"107.318539ms","start":"2026-10-26T21:37:19.106237Z","end":"2026-10-26T21:37:19.213556Z","steps":["trace[1006258037] 'process raft request' (duration: 107.148734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:19.67417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.62927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:37:19.674252Z","caller":"traceutil/trace.go:171","msg":"trace[592729995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5431; }","duration":"241.750064ms","start":"2026-10-26T21:37:19.432485Z","end":"2026-10-26T21:37:19.674235Z","steps":["trace[592729995] 'range keys from in-memory index tree' (duration: 241.573259ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:27.841328Z","caller":"traceutil/trace.go:171","msg":"trace[496475137] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"158.010544ms","start":"2026-10-26T21:37:27.683275Z","end":"2026-10-26T21:37:27.841285Z","steps":["trace[496475137] 'process raft request' (duration: 79.694369ms)","trace[496475137] 'compare' (duration: 77.821493ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:37:28.065268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.311173ms","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-10-26T21:37:28.065396Z","caller":"traceutil/trace.go:171","msg":"trace[1918061889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5596; }","duration":"112.483958ms","start":"2026-10-26T21:37:27.952886Z","end":"2026-10-26T21:37:28.06537Z","steps":["trace[1918061889] 'range keys from in-memory index tree' (duration: 112.117068ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.443578Z","caller":"traceutil/trace.go:171","msg":"trace[515351408] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"373.363808ms","start":"2026-10-26T21:37:28.070192Z","end":"2026-10-26T21:37:28.443556Z","steps":["trace[515351408] 'process raft request' (duration: 373.110162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:28.443727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:37:28.070165Z","time spent":"373.485982ms","remote":"127.0.0.1:42416","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-10-26T21:37:28.443858Z","caller":"traceutil/trace.go:171","msg":"trace[261908347] transaction","detail":"{read_only:false; response_revision:5599; number_of_response:1; }","duration":"373.605464ms","start":"2026-10-26T21:37:28.070223Z","end":"2026-10-26T21:37:28.443828Z","steps":["trace[261908347] 'process raft request' (duration: 373.236985ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.443996Z","caller":"traceutil/trace.go:171","msg":"trace[1833477383] transaction","detail":"{read_only:false; response_revision:5600; number_of_response:1; }","duration":"370.728763ms","start":"2026-10-26T21:37:28.073244Z","end":"2026-10-26T21:37:28.443972Z","steps":["trace[1833477383] 'process raft request' (duration: 370.255831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:28.444077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:37:28.070201Z","time spent":"373.782759ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:37:28.444114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:37:28.073225Z","time spent":"370.832615ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3744,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:38:00.557873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2940} {"level":"info","ts":"2026-10-26T21:38:00.633275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2940,"took":"74.12642ms","hash":3329409778,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":32616448,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T21:38:00.633354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3329409778,"revision":2940,"compact-revision":-1} {"level":"info","ts":"2026-10-26T21:43:00.564511Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5892} {"level":"info","ts":"2026-10-26T21:43:00.649264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5892,"took":"82.932219ms","hash":221692869,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":26419200,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T21:43:00.649375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":221692869,"revision":5892,"compact-revision":2940} {"level":"warn","ts":"2026-10-26T21:43:39.492698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.65653ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-26T21:43:39.492807Z","caller":"traceutil/trace.go:171","msg":"trace[1179268161] linearizableReadLoop","detail":"{readStateIndex:8753; appliedIndex:8752; }","duration":"256.243432ms","start":"2026-10-26T21:43:39.236544Z","end":"2026-10-26T21:43:39.492787Z","steps":["trace[1179268161] 'read index received' (duration: 43.310305ms)","trace[1179268161] 'applied index is now lower than readState.Index' (duration: 212.931637ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:43:39.493004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.456177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-26T21:43:39.493057Z","caller":"traceutil/trace.go:171","msg":"trace[416888648] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8453; }","duration":"256.55012ms","start":"2026-10-26T21:43:39.236498Z","end":"2026-10-26T21:43:39.493048Z","steps":["trace[416888648] 'agreement among raft nodes before linearized reading' (duration: 256.378976ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:43:39.500055Z","caller":"traceutil/trace.go:171","msg":"trace[1690975993] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"253.618477ms","start":"2026-10-26T21:43:39.246385Z","end":"2026-10-26T21:43:39.500003Z","steps":["trace[1690975993] 'process raft request' (duration: 253.474043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:43:39.500304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.296387ms","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-10-26T21:43:39.500381Z","caller":"traceutil/trace.go:171","msg":"trace[2067065813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8454; }","duration":"229.40499ms","start":"2026-10-26T21:43:39.270958Z","end":"2026-10-26T21:43:39.500363Z","steps":["trace[2067065813] 'agreement among raft nodes before linearized reading' (duration: 229.218515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:43:52.252223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.794556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T21:43:52.252338Z","caller":"traceutil/trace.go:171","msg":"trace[1967206876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8522; }","duration":"173.93906ms","start":"2026-10-26T21:43:52.078364Z","end":"2026-10-26T21:43:52.252303Z","steps":["trace[1967206876] 'range keys from in-memory index tree' (duration: 173.587741ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:43:53.734275Z","caller":"traceutil/trace.go:171","msg":"trace[1547607949] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"138.985354ms","start":"2026-10-26T21:43:53.595137Z","end":"2026-10-26T21:43:53.734123Z","steps":["trace[1547607949] 'process raft request' (duration: 81.316048ms)","trace[1547607949] 'compare' (duration: 57.253035ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:43:53.945017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.374836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T21:43:53.945103Z","caller":"traceutil/trace.go:171","msg":"trace[2039677735] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:8529; }","duration":"102.483349ms","start":"2026-10-26T21:43:53.842602Z","end":"2026-10-26T21:43:53.945086Z","steps":["trace[2039677735] 'count revisions from in-memory index tree' (duration: 102.294404ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:43:54.116036Z","caller":"traceutil/trace.go:171","msg":"trace[1758019159] linearizableReadLoop","detail":"{readStateIndex:8836; appliedIndex:8835; }","duration":"101.212426ms","start":"2026-10-26T21:43:54.014799Z","end":"2026-10-26T21:43:54.116012Z","steps":["trace[1758019159] 'read index received' (duration: 94.001406ms)","trace[1758019159] 'applied index is now lower than readState.Index' (duration: 7.21006ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:43:54.116111Z","caller":"traceutil/trace.go:171","msg":"trace[1745784878] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"102.687423ms","start":"2026-10-26T21:43:54.013362Z","end":"2026-10-26T21:43:54.11605Z","steps":["trace[1745784878] 'process raft request' (duration: 95.478733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:43:54.116234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.420321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-26T21:43:54.116382Z","caller":"traceutil/trace.go:171","msg":"trace[2067043223] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:8532; }","duration":"101.576736ms","start":"2026-10-26T21:43:54.014764Z","end":"2026-10-26T21:43:54.116341Z","steps":["trace[2067043223] 'agreement among raft nodes before linearized reading' (duration: 101.36115ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:44:18.617542Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000232d.wal"} {"level":"info","ts":"2026-10-26T21:44:22.907118Z","caller":"traceutil/trace.go:171","msg":"trace[258510302] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"363.133301ms","start":"2026-10-26T21:44:22.543961Z","end":"2026-10-26T21:44:22.907094Z","steps":["trace[258510302] 'process raft request' (duration: 362.965397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:22.907288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:44:22.543918Z","time spent":"363.293425ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:44:23.085788Z","caller":"traceutil/trace.go:171","msg":"trace[1311364839] linearizableReadLoop","detail":"{readStateIndex:9052; appliedIndex:9050; }","duration":"405.314469ms","start":"2026-10-26T21:44:22.680448Z","end":"2026-10-26T21:44:23.085762Z","steps":["trace[1311364839] 'read index received' (duration: 226.572949ms)","trace[1311364839] 'applied index is now lower than readState.Index' (duration: 178.74052ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:44:23.086099Z","caller":"traceutil/trace.go:171","msg":"trace[846497803] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"486.587676ms","start":"2026-10-26T21:44:22.599499Z","end":"2026-10-26T21:44:23.086087Z","steps":["trace[846497803] 'process raft request' (duration: 484.804331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:23.086378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.256679ms","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-10-26T21:44:23.086362Z","caller":"traceutil/trace.go:171","msg":"trace[885981534] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"180.387052ms","start":"2026-10-26T21:44:22.905929Z","end":"2026-10-26T21:44:23.086316Z","steps":["trace[885981534] 'process raft request' (duration: 179.780496ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:44:23.08643Z","caller":"traceutil/trace.go:171","msg":"trace[1481219106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8742; }","duration":"358.330821ms","start":"2026-10-26T21:44:22.728086Z","end":"2026-10-26T21:44:23.086417Z","steps":["trace[1481219106] 'agreement among raft nodes before linearized reading' (duration: 358.181877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:23.086457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:44:22.728074Z","time spent":"358.376752ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T21:44:23.086506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.049707ms","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-10-26T21:44:23.086562Z","caller":"traceutil/trace.go:171","msg":"trace[236523428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8742; }","duration":"406.145999ms","start":"2026-10-26T21:44:22.680399Z","end":"2026-10-26T21:44:23.086545Z","steps":["trace[236523428] 'agreement among raft nodes before linearized reading' (duration: 405.990765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:23.086586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:44:22.599468Z","time spent":"486.688549ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:44:23.086629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:44:22.680386Z","time spent":"406.234252ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T21:44:26.056192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.801353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:44:26.056283Z","caller":"traceutil/trace.go:171","msg":"trace[1085724255] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8755; }","duration":"199.119541ms","start":"2026-10-26T21:44:25.857131Z","end":"2026-10-26T21:44:26.05625Z","steps":["trace[1085724255] 'count revisions from in-memory index tree' (duration: 198.70685ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:45:51.624932Z","caller":"traceutil/trace.go:171","msg":"trace[370542263] transaction","detail":"{read_only:false; response_revision:9589; number_of_response:1; }","duration":"119.111891ms","start":"2026-10-26T21:45:51.505771Z","end":"2026-10-26T21:45:51.624883Z","steps":["trace[370542263] 'process raft request' (duration: 118.943507ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:46:03.957972Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"357bf0025115def9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T21:46:03.963439Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T21:46:03.963547Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T21:48:00.571672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8074} {"level":"info","ts":"2026-10-26T21:48:00.643008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8074,"took":"69.107829ms","hash":3823726788,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":28770304,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T21:48:00.643103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3823726788,"revision":8074,"compact-revision":5892} {"level":"warn","ts":"2026-10-26T21:49:34.202968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.101401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b4fd1c7b-cd24-407b-993b-f78c3dc58dbb\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-26T21:49:34.203107Z","caller":"traceutil/trace.go:171","msg":"trace[1552759229] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b4fd1c7b-cd24-407b-993b-f78c3dc58dbb; range_end:; response_count:1; response_revision:11428; }","duration":"141.296266ms","start":"2026-10-26T21:49:34.061786Z","end":"2026-10-26T21:49:34.203082Z","steps":["trace[1552759229] 'range keys from in-memory index tree' (duration: 140.952987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:49:34.202967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.656886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T21:49:34.203382Z","caller":"traceutil/trace.go:171","msg":"trace[1848358209] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:11428; }","duration":"132.125948ms","start":"2026-10-26T21:49:34.071221Z","end":"2026-10-26T21:49:34.203347Z","steps":["trace[1848358209] 'count revisions from in-memory index tree' (duration: 131.581984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:49:56.858475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.16547ms","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-10-26T21:49:56.858561Z","caller":"traceutil/trace.go:171","msg":"trace[359669142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11553; }","duration":"163.297373ms","start":"2026-10-26T21:49:56.695245Z","end":"2026-10-26T21:49:56.858542Z","steps":["trace[359669142] 'range keys from in-memory index tree' (duration: 163.028196ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:53:00.580042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10493} {"level":"info","ts":"2026-10-26T21:53:00.662942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10493,"took":"80.472304ms","hash":770100768,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":27631616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T21:53:00.663016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":770100768,"revision":10493,"compact-revision":8074} {"level":"info","ts":"2026-10-26T21:56:30.309501Z","caller":"traceutil/trace.go:171","msg":"trace[914798059] transaction","detail":"{read_only:false; response_revision:14191; number_of_response:1; }","duration":"185.221464ms","start":"2026-10-26T21:56:30.124261Z","end":"2026-10-26T21:56:30.309482Z","steps":["trace[914798059] 'process raft request' (duration: 185.189073ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:56:30.309583Z","caller":"traceutil/trace.go:171","msg":"trace[1744341455] transaction","detail":"{read_only:false; response_revision:14190; number_of_response:1; }","duration":"186.788813ms","start":"2026-10-26T21:56:30.122769Z","end":"2026-10-26T21:56:30.309557Z","steps":["trace[1744341455] 'process raft request' (duration: 149.486ms)","trace[1744341455] 'compare' (duration: 37.100858ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:56:30.3338Z","caller":"traceutil/trace.go:171","msg":"trace[1116340343] transaction","detail":"{read_only:false; response_revision:14192; number_of_response:1; }","duration":"167.126992ms","start":"2026-10-26T21:56:30.166646Z","end":"2026-10-26T21:56:30.333773Z","steps":["trace[1116340343] 'process raft request' (duration: 166.970368ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:56:31.013245Z","caller":"traceutil/trace.go:171","msg":"trace[693368481] transaction","detail":"{read_only:false; response_revision:14195; number_of_response:1; }","duration":"214.534597ms","start":"2026-10-26T21:56:30.798688Z","end":"2026-10-26T21:56:31.013222Z","steps":["trace[693368481] 'process raft request' (duration: 214.373243ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:56:31.175687Z","caller":"traceutil/trace.go:171","msg":"trace[2122181464] linearizableReadLoop","detail":"{readStateIndex:14686; appliedIndex:14684; }","duration":"316.239172ms","start":"2026-10-26T21:56:30.859422Z","end":"2026-10-26T21:56:31.175661Z","steps":["trace[2122181464] 'read index received' (duration: 153.643644ms)","trace[2122181464] 'applied index is now lower than readState.Index' (duration: 162.594378ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:56:31.175809Z","caller":"traceutil/trace.go:171","msg":"trace[1859244188] transaction","detail":"{read_only:false; response_revision:14196; number_of_response:1; }","duration":"335.920464ms","start":"2026-10-26T21:56:30.839876Z","end":"2026-10-26T21:56:31.175796Z","steps":["trace[1859244188] 'process raft request' (duration: 277.613545ms)","trace[1859244188] 'compare' (duration: 58.071233ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:56:31.175947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:56:30.839808Z","time spent":"336.048428ms","remote":"127.0.0.1:42416","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-10-26T21:56:31.176192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.987209ms","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-10-26T21:56:31.176307Z","caller":"traceutil/trace.go:171","msg":"trace[1769718204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14196; }","duration":"227.151773ms","start":"2026-10-26T21:56:30.949131Z","end":"2026-10-26T21:56:31.176282Z","steps":["trace[1769718204] 'agreement among raft nodes before linearized reading' (duration: 226.872986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:56:31.176403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.9872ms","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-10-26T21:56:31.176456Z","caller":"traceutil/trace.go:171","msg":"trace[141822080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14196; }","duration":"317.080303ms","start":"2026-10-26T21:56:30.859362Z","end":"2026-10-26T21:56:31.176442Z","steps":["trace[141822080] 'agreement among raft nodes before linearized reading' (duration: 316.93242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:56:31.176487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:56:30.859333Z","time spent":"317.147444ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T21:56:41.411436Z","caller":"traceutil/trace.go:171","msg":"trace[527818867] linearizableReadLoop","detail":"{readStateIndex:14861; appliedIndex:14860; }","duration":"166.810294ms","start":"2026-10-26T21:56:41.244603Z","end":"2026-10-26T21:56:41.411413Z","steps":["trace[527818867] 'read index received' (duration: 160.790033ms)","trace[527818867] 'applied index is now lower than readState.Index' (duration: 6.019201ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:56:41.411588Z","caller":"traceutil/trace.go:171","msg":"trace[2119455552] transaction","detail":"{read_only:false; response_revision:14368; number_of_response:1; }","duration":"199.997043ms","start":"2026-10-26T21:56:41.211552Z","end":"2026-10-26T21:56:41.411549Z","steps":["trace[2119455552] 'process raft request' (duration: 193.907601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:56:41.411657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.591043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-26T21:56:41.411658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.034599ms","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-10-26T21:56:41.411692Z","caller":"traceutil/trace.go:171","msg":"trace[1044785471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14368; }","duration":"153.648005ms","start":"2026-10-26T21:56:41.258033Z","end":"2026-10-26T21:56:41.411681Z","steps":["trace[1044785471] 'agreement among raft nodes before linearized reading' (duration: 153.534242ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:56:41.411704Z","caller":"traceutil/trace.go:171","msg":"trace[1859228201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14368; }","duration":"167.141212ms","start":"2026-10-26T21:56:41.244549Z","end":"2026-10-26T21:56:41.41169Z","steps":["trace[1859228201] 'agreement among raft nodes before linearized reading' (duration: 166.993378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:56:41.411913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.986982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03b0fbc6-cb66-43e1-a4ed-89d58f1abf34\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-10-26T21:56:41.411976Z","caller":"traceutil/trace.go:171","msg":"trace[1497903917] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03b0fbc6-cb66-43e1-a4ed-89d58f1abf34; range_end:; response_count:1; response_revision:14368; }","duration":"144.048094ms","start":"2026-10-26T21:56:41.267897Z","end":"2026-10-26T21:56:41.411945Z","steps":["trace[1497903917] 'agreement among raft nodes before linearized reading' (duration: 143.88944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:56:41.412022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.592528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xpnrs\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-26T21:56:41.412087Z","caller":"traceutil/trace.go:171","msg":"trace[744143775] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xpnrs; range_end:; response_count:1; response_revision:14368; }","duration":"146.71001ms","start":"2026-10-26T21:56:41.26536Z","end":"2026-10-26T21:56:41.41207Z","steps":["trace[744143775] 'agreement among raft nodes before linearized reading' (duration: 146.430133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:57:47.867654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.176022ms","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-10-26T21:57:47.867745Z","caller":"traceutil/trace.go:171","msg":"trace[1766283598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15282; }","duration":"105.317315ms","start":"2026-10-26T21:57:47.762409Z","end":"2026-10-26T21:57:47.867726Z","steps":["trace[1766283598] 'range keys from in-memory index tree' (duration: 105.008507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:57:47.868102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.980686ms","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-10-26T21:57:47.868133Z","caller":"traceutil/trace.go:171","msg":"trace[1149004154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15282; }","duration":"107.038338ms","start":"2026-10-26T21:57:47.761086Z","end":"2026-10-26T21:57:47.868124Z","steps":["trace[1149004154] 'range keys from in-memory index tree' (duration: 106.831213ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:58:00.590909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12761} {"level":"info","ts":"2026-10-26T21:58:00.674786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12761,"took":"82.054323ms","hash":1059119690,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":31551488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T21:58:00.674886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1059119690,"revision":12761,"compact-revision":10493} {"level":"info","ts":"2026-10-26T21:58:06.456882Z","caller":"traceutil/trace.go:171","msg":"trace[66069629] transaction","detail":"{read_only:false; response_revision:15386; number_of_response:1; }","duration":"129.054929ms","start":"2026-10-26T21:58:06.327764Z","end":"2026-10-26T21:58:06.456819Z","steps":["trace[66069629] 'process raft request' (duration: 128.774522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:58:06.649441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.397361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rjlws\" ","response":"range_response_count:1 size:9798"} {"level":"info","ts":"2026-10-26T21:58:06.649525Z","caller":"traceutil/trace.go:171","msg":"trace[685654359] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rjlws; range_end:; response_count:1; response_revision:15386; }","duration":"108.533826ms","start":"2026-10-26T21:58:06.540972Z","end":"2026-10-26T21:58:06.649506Z","steps":["trace[685654359] 'range keys from in-memory index tree' (duration: 108.194867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:01:20.71035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.600081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-26T22:01:20.710449Z","caller":"traceutil/trace.go:171","msg":"trace[1894599345] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:16925; }","duration":"107.757385ms","start":"2026-10-26T22:01:20.602669Z","end":"2026-10-26T22:01:20.710427Z","steps":["trace[1894599345] 'count revisions from in-memory index tree' (duration: 107.512479ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:03:00.619657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15354} {"level":"info","ts":"2026-10-26T22:03:00.727814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15354,"took":"105.215411ms","hash":1388946865,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:03:00.727878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1388946865,"revision":15354,"compact-revision":12761} {"level":"warn","ts":"2026-10-26T22:03:58.617786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.430115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-kjwhb\" ","response":"range_response_count:1 size:15431"} {"level":"info","ts":"2026-10-26T22:03:58.617849Z","caller":"traceutil/trace.go:171","msg":"trace[1222142007] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-kjwhb; range_end:; response_count:1; response_revision:17987; }","duration":"288.549407ms","start":"2026-10-26T22:03:58.329284Z","end":"2026-10-26T22:03:58.617833Z","steps":["trace[1222142007] 'range keys from in-memory index tree' (duration: 288.288401ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:03:58.634183Z","caller":"traceutil/trace.go:171","msg":"trace[1084556271] transaction","detail":"{read_only:false; response_revision:17988; number_of_response:1; }","duration":"302.26512ms","start":"2026-10-26T22:03:58.331888Z","end":"2026-10-26T22:03:58.634153Z","steps":["trace[1084556271] 'process raft request' (duration: 301.980113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:03:58.634338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:03:58.331869Z","time spent":"302.397104ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":936,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T22:03:58.634373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.457534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T22:03:58.634463Z","caller":"traceutil/trace.go:171","msg":"trace[447541749] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:17988; }","duration":"278.555347ms","start":"2026-10-26T22:03:58.355882Z","end":"2026-10-26T22:03:58.634438Z","steps":["trace[447541749] 'agreement among raft nodes before linearized reading' (duration: 278.351782ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:03:58.636339Z","caller":"traceutil/trace.go:171","msg":"trace[505560463] linearizableReadLoop","detail":"{readStateIndex:18600; appliedIndex:18599; }","duration":"278.193078ms","start":"2026-10-26T22:03:58.355897Z","end":"2026-10-26T22:03:58.63409Z","steps":["trace[505560463] 'read index received' (duration: 278.086816ms)","trace[505560463] 'applied index is now lower than readState.Index' (duration: 104.802µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:03:58.641132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.392367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-xk5bl\" ","response":"range_response_count:1 size:22121"} {"level":"warn","ts":"2026-10-26T22:03:58.641161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.616889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T22:03:58.641185Z","caller":"traceutil/trace.go:171","msg":"trace[1238736019] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-xk5bl; range_end:; response_count:1; response_revision:17989; }","duration":"171.484929ms","start":"2026-10-26T22:03:58.469687Z","end":"2026-10-26T22:03:58.641172Z","steps":["trace[1238736019] 'agreement among raft nodes before linearized reading' (duration: 171.300915ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:03:58.64121Z","caller":"traceutil/trace.go:171","msg":"trace[1162403719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17989; }","duration":"272.715051ms","start":"2026-10-26T22:03:58.368479Z","end":"2026-10-26T22:03:58.641194Z","steps":["trace[1162403719] 'agreement among raft nodes before linearized reading' (duration: 272.498936ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:03:59.78242Z","caller":"traceutil/trace.go:171","msg":"trace[1107467865] transaction","detail":"{read_only:false; response_revision:18009; number_of_response:1; }","duration":"161.751774ms","start":"2026-10-26T22:03:59.62064Z","end":"2026-10-26T22:03:59.782391Z","steps":["trace[1107467865] 'process raft request' (duration: 144.304458ms)","trace[1107467865] 'compare' (duration: 17.128358ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:04:05.478409Z","caller":"traceutil/trace.go:171","msg":"trace[942506132] transaction","detail":"{read_only:false; response_revision:18054; number_of_response:1; }","duration":"189.319325ms","start":"2026-10-26T22:04:05.289028Z","end":"2026-10-26T22:04:05.478347Z","steps":["trace[942506132] 'process raft request' (duration: 189.046108ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:04:06.075124Z","caller":"traceutil/trace.go:171","msg":"trace[470436090] linearizableReadLoop","detail":"{readStateIndex:18669; appliedIndex:18668; }","duration":"268.66935ms","start":"2026-10-26T22:04:05.806427Z","end":"2026-10-26T22:04:06.075096Z","steps":["trace[470436090] 'read index received' (duration: 268.480605ms)","trace[470436090] 'applied index is now lower than readState.Index' (duration: 187.575µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:04:06.075176Z","caller":"traceutil/trace.go:171","msg":"trace[211733241] transaction","detail":"{read_only:false; response_revision:18055; number_of_response:1; }","duration":"518.54626ms","start":"2026-10-26T22:04:05.556594Z","end":"2026-10-26T22:04:06.07514Z","steps":["trace[211733241] 'process raft request' (duration: 518.300514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:04:06.075363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:04:05.55657Z","time spent":"518.683684ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21013,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:04:06.075486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.021179ms","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-10-26T22:04:06.075617Z","caller":"traceutil/trace.go:171","msg":"trace[1150894269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18055; }","duration":"269.226194ms","start":"2026-10-26T22:04:05.80636Z","end":"2026-10-26T22:04:06.075586Z","steps":["trace[1150894269] 'agreement among raft nodes before linearized reading' (duration: 268.831384ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:04:06.243661Z","caller":"traceutil/trace.go:171","msg":"trace[129024492] linearizableReadLoop","detail":"{readStateIndex:18670; appliedIndex:18669; }","duration":"168.434513ms","start":"2026-10-26T22:04:06.075196Z","end":"2026-10-26T22:04:06.243631Z","steps":["trace[129024492] 'read index received' (duration: 123.598911ms)","trace[129024492] 'applied index is now lower than readState.Index' (duration: 44.834632ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:04:06.243955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.966033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-26T22:04:06.244032Z","caller":"traceutil/trace.go:171","msg":"trace[322109060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18056; }","duration":"354.095806ms","start":"2026-10-26T22:04:05.889916Z","end":"2026-10-26T22:04:06.244012Z","steps":["trace[322109060] 'agreement among raft nodes before linearized reading' (duration: 353.873501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:04:06.244077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:04:05.889893Z","time spent":"354.175749ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T22:04:06.244206Z","caller":"traceutil/trace.go:171","msg":"trace[1434704702] transaction","detail":"{read_only:false; response_revision:18056; number_of_response:1; }","duration":"419.640086ms","start":"2026-10-26T22:04:05.824551Z","end":"2026-10-26T22:04:06.244191Z","steps":["trace[1434704702] 'process raft request' (duration: 374.23103ms)","trace[1434704702] 'compare' (duration: 44.523634ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:04:06.244294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:04:05.824522Z","time spent":"419.710968ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:04:06.244458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.985239ms","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-10-26T22:04:06.244512Z","caller":"traceutil/trace.go:171","msg":"trace[329132714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18056; }","duration":"387.102542ms","start":"2026-10-26T22:04:05.857394Z","end":"2026-10-26T22:04:06.244497Z","steps":["trace[329132714] 'agreement among raft nodes before linearized reading' (duration: 386.398094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:04:06.244586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:04:05.857368Z","time spent":"387.205904ms","remote":"127.0.0.1:42416","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":"warn","ts":"2026-10-26T22:04:06.268881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.967312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-phknz\" ","response":"range_response_count:1 size:21030"} {"level":"info","ts":"2026-10-26T22:04:06.26895Z","caller":"traceutil/trace.go:171","msg":"trace[844425827] transaction","detail":"{read_only:false; response_revision:18058; number_of_response:1; }","duration":"143.095349ms","start":"2026-10-26T22:04:06.125818Z","end":"2026-10-26T22:04:06.268913Z","steps":["trace[844425827] 'process raft request' (duration: 142.749471ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:04:06.268977Z","caller":"traceutil/trace.go:171","msg":"trace[1712359185] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-phknz; range_end:; response_count:1; response_revision:18059; }","duration":"174.115965ms","start":"2026-10-26T22:04:06.094837Z","end":"2026-10-26T22:04:06.268953Z","steps":["trace[1712359185] 'agreement among raft nodes before linearized reading' (duration: 173.847179ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:04:06.268696Z","caller":"traceutil/trace.go:171","msg":"trace[652572118] transaction","detail":"{read_only:false; response_revision:18057; number_of_response:1; }","duration":"181.891719ms","start":"2026-10-26T22:04:06.086783Z","end":"2026-10-26T22:04:06.268675Z","steps":["trace[652572118] 'process raft request' (duration: 181.587082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:00.6507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.674826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:05:00.650996Z","caller":"traceutil/trace.go:171","msg":"trace[1107819750] linearizableReadLoop","detail":"{readStateIndex:19373; appliedIndex:19371; }","duration":"115.742633ms","start":"2026-10-26T22:05:00.535228Z","end":"2026-10-26T22:05:00.650971Z","steps":["trace[1107819750] 'read index received' (duration: 8.653907ms)","trace[1107819750] 'applied index is now lower than readState.Index' (duration: 107.086836ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:05:00.651116Z","caller":"traceutil/trace.go:171","msg":"trace[1110262302] transaction","detail":"{read_only:false; response_revision:18738; number_of_response:1; }","duration":"157.044394ms","start":"2026-10-26T22:05:00.494053Z","end":"2026-10-26T22:05:00.651097Z","steps":["trace[1110262302] 'process raft request' (duration: 49.844675ms)","trace[1110262302] 'compare' (duration: 106.468661ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:05:00.651218Z","caller":"traceutil/trace.go:171","msg":"trace[1635348560] transaction","detail":"{read_only:false; response_revision:18739; number_of_response:1; }","duration":"149.722921ms","start":"2026-10-26T22:05:00.501448Z","end":"2026-10-26T22:05:00.651171Z","steps":["trace[1635348560] 'process raft request' (duration: 149.448484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:00.651552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.321537ms","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-10-26T22:05:00.651646Z","caller":"traceutil/trace.go:171","msg":"trace[1497994563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18739; }","duration":"116.413609ms","start":"2026-10-26T22:05:00.53521Z","end":"2026-10-26T22:05:00.651623Z","steps":["trace[1497994563] 'agreement among raft nodes before linearized reading' (duration: 116.112232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:00.693966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.324923ms","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-10-26T22:05:00.694065Z","caller":"traceutil/trace.go:171","msg":"trace[241093573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18740; }","duration":"123.480237ms","start":"2026-10-26T22:05:00.570562Z","end":"2026-10-26T22:05:00.694043Z","steps":["trace[241093573] 'agreement among raft nodes before linearized reading' (duration: 123.20815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:00.694315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.423655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-26T22:05:00.694354Z","caller":"traceutil/trace.go:171","msg":"trace[1968687939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18740; }","duration":"113.499257ms","start":"2026-10-26T22:05:00.580844Z","end":"2026-10-26T22:05:00.694343Z","steps":["trace[1968687939] 'agreement among raft nodes before linearized reading' (duration: 113.403355ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:21.427515Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"357bf0025115def9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:06:21.43535Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T22:06:21.435458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T22:08:00.626696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17588} {"level":"info","ts":"2026-10-26T22:08:00.730507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17588,"took":"98.257685ms","hash":1799544842,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":34271232,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T22:08:00.730579Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1799544842,"revision":17588,"compact-revision":15354} {"level":"info","ts":"2026-10-26T22:10:03.541067Z","caller":"traceutil/trace.go:171","msg":"trace[574346775] transaction","detail":"{read_only:false; response_revision:20854; number_of_response:1; }","duration":"108.958286ms","start":"2026-10-26T22:10:03.432084Z","end":"2026-10-26T22:10:03.541043Z","steps":["trace[574346775] 'process raft request' (duration: 108.815213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:10:03.880171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.09515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:10:03.880311Z","caller":"traceutil/trace.go:171","msg":"trace[241493549] transaction","detail":"{read_only:false; response_revision:20855; number_of_response:1; }","duration":"364.24439ms","start":"2026-10-26T22:10:03.516039Z","end":"2026-10-26T22:10:03.880283Z","steps":["trace[241493549] 'process raft request' (duration: 260.925184ms)","trace[241493549] 'compare' (duration: 102.944067ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:10:03.880384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:10:03.516007Z","time spent":"364.345283ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:10:03.882439Z","caller":"traceutil/trace.go:171","msg":"trace[1216258457] transaction","detail":"{read_only:false; response_revision:20857; number_of_response:1; }","duration":"112.347011ms","start":"2026-10-26T22:10:03.770055Z","end":"2026-10-26T22:10:03.882402Z","steps":["trace[1216258457] 'process raft request' (duration: 112.261299ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:10:03.882573Z","caller":"traceutil/trace.go:171","msg":"trace[2073678042] transaction","detail":"{read_only:false; response_revision:20856; number_of_response:1; }","duration":"243.848729ms","start":"2026-10-26T22:10:03.638638Z","end":"2026-10-26T22:10:03.882486Z","steps":["trace[2073678042] 'process raft request' (duration: 243.550152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:10:04.313069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.657407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.55\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-26T22:10:04.313171Z","caller":"traceutil/trace.go:171","msg":"trace[1799207315] range","detail":"{range_begin:/registry/masterleases/162.253.55.55; range_end:; response_count:1; response_revision:20858; }","duration":"282.849221ms","start":"2026-10-26T22:10:04.030294Z","end":"2026-10-26T22:10:04.313143Z","steps":["trace[1799207315] 'range keys from in-memory index tree' (duration: 282.511073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:10:04.699828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.22841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-26T22:10:04.699857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.810112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T22:10:04.699923Z","caller":"traceutil/trace.go:171","msg":"trace[930357867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20862; }","duration":"143.387504ms","start":"2026-10-26T22:10:04.556513Z","end":"2026-10-26T22:10:04.699901Z","steps":["trace[930357867] 'range keys from in-memory index tree' (duration: 142.944604ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:10:04.699931Z","caller":"traceutil/trace.go:171","msg":"trace[1543352918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20862; }","duration":"110.929425ms","start":"2026-10-26T22:10:04.588979Z","end":"2026-10-26T22:10:04.699909Z","steps":["trace[1543352918] 'range keys from in-memory index tree' (duration: 110.618818ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:10:04.940963Z","caller":"traceutil/trace.go:171","msg":"trace[935851829] transaction","detail":"{read_only:false; response_revision:20863; number_of_response:1; }","duration":"237.158351ms","start":"2026-10-26T22:10:04.703774Z","end":"2026-10-26T22:10:04.940933Z","steps":["trace[935851829] 'process raft request' (duration: 236.944165ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:10:04.944549Z","caller":"traceutil/trace.go:171","msg":"trace[1648218804] transaction","detail":"{read_only:false; response_revision:20864; number_of_response:1; }","duration":"240.301899ms","start":"2026-10-26T22:10:04.704229Z","end":"2026-10-26T22:10:04.944531Z","steps":["trace[1648218804] 'process raft request' (duration: 240.150336ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:10:04.94474Z","caller":"traceutil/trace.go:171","msg":"trace[1769008681] linearizableReadLoop","detail":"{readStateIndex:21570; appliedIndex:21568; }","duration":"203.670486ms","start":"2026-10-26T22:10:04.741046Z","end":"2026-10-26T22:10:04.944716Z","steps":["trace[1769008681] 'read index received' (duration: 199.751258ms)","trace[1769008681] 'applied index is now lower than readState.Index' (duration: 3.917718ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:10:04.944956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.888771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-10-26T22:10:04.945032Z","caller":"traceutil/trace.go:171","msg":"trace[508622301] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:20865; }","duration":"203.973023ms","start":"2026-10-26T22:10:04.741039Z","end":"2026-10-26T22:10:04.945012Z","steps":["trace[508622301] 'agreement among raft nodes before linearized reading' (duration: 203.775308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:10:04.94496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.188658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:10:04.945141Z","caller":"traceutil/trace.go:171","msg":"trace[1905348992] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:20865; }","duration":"159.427544ms","start":"2026-10-26T22:10:04.785693Z","end":"2026-10-26T22:10:04.945121Z","steps":["trace[1905348992] 'agreement among raft nodes before linearized reading' (duration: 159.179378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:10:05.301949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.548522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418311"} {"level":"info","ts":"2026-10-26T22:10:05.302037Z","caller":"traceutil/trace.go:171","msg":"trace[906347762] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:20865; }","duration":"158.704336ms","start":"2026-10-26T22:10:05.143311Z","end":"2026-10-26T22:10:05.302015Z","steps":["trace[906347762] 'range keys from in-memory index tree' (duration: 158.242615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:10:05.551752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.73582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:10:05.551822Z","caller":"traceutil/trace.go:171","msg":"trace[1320827487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20866; }","duration":"122.847612ms","start":"2026-10-26T22:10:05.428956Z","end":"2026-10-26T22:10:05.551803Z","steps":["trace[1320827487] 'range keys from in-memory index tree' (duration: 122.678848ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:13:00.632593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20011} {"level":"info","ts":"2026-10-26T22:13:00.749917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20011,"took":"113.672634ms","hash":293811522,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":30801920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:13:00.749985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":293811522,"revision":20011,"compact-revision":17588} {"level":"info","ts":"2026-10-26T22:14:13.196975Z","caller":"traceutil/trace.go:171","msg":"trace[914420933] transaction","detail":"{read_only:false; response_revision:23375; number_of_response:1; }","duration":"315.414708ms","start":"2026-10-26T22:14:12.881515Z","end":"2026-10-26T22:14:13.196929Z","steps":["trace[914420933] 'process raft request' (duration: 315.178072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:14:13.197276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:14:12.881492Z","time spent":"315.656245ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:14:13.263787Z","caller":"traceutil/trace.go:171","msg":"trace[70767029] transaction","detail":"{read_only:false; response_revision:23376; number_of_response:1; }","duration":"380.569448ms","start":"2026-10-26T22:14:12.883184Z","end":"2026-10-26T22:14:13.263753Z","steps":["trace[70767029] 'process raft request' (duration: 380.342142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:14:13.264151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:14:12.883159Z","time spent":"380.733052ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:14:13.264221Z","caller":"traceutil/trace.go:171","msg":"trace[1998675150] transaction","detail":"{read_only:false; response_revision:23377; number_of_response:1; }","duration":"379.796329ms","start":"2026-10-26T22:14:12.884357Z","end":"2026-10-26T22:14:13.264153Z","steps":["trace[1998675150] 'process raft request' (duration: 379.339287ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:14:13.264323Z","caller":"traceutil/trace.go:171","msg":"trace[635182891] transaction","detail":"{read_only:false; response_revision:23378; number_of_response:1; }","duration":"359.07986ms","start":"2026-10-26T22:14:12.905222Z","end":"2026-10-26T22:14:13.264302Z","steps":["trace[635182891] 'process raft request' (duration: 358.798843ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:14:13.264518Z","caller":"traceutil/trace.go:171","msg":"trace[1061597486] transaction","detail":"{read_only:false; response_revision:23379; number_of_response:1; }","duration":"211.956251ms","start":"2026-10-26T22:14:13.05254Z","end":"2026-10-26T22:14:13.264497Z","steps":["trace[1061597486] 'process raft request' (duration: 211.670614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:14:13.264483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:14:12.905203Z","time spent":"359.210014ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:14:13.264623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:14:12.884319Z","time spent":"380.070115ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:14:13.264793Z","caller":"traceutil/trace.go:171","msg":"trace[1319642276] transaction","detail":"{read_only:false; response_revision:23380; number_of_response:1; }","duration":"143.887778ms","start":"2026-10-26T22:14:13.120864Z","end":"2026-10-26T22:14:13.264752Z","steps":["trace[1319642276] 'process raft request' (duration: 143.54708ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:16:40.590202Z","caller":"traceutil/trace.go:171","msg":"trace[33709981] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"118.425552ms","start":"2026-10-26T22:16:40.471756Z","end":"2026-10-26T22:16:40.590182Z","steps":["trace[33709981] 'process raft request' (duration: 117.774495ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:18.747542Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065a0.wal"} {"level":"info","ts":"2026-10-26T22:17:50.195558Z","caller":"traceutil/trace.go:171","msg":"trace[459156493] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"146.826064ms","start":"2026-10-26T22:17:50.048705Z","end":"2026-10-26T22:17:50.195531Z","steps":["trace[459156493] 'process raft request' (duration: 146.66055ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:50.207948Z","caller":"traceutil/trace.go:171","msg":"trace[1861200092] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"158.852034ms","start":"2026-10-26T22:17:50.049075Z","end":"2026-10-26T22:17:50.207928Z","steps":["trace[1861200092] 'process raft request' (duration: 158.717371ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:50.208116Z","caller":"traceutil/trace.go:171","msg":"trace[1313537373] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"157.837759ms","start":"2026-10-26T22:17:50.050263Z","end":"2026-10-26T22:17:50.2081Z","steps":["trace[1313537373] 'process raft request' (duration: 157.621384ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:55.637801Z","caller":"traceutil/trace.go:171","msg":"trace[1000307860] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"152.393413ms","start":"2026-10-26T22:17:55.485374Z","end":"2026-10-26T22:17:55.637767Z","steps":["trace[1000307860] 'process raft request' (duration: 152.335061ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:55.637873Z","caller":"traceutil/trace.go:171","msg":"trace[928464853] transaction","detail":"{read_only:false; response_revision:25530; number_of_response:1; }","duration":"152.468815ms","start":"2026-10-26T22:17:55.48537Z","end":"2026-10-26T22:17:55.637839Z","steps":["trace[928464853] 'process raft request' (duration: 152.165967ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:55.663261Z","caller":"traceutil/trace.go:171","msg":"trace[546860560] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"147.08649ms","start":"2026-10-26T22:17:55.516158Z","end":"2026-10-26T22:17:55.663245Z","steps":["trace[546860560] 'process raft request' (duration: 146.891755ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:55.973785Z","caller":"traceutil/trace.go:171","msg":"trace[1511818367] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"299.247937ms","start":"2026-10-26T22:17:55.674501Z","end":"2026-10-26T22:17:55.973749Z","steps":["trace[1511818367] 'process raft request' (duration: 298.508538ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:56.220841Z","caller":"traceutil/trace.go:171","msg":"trace[160627695] linearizableReadLoop","detail":"{readStateIndex:26368; appliedIndex:26367; }","duration":"238.22885ms","start":"2026-10-26T22:17:55.982573Z","end":"2026-10-26T22:17:56.220802Z","steps":["trace[160627695] 'read index received' (duration: 213.522662ms)","trace[160627695] 'applied index is now lower than readState.Index' (duration: 24.704008ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:17:56.221133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.533268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:204"} {"level":"info","ts":"2026-10-26T22:17:56.221256Z","caller":"traceutil/trace.go:171","msg":"trace[1648373941] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:25534; }","duration":"238.678361ms","start":"2026-10-26T22:17:55.982549Z","end":"2026-10-26T22:17:56.221227Z","steps":["trace[1648373941] 'agreement among raft nodes before linearized reading' (duration: 238.433805ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:56.221398Z","caller":"traceutil/trace.go:171","msg":"trace[290454884] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"240.185559ms","start":"2026-10-26T22:17:55.980801Z","end":"2026-10-26T22:17:56.220987Z","steps":["trace[290454884] 'process raft request' (duration: 215.43073ms)","trace[290454884] 'compare' (duration: 23.97443ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:17:56.223547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.640391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-10-26T22:17:56.223638Z","caller":"traceutil/trace.go:171","msg":"trace[1807571235] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:25535; }","duration":"240.792185ms","start":"2026-10-26T22:17:55.982823Z","end":"2026-10-26T22:17:56.223615Z","steps":["trace[1807571235] 'agreement among raft nodes before linearized reading' (duration: 240.58233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:17:56.223768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.228651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-10-26T22:17:56.223847Z","caller":"traceutil/trace.go:171","msg":"trace[57009809] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:25535; }","duration":"240.375364ms","start":"2026-10-26T22:17:55.983449Z","end":"2026-10-26T22:17:56.223825Z","steps":["trace[57009809] 'agreement among raft nodes before linearized reading' (duration: 240.155129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:17:56.223777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.852379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T22:17:56.223926Z","caller":"traceutil/trace.go:171","msg":"trace[1472436818] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25535; }","duration":"172.100416ms","start":"2026-10-26T22:17:56.051807Z","end":"2026-10-26T22:17:56.223908Z","steps":["trace[1472436818] 'agreement among raft nodes before linearized reading' (duration: 171.764768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:17:56.576629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.743197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:17:56.576778Z","caller":"traceutil/trace.go:171","msg":"trace[983627305] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"350.93541ms","start":"2026-10-26T22:17:56.225804Z","end":"2026-10-26T22:17:56.576739Z","steps":["trace[983627305] 'process raft request' (duration: 174.971827ms)","trace[983627305] 'compare' (duration: 175.488371ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:17:56.576868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:17:56.225774Z","time spent":"351.039423ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:17:56.633054Z","caller":"traceutil/trace.go:171","msg":"trace[663149530] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"401.28488ms","start":"2026-10-26T22:17:56.231734Z","end":"2026-10-26T22:17:56.633019Z","steps":["trace[663149530] 'process raft request' (duration: 401.189097ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:56.633094Z","caller":"traceutil/trace.go:171","msg":"trace[1759574723] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"401.413973ms","start":"2026-10-26T22:17:56.231641Z","end":"2026-10-26T22:17:56.633055Z","steps":["trace[1759574723] 'process raft request' (duration: 401.149956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:17:56.633248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:17:56.231707Z","time spent":"401.432864ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:17:56.633271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:17:56.23161Z","time spent":"401.559547ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:17:56.63339Z","caller":"traceutil/trace.go:171","msg":"trace[1819111138] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"401.594387ms","start":"2026-10-26T22:17:56.231768Z","end":"2026-10-26T22:17:56.633363Z","steps":["trace[1819111138] 'process raft request' (duration: 401.208588ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:56.63346Z","caller":"traceutil/trace.go:171","msg":"trace[2130480957] transaction","detail":"{read_only:false; response_revision:25540; number_of_response:1; }","duration":"400.732246ms","start":"2026-10-26T22:17:56.232713Z","end":"2026-10-26T22:17:56.633445Z","steps":["trace[2130480957] 'process raft request' (duration: 400.540321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:17:56.6335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:17:56.231755Z","time spent":"401.69443ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:17:56.633567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:17:56.232699Z","time spent":"400.808468ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":652,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:17:56.633777Z","caller":"traceutil/trace.go:171","msg":"trace[667623985] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"400.883289ms","start":"2026-10-26T22:17:56.232877Z","end":"2026-10-26T22:17:56.633761Z","steps":["trace[667623985] 'process raft request' (duration: 400.48763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:17:56.633871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:17:56.232856Z","time spent":"400.969272ms","remote":"127.0.0.1:42428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1167,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:17:56.633904Z","caller":"traceutil/trace.go:171","msg":"trace[1074181511] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"395.582507ms","start":"2026-10-26T22:17:56.238293Z","end":"2026-10-26T22:17:56.633875Z","steps":["trace[1074181511] 'process raft request' (duration: 395.382102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:17:56.634081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:17:56.238276Z","time spent":"395.71179ms","remote":"127.0.0.1:42630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:17:56.634438Z","caller":"traceutil/trace.go:171","msg":"trace[293929919] linearizableReadLoop","detail":"{readStateIndex:26377; appliedIndex:26370; }","duration":"202.487406ms","start":"2026-10-26T22:17:56.431935Z","end":"2026-10-26T22:17:56.634422Z","steps":["trace[293929919] 'read index received' (duration: 200.871846ms)","trace[293929919] 'applied index is now lower than readState.Index' (duration: 1.61442ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:17:56.634578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.63431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:17:56.634614Z","caller":"traceutil/trace.go:171","msg":"trace[35103080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25543; }","duration":"202.713922ms","start":"2026-10-26T22:17:56.431891Z","end":"2026-10-26T22:17:56.634605Z","steps":["trace[35103080] 'agreement among raft nodes before linearized reading' (duration: 202.603609ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:17:56.634894Z","caller":"traceutil/trace.go:171","msg":"trace[1735570587] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"208.442996ms","start":"2026-10-26T22:17:56.426439Z","end":"2026-10-26T22:17:56.634882Z","steps":["trace[1735570587] 'process raft request' (duration: 207.882731ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:00.639789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22203} {"level":"info","ts":"2026-10-26T22:18:00.785955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22203,"took":"141.753577ms","hash":4021318935,"current-db-size-bytes":57741312,"current-db-size":"58 MB","current-db-size-in-use-bytes":45113344,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-26T22:18:00.786025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4021318935,"revision":22203,"compact-revision":20011} {"level":"info","ts":"2026-10-26T22:18:58.101935Z","caller":"traceutil/trace.go:171","msg":"trace[1402598045] linearizableReadLoop","detail":"{readStateIndex:26942; appliedIndex:26941; }","duration":"141.281514ms","start":"2026-10-26T22:18:57.960623Z","end":"2026-10-26T22:18:58.101905Z","steps":["trace[1402598045] 'read index received' (duration: 141.104619ms)","trace[1402598045] 'applied index is now lower than readState.Index' (duration: 175.735µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:18:58.102141Z","caller":"traceutil/trace.go:171","msg":"trace[2042718536] transaction","detail":"{read_only:false; response_revision:26093; number_of_response:1; }","duration":"201.494121ms","start":"2026-10-26T22:18:57.900613Z","end":"2026-10-26T22:18:58.102107Z","steps":["trace[2042718536] 'process raft request' (duration: 201.095401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:58.102299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.648593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T22:18:58.102423Z","caller":"traceutil/trace.go:171","msg":"trace[1970478368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26093; }","duration":"141.795927ms","start":"2026-10-26T22:18:57.960589Z","end":"2026-10-26T22:18:58.102385Z","steps":["trace[1970478368] 'agreement among raft nodes before linearized reading' (duration: 141.51921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:58.102516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.037647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T22:18:58.102565Z","caller":"traceutil/trace.go:171","msg":"trace[340308668] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26093; }","duration":"135.13074ms","start":"2026-10-26T22:18:57.967419Z","end":"2026-10-26T22:18:58.10255Z","steps":["trace[340308668] 'agreement among raft nodes before linearized reading' (duration: 134.953135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:58.441915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.02865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:18:58.442182Z","caller":"traceutil/trace.go:171","msg":"trace[1949444869] linearizableReadLoop","detail":"{readStateIndex:26945; appliedIndex:26942; }","duration":"316.686064ms","start":"2026-10-26T22:18:58.125477Z","end":"2026-10-26T22:18:58.442163Z","steps":["trace[1949444869] 'read index received' (duration: 49.316824ms)","trace[1949444869] 'applied index is now lower than readState.Index' (duration: 267.36833ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:18:58.442364Z","caller":"traceutil/trace.go:171","msg":"trace[426292560] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"331.142825ms","start":"2026-10-26T22:18:58.111174Z","end":"2026-10-26T22:18:58.442317Z","steps":["trace[426292560] 'process raft request' (duration: 330.94945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:58.442581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:58.111149Z","time spent":"331.33898ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:18:58.442786Z","caller":"traceutil/trace.go:171","msg":"trace[1615276709] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"335.857093ms","start":"2026-10-26T22:18:58.106915Z","end":"2026-10-26T22:18:58.442772Z","steps":["trace[1615276709] 'process raft request' (duration: 67.858838ms)","trace[1615276709] 'compare' (duration: 266.864286ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:18:58.442934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:58.106887Z","time spent":"336.010827ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:18:58.443127Z","caller":"traceutil/trace.go:171","msg":"trace[1514639403] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"336.098489ms","start":"2026-10-26T22:18:58.107017Z","end":"2026-10-26T22:18:58.443116Z","steps":["trace[1514639403] 'process raft request' (duration: 335.017811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:58.443198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:58.107003Z","time spent":"336.14823ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:18:58.444679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.184926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T22:18:58.444792Z","caller":"traceutil/trace.go:171","msg":"trace[1203281273] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26096; }","duration":"319.34135ms","start":"2026-10-26T22:18:58.125425Z","end":"2026-10-26T22:18:58.444767Z","steps":["trace[1203281273] 'agreement among raft nodes before linearized reading' (duration: 318.428257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:58.444848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:58.125376Z","time spent":"319.459733ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12454,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-26T22:18:58.74235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.020677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:18:58.742711Z","caller":"traceutil/trace.go:171","msg":"trace[135418217] linearizableReadLoop","detail":"{readStateIndex:26946; appliedIndex:26945; }","duration":"203.572624ms","start":"2026-10-26T22:18:58.53912Z","end":"2026-10-26T22:18:58.742693Z","steps":["trace[135418217] 'read index received' (duration: 102.025393ms)","trace[135418217] 'applied index is now lower than readState.Index' (duration: 101.545741ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:18:58.742792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.678016ms","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-10-26T22:18:58.742823Z","caller":"traceutil/trace.go:171","msg":"trace[54880477] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26097; }","duration":"203.773419ms","start":"2026-10-26T22:18:58.539041Z","end":"2026-10-26T22:18:58.742814Z","steps":["trace[54880477] 'agreement among raft nodes before linearized reading' (duration: 203.705917ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:58.742844Z","caller":"traceutil/trace.go:171","msg":"trace[1995889120] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"263.604395ms","start":"2026-10-26T22:18:58.479172Z","end":"2026-10-26T22:18:58.742777Z","steps":["trace[1995889120] 'process raft request' (duration: 162.045054ms)","trace[1995889120] 'compare' (duration: 100.519795ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:18:59.00879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.847784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:18:59.00893Z","caller":"traceutil/trace.go:171","msg":"trace[457260482] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26098; }","duration":"123.052939ms","start":"2026-10-26T22:18:58.885837Z","end":"2026-10-26T22:18:59.00889Z","steps":["trace[457260482] 'count revisions from in-memory index tree' (duration: 122.725661ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:59.024351Z","caller":"traceutil/trace.go:171","msg":"trace[1933794550] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"130.436853ms","start":"2026-10-26T22:18:58.893885Z","end":"2026-10-26T22:18:59.024322Z","steps":["trace[1933794550] 'process raft request' (duration: 130.258409ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:59.250357Z","caller":"traceutil/trace.go:171","msg":"trace[555937060] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"350.307184ms","start":"2026-10-26T22:18:58.900022Z","end":"2026-10-26T22:18:59.250329Z","steps":["trace[555937060] 'process raft request' (duration: 348.188421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:59.250586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:58.899991Z","time spent":"350.477368ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:18:59.25097Z","caller":"traceutil/trace.go:171","msg":"trace[1892235101] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"110.519234ms","start":"2026-10-26T22:18:59.140434Z","end":"2026-10-26T22:18:59.250954Z","steps":["trace[1892235101] 'process raft request' (duration: 110.35571ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:59.251002Z","caller":"traceutil/trace.go:171","msg":"trace[410971567] linearizableReadLoop","detail":"{readStateIndex:26951; appliedIndex:26947; }","duration":"252.546338ms","start":"2026-10-26T22:18:58.998307Z","end":"2026-10-26T22:18:59.250853Z","steps":["trace[410971567] 'read index received' (duration: 25.99523ms)","trace[410971567] 'applied index is now lower than readState.Index' (duration: 226.549918ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:18:59.251118Z","caller":"traceutil/trace.go:171","msg":"trace[208678690] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"350.058178ms","start":"2026-10-26T22:18:58.901024Z","end":"2026-10-26T22:18:59.251082Z","steps":["trace[208678690] 'process raft request' (duration: 349.091223ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:59.251171Z","caller":"traceutil/trace.go:171","msg":"trace[1535196948] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"348.224902ms","start":"2026-10-26T22:18:58.902933Z","end":"2026-10-26T22:18:59.251158Z","steps":["trace[1535196948] 'process raft request' (duration: 347.717729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:59.251237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:58.902918Z","time spent":"348.284774ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:18:59.251301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:58.901008Z","time spent":"350.189622ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:18:59.251285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.948258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T22:18:59.25137Z","caller":"traceutil/trace.go:171","msg":"trace[1973187476] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26103; }","duration":"253.087502ms","start":"2026-10-26T22:18:58.998259Z","end":"2026-10-26T22:18:59.251347Z","steps":["trace[1973187476] 'agreement among raft nodes before linearized reading' (duration: 252.941579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:59.251826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.947415ms","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-10-26T22:18:59.251911Z","caller":"traceutil/trace.go:171","msg":"trace[565523546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26103; }","duration":"121.09364ms","start":"2026-10-26T22:18:59.1308Z","end":"2026-10-26T22:18:59.251893Z","steps":["trace[565523546] 'agreement among raft nodes before linearized reading' (duration: 120.449114ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:22:38.634138Z","caller":"traceutil/trace.go:171","msg":"trace[1106615755] transaction","detail":"{read_only:false; response_revision:28257; number_of_response:1; }","duration":"118.289289ms","start":"2026-10-26T22:22:38.515817Z","end":"2026-10-26T22:22:38.634106Z","steps":["trace[1106615755] 'process raft request' (duration: 117.959371ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:22:38.634371Z","caller":"traceutil/trace.go:171","msg":"trace[738503770] linearizableReadLoop","detail":"{readStateIndex:29159; appliedIndex:29157; }","duration":"117.231283ms","start":"2026-10-26T22:22:38.517094Z","end":"2026-10-26T22:22:38.634326Z","steps":["trace[738503770] 'read index received' (duration: 33.767445ms)","trace[738503770] 'applied index is now lower than readState.Index' (duration: 83.460398ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:22:38.634443Z","caller":"traceutil/trace.go:171","msg":"trace[971191519] transaction","detail":"{read_only:false; response_revision:28258; number_of_response:1; }","duration":"117.316575ms","start":"2026-10-26T22:22:38.517091Z","end":"2026-10-26T22:22:38.634408Z","steps":["trace[971191519] 'process raft request' (duration: 116.950946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:22:38.634686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.569081ms","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-10-26T22:22:38.634735Z","caller":"traceutil/trace.go:171","msg":"trace[875623682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28258; }","duration":"117.667714ms","start":"2026-10-26T22:22:38.517055Z","end":"2026-10-26T22:22:38.634723Z","steps":["trace[875623682] 'agreement among raft nodes before linearized reading' (duration: 117.411787ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:23:00.646486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25570} {"level":"info","ts":"2026-10-26T22:23:00.832192Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25570,"took":"179.027999ms","hash":2140563752,"current-db-size-bytes":59379712,"current-db-size":"59 MB","current-db-size-in-use-bytes":36347904,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T22:23:00.832276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2140563752,"revision":25570,"compact-revision":22203} {"level":"info","ts":"2026-10-26T22:24:14.034198Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"357bf0025115def9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:24:14.041632Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T22:24:14.04177Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T22:28:00.653617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28523} {"level":"info","ts":"2026-10-26T22:28:00.813101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28523,"took":"156.192147ms","hash":3799337395,"current-db-size-bytes":59379712,"current-db-size":"59 MB","current-db-size-in-use-bytes":32722944,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T22:28:00.813203Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3799337395,"revision":28523,"compact-revision":25570} {"level":"info","ts":"2026-10-26T22:33:00.664425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30772} {"level":"info","ts":"2026-10-26T22:33:00.813917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30772,"took":"145.270434ms","hash":1243059921,"current-db-size-bytes":59379712,"current-db-size":"59 MB","current-db-size-in-use-bytes":31383552,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:33:00.814001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1243059921,"revision":30772,"compact-revision":28523} {"level":"info","ts":"2026-10-26T22:38:00.671773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33029} {"level":"info","ts":"2026-10-26T22:38:00.825266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33029,"took":"148.596731ms","hash":2075958475,"current-db-size-bytes":59379712,"current-db-size":"59 MB","current-db-size-in-use-bytes":31076352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:38:00.825434Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2075958475,"revision":33029,"compact-revision":30772} {"level":"info","ts":"2026-10-26T22:43:00.677653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35261} {"level":"info","ts":"2026-10-26T22:43:00.813835Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35261,"took":"132.906587ms","hash":466869481,"current-db-size-bytes":59379712,"current-db-size":"59 MB","current-db-size-in-use-bytes":32407552,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:43:00.81391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":466869481,"revision":35261,"compact-revision":33029} {"level":"info","ts":"2026-10-26T22:45:39.373003Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"357bf0025115def9","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:45:39.379889Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T22:45:39.380024Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T22:48:00.684201Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37554} {"level":"info","ts":"2026-10-26T22:48:00.819475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37554,"took":"130.181689ms","hash":2572269860,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":32206848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:48:00.819544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2572269860,"revision":37554,"compact-revision":35261} {"level":"info","ts":"2026-10-26T22:53:00.691325Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39833} {"level":"info","ts":"2026-10-26T22:53:00.831842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39833,"took":"137.301982ms","hash":794845335,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:53:00.83192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":794845335,"revision":39833,"compact-revision":37554}