{"level":"warn","ts":"2026-03-18T05:34:15.592315Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T05:34:15.592391Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-03-18T05:34:15.592454Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T05:34:15.592461Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-18T05:34:15.592476Z","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-03-18T05:34:15.593089Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-03-18T05:34:15.593162Z","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.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-03-18T05:34:15.598811Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.450852ms"} {"level":"info","ts":"2026-03-18T05:34:15.607034Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-03-18T05:34:15.607119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T05:34:15.607139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-03-18T05:34:15.607148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T05:34:15.607157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-03-18T05:34:15.607191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-03-18T05:34:15.619666Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T05:34:15.621076Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T05:34:15.622612Z","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-03-18T05:34:15.624531Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T05:34:15.629041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-03-18T05:34:15.62915Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-18T05:34:15.629184Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T05:34:15.62928Z","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-03-18T05:34:15.629411Z","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-03-18T05:34:15.629452Z","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-03-18T05:34:15.630448Z","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-03-18T05:34:15.630552Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-18T05:34:15.63058Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-18T05:34:15.63079Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T05:34:15.630952Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T05:34:16.607991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T05:34:16.608066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T05:34:16.60809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-03-18T05:34:16.60811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-03-18T05:34:16.608121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-18T05:34:16.608137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-03-18T05:34:16.60815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-18T05:34:16.609347Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T05:34:16.60937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T05:34:16.609357Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:16.609316Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T05:34:16.609927Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T05:34:16.609989Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T05:34:16.610662Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:16.610811Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:16.610879Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:16.612645Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T05:34:16.613674Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"warn","ts":"2026-03-18T05:34:37.236148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.824777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:34:37.236385Z","caller":"traceutil/trace.go:171","msg":"trace[1661963647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"210.124394ms","start":"2026-03-18T05:34:37.026232Z","end":"2026-03-18T05:34:37.236356Z","steps":["trace[1661963647] 'range keys from in-memory index tree' (duration: 209.704024ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:34:37.352406Z","caller":"traceutil/trace.go:171","msg":"trace[2130068627] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"111.818129ms","start":"2026-03-18T05:34:37.240554Z","end":"2026-03-18T05:34:37.352372Z","steps":["trace[2130068627] 'process raft request' (duration: 111.659515ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:34:42.690461Z","caller":"traceutil/trace.go:171","msg":"trace[915337974] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"128.257428ms","start":"2026-03-18T05:34:42.562137Z","end":"2026-03-18T05:34:42.690394Z","steps":["trace[915337974] 'process raft request' (duration: 128.010322ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:34:44.839529Z","caller":"traceutil/trace.go:171","msg":"trace[101460294] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"137.914833ms","start":"2026-03-18T05:34:44.70158Z","end":"2026-03-18T05:34:44.839495Z","steps":["trace[101460294] 'process raft request' (duration: 137.696848ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:34:51.637077Z","caller":"traceutil/trace.go:171","msg":"trace[1063970044] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"143.002311ms","start":"2026-03-18T05:34:51.494049Z","end":"2026-03-18T05:34:51.637051Z","steps":["trace[1063970044] 'read index received' (duration: 142.766336ms)","trace[1063970044] 'applied index is now lower than readState.Index' (duration: 234.995µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:34:51.63716Z","caller":"traceutil/trace.go:171","msg":"trace[1003531005] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"203.230435ms","start":"2026-03-18T05:34:51.43389Z","end":"2026-03-18T05:34:51.63712Z","steps":["trace[1003531005] 'process raft request' (duration: 202.996689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:51.63729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.242806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w7rbd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-18T05:34:51.637335Z","caller":"traceutil/trace.go:171","msg":"trace[2047185612] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w7rbd; range_end:; response_count:1; response_revision:548; }","duration":"143.364979ms","start":"2026-03-18T05:34:51.493956Z","end":"2026-03-18T05:34:51.637321Z","steps":["trace[2047185612] 'agreement among raft nodes before linearized reading' (duration: 143.204566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:52.2722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.3209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:34:52.272287Z","caller":"traceutil/trace.go:171","msg":"trace[34697371] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:549; }","duration":"448.470053ms","start":"2026-03-18T05:34:51.823795Z","end":"2026-03-18T05:34:52.272265Z","steps":["trace[34697371] 'range keys from in-memory index tree' (duration: 448.218537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:52.272334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:34:51.823769Z","time spent":"448.555765ms","remote":"127.0.0.1:57834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-03-18T05:34:52.272459Z","caller":"traceutil/trace.go:171","msg":"trace[937138172] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"202.850666ms","start":"2026-03-18T05:34:52.069552Z","end":"2026-03-18T05:34:52.272403Z","steps":["trace[937138172] 'process raft request' (duration: 154.395994ms)","trace[937138172] 'compare' (duration: 48.314279ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:34:52.840776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747811053088160675,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T05:34:53.099503Z","caller":"traceutil/trace.go:171","msg":"trace[1933201192] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"759.248896ms","start":"2026-03-18T05:34:52.340224Z","end":"2026-03-18T05:34:53.099472Z","steps":["trace[1933201192] 'read index received' (duration: 759.009391ms)","trace[1933201192] 'applied index is now lower than readState.Index' (duration: 238.725µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:34:53.099579Z","caller":"traceutil/trace.go:171","msg":"trace[1424471387] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"959.335129ms","start":"2026-03-18T05:34:52.140204Z","end":"2026-03-18T05:34:53.099539Z","steps":["trace[1424471387] 'process raft request' (duration: 959.118234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:53.099662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.45257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:34:53.099714Z","caller":"traceutil/trace.go:171","msg":"trace[130332833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"400.546383ms","start":"2026-03-18T05:34:52.699153Z","end":"2026-03-18T05:34:53.099699Z","steps":["trace[130332833] 'agreement among raft nodes before linearized reading' (duration: 400.464231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:53.099738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:34:52.699133Z","time spent":"400.601293ms","remote":"127.0.0.1:57756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T05:34:53.099745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.010297ms","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-03-18T05:34:53.099782Z","caller":"traceutil/trace.go:171","msg":"trace[2123327686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"180.078008ms","start":"2026-03-18T05:34:52.919694Z","end":"2026-03-18T05:34:53.099772Z","steps":["trace[2123327686] 'agreement among raft nodes before linearized reading' (duration: 179.930235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:53.099888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.308105ms","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-03-18T05:34:53.099965Z","caller":"traceutil/trace.go:171","msg":"trace[69698059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"212.388447ms","start":"2026-03-18T05:34:52.887552Z","end":"2026-03-18T05:34:53.099941Z","steps":["trace[69698059] 'agreement among raft nodes before linearized reading' (duration: 212.162132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:53.099927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.657957ms","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-03-18T05:34:53.100058Z","caller":"traceutil/trace.go:171","msg":"trace[330943917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"759.848611ms","start":"2026-03-18T05:34:52.340175Z","end":"2026-03-18T05:34:53.100023Z","steps":["trace[330943917] 'agreement among raft nodes before linearized reading' (duration: 759.440512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:53.100115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:34:52.340157Z","time spent":"759.946633ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:34:53.100226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:34:52.140179Z","time spent":"959.490302ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4749,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:34:53.744475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.110838ms","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-03-18T05:34:53.744556Z","caller":"traceutil/trace.go:171","msg":"trace[1418710566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"103.244211ms","start":"2026-03-18T05:34:53.641293Z","end":"2026-03-18T05:34:53.744537Z","steps":["trace[1418710566] 'range keys from in-memory index tree' (duration: 102.942173ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:34:54.800268Z","caller":"traceutil/trace.go:171","msg":"trace[162917859] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"169.200347ms","start":"2026-03-18T05:34:54.631002Z","end":"2026-03-18T05:34:54.800202Z","steps":["trace[162917859] 'process raft request' (duration: 168.840508ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:34:54.802293Z","caller":"traceutil/trace.go:171","msg":"trace[2146783238] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"168.86575ms","start":"2026-03-18T05:34:54.632632Z","end":"2026-03-18T05:34:54.801497Z","steps":["trace[2146783238] 'process raft request' (duration: 167.949698ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:34:54.801472Z","caller":"traceutil/trace.go:171","msg":"trace[1888229224] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"101.781626ms","start":"2026-03-18T05:34:54.699654Z","end":"2026-03-18T05:34:54.801436Z","steps":["trace[1888229224] 'read index received' (duration: 100.117948ms)","trace[1888229224] 'applied index is now lower than readState.Index' (duration: 1.662178ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:34:54.803126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.408554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:34:54.803328Z","caller":"traceutil/trace.go:171","msg":"trace[2106452385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"103.6449ms","start":"2026-03-18T05:34:54.699581Z","end":"2026-03-18T05:34:54.803226Z","steps":["trace[2106452385] 'agreement among raft nodes before linearized reading' (duration: 101.959011ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:11.773159Z","caller":"traceutil/trace.go:171","msg":"trace[312111606] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"128.557245ms","start":"2026-03-18T05:35:11.644571Z","end":"2026-03-18T05:35:11.773128Z","steps":["trace[312111606] 'process raft request' (duration: 123.172585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:12.273504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.264581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:35:12.273612Z","caller":"traceutil/trace.go:171","msg":"trace[1190473859] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"378.554642ms","start":"2026-03-18T05:35:11.895032Z","end":"2026-03-18T05:35:12.273587Z","steps":["trace[1190473859] 'read index received' (duration: 35.938134ms)","trace[1190473859] 'applied index is now lower than readState.Index' (duration: 342.615118ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:12.273782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.747845ms","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-03-18T05:35:12.2738Z","caller":"traceutil/trace.go:171","msg":"trace[1456161745] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"494.554975ms","start":"2026-03-18T05:35:11.779215Z","end":"2026-03-18T05:35:12.27377Z","steps":["trace[1456161745] 'process raft request' (duration: 151.793584ms)","trace[1456161745] 'compare' (duration: 342.151369ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:12.273841Z","caller":"traceutil/trace.go:171","msg":"trace[1412418737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"378.838387ms","start":"2026-03-18T05:35:11.894987Z","end":"2026-03-18T05:35:12.273826Z","steps":["trace[1412418737] 'agreement among raft nodes before linearized reading' (duration: 378.671093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:12.273891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:11.894972Z","time spent":"378.906509ms","remote":"127.0.0.1:58010","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-03-18T05:35:12.273952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:11.779193Z","time spent":"494.671906ms","remote":"127.0.0.1:58182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3875,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:35:29.453382Z","caller":"traceutil/trace.go:171","msg":"trace[444766050] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"150.949228ms","start":"2026-03-18T05:35:29.302414Z","end":"2026-03-18T05:35:29.453363Z","steps":["trace[444766050] 'read index received' (duration: 146.034337ms)","trace[444766050] 'applied index is now lower than readState.Index' (duration: 4.91378ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:29.453512Z","caller":"traceutil/trace.go:171","msg":"trace[1055222330] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"211.817652ms","start":"2026-03-18T05:35:29.241681Z","end":"2026-03-18T05:35:29.453498Z","steps":["trace[1055222330] 'process raft request' (duration: 206.804179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:29.453762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.332744ms","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-03-18T05:35:29.453806Z","caller":"traceutil/trace.go:171","msg":"trace[1556135917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"151.420646ms","start":"2026-03-18T05:35:29.302374Z","end":"2026-03-18T05:35:29.453795Z","steps":["trace[1556135917] 'agreement among raft nodes before linearized reading' (duration: 151.258724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:29.45377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.259192ms","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-03-18T05:35:29.453894Z","caller":"traceutil/trace.go:171","msg":"trace[1999668119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"145.420916ms","start":"2026-03-18T05:35:29.308451Z","end":"2026-03-18T05:35:29.453872Z","steps":["trace[1999668119] 'agreement among raft nodes before linearized reading' (duration: 145.130501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:29.454105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.023774ms","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-03-18T05:35:29.454233Z","caller":"traceutil/trace.go:171","msg":"trace[523154195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"141.181687ms","start":"2026-03-18T05:35:29.313019Z","end":"2026-03-18T05:35:29.454201Z","steps":["trace[523154195] 'agreement among raft nodes before linearized reading' (duration: 140.728609ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:46.875868Z","caller":"traceutil/trace.go:171","msg":"trace[1782896581] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"147.667497ms","start":"2026-03-18T05:35:46.72818Z","end":"2026-03-18T05:35:46.875848Z","steps":["trace[1782896581] 'process raft request' (duration: 147.590986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:00.298312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.473346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-03-18T05:36:00.298407Z","caller":"traceutil/trace.go:171","msg":"trace[39474936] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1584; }","duration":"449.626459ms","start":"2026-03-18T05:35:59.848761Z","end":"2026-03-18T05:36:00.298387Z","steps":["trace[39474936] 'range keys from in-memory index tree' (duration: 449.247831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:00.298451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:59.848726Z","time spent":"449.716282ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10691,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-03-18T05:36:00.298286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.724536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-268f1086-2f2c-4438-80ef-be483e3ecf19\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-18T05:36:00.298682Z","caller":"traceutil/trace.go:171","msg":"trace[885847188] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-268f1086-2f2c-4438-80ef-be483e3ecf19; range_end:; response_count:1; response_revision:1584; }","duration":"435.143346ms","start":"2026-03-18T05:35:59.863495Z","end":"2026-03-18T05:36:00.298638Z","steps":["trace[885847188] 'range keys from in-memory index tree' (duration: 434.527762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:00.298737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:59.863484Z","time spent":"435.235388ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-268f1086-2f2c-4438-80ef-be483e3ecf19\" "} {"level":"info","ts":"2026-03-18T05:36:00.312954Z","caller":"traceutil/trace.go:171","msg":"trace[1619903962] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"378.337394ms","start":"2026-03-18T05:35:59.934569Z","end":"2026-03-18T05:36:00.312907Z","steps":["trace[1619903962] 'process raft request' (duration: 377.886604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:00.31318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:59.934536Z","time spent":"378.482247ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:00.491632Z","caller":"traceutil/trace.go:171","msg":"trace[1419201297] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"115.925126ms","start":"2026-03-18T05:36:00.375678Z","end":"2026-03-18T05:36:00.491603Z","steps":["trace[1419201297] 'process raft request' (duration: 115.630749ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:00.491949Z","caller":"traceutil/trace.go:171","msg":"trace[714043393] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"109.793898ms","start":"2026-03-18T05:36:00.382112Z","end":"2026-03-18T05:36:00.491906Z","steps":["trace[714043393] 'process raft request' (duration: 109.726597ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:00.491988Z","caller":"traceutil/trace.go:171","msg":"trace[332350429] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"115.360603ms","start":"2026-03-18T05:36:00.376574Z","end":"2026-03-18T05:36:00.491934Z","steps":["trace[332350429] 'process raft request' (duration: 115.168619ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:00.49198Z","caller":"traceutil/trace.go:171","msg":"trace[360683717] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"115.559048ms","start":"2026-03-18T05:36:00.376402Z","end":"2026-03-18T05:36:00.491961Z","steps":["trace[360683717] 'process raft request' (duration: 115.119788ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:00.7442Z","caller":"traceutil/trace.go:171","msg":"trace[2031866355] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"148.957076ms","start":"2026-03-18T05:36:00.595209Z","end":"2026-03-18T05:36:00.744166Z","steps":["trace[2031866355] 'process raft request' (duration: 148.67219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:01.376211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.745508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:36:01.376343Z","caller":"traceutil/trace.go:171","msg":"trace[907292138] linearizableReadLoop","detail":"{readStateIndex:1655; appliedIndex:1654; }","duration":"605.0473ms","start":"2026-03-18T05:36:00.77127Z","end":"2026-03-18T05:36:01.376317Z","steps":["trace[907292138] 'read index received' (duration: 486.015615ms)","trace[907292138] 'applied index is now lower than readState.Index' (duration: 119.029915ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:01.376426Z","caller":"traceutil/trace.go:171","msg":"trace[21555534] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"628.063416ms","start":"2026-03-18T05:36:00.748328Z","end":"2026-03-18T05:36:01.376391Z","steps":["trace[21555534] 'process raft request' (duration: 509.029791ms)","trace[21555534] 'compare' (duration: 118.319599ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:01.376549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.280805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-18T05:36:01.376607Z","caller":"traceutil/trace.go:171","msg":"trace[1712295375] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1595; }","duration":"605.378287ms","start":"2026-03-18T05:36:00.771212Z","end":"2026-03-18T05:36:01.37659Z","steps":["trace[1712295375] 'agreement among raft nodes before linearized reading' (duration: 605.207003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:01.376649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:00.771192Z","time spent":"605.445618ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-18T05:36:01.376554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:00.748305Z","time spent":"628.182698ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:01.38858Z","caller":"traceutil/trace.go:171","msg":"trace[1000509154] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"125.784396ms","start":"2026-03-18T05:36:01.262755Z","end":"2026-03-18T05:36:01.388539Z","steps":["trace[1000509154] 'process raft request' (duration: 125.672003ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:07.65773Z","caller":"traceutil/trace.go:171","msg":"trace[1976651519] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"251.36221ms","start":"2026-03-18T05:36:07.406346Z","end":"2026-03-18T05:36:07.657708Z","steps":["trace[1976651519] 'process raft request' (duration: 251.188566ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:07.762839Z","caller":"traceutil/trace.go:171","msg":"trace[1311151320] linearizableReadLoop","detail":"{readStateIndex:1716; appliedIndex:1715; }","duration":"348.68911ms","start":"2026-03-18T05:36:07.414121Z","end":"2026-03-18T05:36:07.76281Z","steps":["trace[1311151320] 'read index received' (duration: 243.712149ms)","trace[1311151320] 'applied index is now lower than readState.Index' (duration: 104.976041ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:07.762882Z","caller":"traceutil/trace.go:171","msg":"trace[1597909415] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"353.635781ms","start":"2026-03-18T05:36:07.409215Z","end":"2026-03-18T05:36:07.762851Z","steps":["trace[1597909415] 'process raft request' (duration: 352.194978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:07.763061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:07.409197Z","time spent":"353.770503ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:36:07.763115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.960006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-03-18T05:36:07.763134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.132112ms","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-03-18T05:36:07.763154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.232448ms","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-03-18T05:36:07.763187Z","caller":"traceutil/trace.go:171","msg":"trace[1206900808] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1654; }","duration":"349.053827ms","start":"2026-03-18T05:36:07.414104Z","end":"2026-03-18T05:36:07.763158Z","steps":["trace[1206900808] 'agreement among raft nodes before linearized reading' (duration: 348.801262ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:07.763199Z","caller":"traceutil/trace.go:171","msg":"trace[1279646480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1654; }","duration":"122.32549ms","start":"2026-03-18T05:36:07.64086Z","end":"2026-03-18T05:36:07.763186Z","steps":["trace[1279646480] 'agreement among raft nodes before linearized reading' (duration: 122.197717ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:07.763204Z","caller":"traceutil/trace.go:171","msg":"trace[489746030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1654; }","duration":"129.223555ms","start":"2026-03-18T05:36:07.633944Z","end":"2026-03-18T05:36:07.763168Z","steps":["trace[489746030] 'agreement among raft nodes before linearized reading' (duration: 129.042081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:07.763237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:07.414096Z","time spent":"349.126999ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-18T05:36:07.763395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.131687ms","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-03-18T05:36:07.763437Z","caller":"traceutil/trace.go:171","msg":"trace[368041164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1654; }","duration":"123.195619ms","start":"2026-03-18T05:36:07.640231Z","end":"2026-03-18T05:36:07.763426Z","steps":["trace[368041164] 'agreement among raft nodes before linearized reading' (duration: 123.071327ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:12.597359Z","caller":"traceutil/trace.go:171","msg":"trace[1790254] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"185.957862ms","start":"2026-03-18T05:36:12.411357Z","end":"2026-03-18T05:36:12.597315Z","steps":["trace[1790254] 'process raft request' (duration: 185.688866ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:14.004989Z","caller":"traceutil/trace.go:171","msg":"trace[773883795] linearizableReadLoop","detail":"{readStateIndex:1751; appliedIndex:1750; }","duration":"216.519211ms","start":"2026-03-18T05:36:13.788446Z","end":"2026-03-18T05:36:14.004965Z","steps":["trace[773883795] 'read index received' (duration: 216.45327ms)","trace[773883795] 'applied index is now lower than readState.Index' (duration: 64.791µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:14.005099Z","caller":"traceutil/trace.go:171","msg":"trace[1718961940] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"329.779308ms","start":"2026-03-18T05:36:13.675285Z","end":"2026-03-18T05:36:14.005064Z","steps":["trace[1718961940] 'process raft request' (duration: 329.514761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:14.005241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.778317ms","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-03-18T05:36:14.005312Z","caller":"traceutil/trace.go:171","msg":"trace[2018886779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1688; }","duration":"216.884829ms","start":"2026-03-18T05:36:13.788389Z","end":"2026-03-18T05:36:14.005274Z","steps":["trace[2018886779] 'agreement among raft nodes before linearized reading' (duration: 216.692805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:14.005312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:13.675262Z","time spent":"329.955051ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:36:14.015138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.560696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-18T05:36:14.015191Z","caller":"traceutil/trace.go:171","msg":"trace[314712815] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1689; }","duration":"211.613207ms","start":"2026-03-18T05:36:13.803563Z","end":"2026-03-18T05:36:14.015176Z","steps":["trace[314712815] 'agreement among raft nodes before linearized reading' (duration: 211.487254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:14.01534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.837001ms","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-03-18T05:36:14.015449Z","caller":"traceutil/trace.go:171","msg":"trace[130195182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1689; }","duration":"227.016505ms","start":"2026-03-18T05:36:13.788409Z","end":"2026-03-18T05:36:14.015426Z","steps":["trace[130195182] 'agreement among raft nodes before linearized reading' (duration: 226.584115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:14.015484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.360104ms","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-03-18T05:36:14.015489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.883085ms","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-03-18T05:36:14.015568Z","caller":"traceutil/trace.go:171","msg":"trace[1660472846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1689; }","duration":"215.437686ms","start":"2026-03-18T05:36:13.800088Z","end":"2026-03-18T05:36:14.015526Z","steps":["trace[1660472846] 'agreement among raft nodes before linearized reading' (duration: 215.030897ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:14.015591Z","caller":"traceutil/trace.go:171","msg":"trace[190699712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1689; }","duration":"222.023288ms","start":"2026-03-18T05:36:13.793538Z","end":"2026-03-18T05:36:14.015561Z","steps":["trace[190699712] 'agreement among raft nodes before linearized reading' (duration: 221.651349ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:51.645477Z","caller":"traceutil/trace.go:171","msg":"trace[1768149188] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"195.992235ms","start":"2026-03-18T05:36:51.44946Z","end":"2026-03-18T05:36:51.645453Z","steps":["trace[1768149188] 'process raft request' (duration: 195.856062ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:52.537607Z","caller":"traceutil/trace.go:171","msg":"trace[939863794] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"332.612673ms","start":"2026-03-18T05:36:52.204963Z","end":"2026-03-18T05:36:52.537575Z","steps":["trace[939863794] 'read index received' (duration: 332.408728ms)","trace[939863794] 'applied index is now lower than readState.Index' (duration: 202.305µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:52.537796Z","caller":"traceutil/trace.go:171","msg":"trace[1472701541] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"350.083559ms","start":"2026-03-18T05:36:52.187695Z","end":"2026-03-18T05:36:52.537778Z","steps":["trace[1472701541] 'process raft request' (duration: 349.69538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.537934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.187671Z","time spent":"350.165661ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:36:52.538201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.361519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-18T05:36:52.538292Z","caller":"traceutil/trace.go:171","msg":"trace[707363588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1890; }","duration":"323.455511ms","start":"2026-03-18T05:36:52.214822Z","end":"2026-03-18T05:36:52.538278Z","steps":["trace[707363588] 'agreement among raft nodes before linearized reading' (duration: 323.250227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.538382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.21476Z","time spent":"323.609815ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T05:36:52.538531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.534755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T05:36:52.538689Z","caller":"traceutil/trace.go:171","msg":"trace[653600839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1890; }","duration":"333.76424ms","start":"2026-03-18T05:36:52.20489Z","end":"2026-03-18T05:36:52.538654Z","steps":["trace[653600839] 'agreement among raft nodes before linearized reading' (duration: 333.270379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.538683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.438461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-18T05:36:52.538764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.204873Z","time spent":"333.880503ms","remote":"127.0.0.1:58010","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":"info","ts":"2026-03-18T05:36:52.538779Z","caller":"traceutil/trace.go:171","msg":"trace[2009068156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1890; }","duration":"323.590495ms","start":"2026-03-18T05:36:52.215169Z","end":"2026-03-18T05:36:52.53876Z","steps":["trace[2009068156] 'agreement among raft nodes before linearized reading' (duration: 323.243997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.538775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.39629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-18T05:36:52.538834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.215162Z","time spent":"323.662496ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T05:36:52.538899Z","caller":"traceutil/trace.go:171","msg":"trace[1652098381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1890; }","duration":"323.594164ms","start":"2026-03-18T05:36:52.215275Z","end":"2026-03-18T05:36:52.538869Z","steps":["trace[1652098381] 'agreement among raft nodes before linearized reading' (duration: 322.97955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.538955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.215254Z","time spent":"323.693027ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T05:36:52.539096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.518253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-18T05:36:52.539203Z","caller":"traceutil/trace.go:171","msg":"trace[1515466023] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"232.654426ms","start":"2026-03-18T05:36:52.306516Z","end":"2026-03-18T05:36:52.539171Z","steps":["trace[1515466023] 'agreement among raft nodes before linearized reading' (duration: 231.578071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.854516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.380117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:36:52.854662Z","caller":"traceutil/trace.go:171","msg":"trace[1998836964] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"310.220704ms","start":"2026-03-18T05:36:52.544418Z","end":"2026-03-18T05:36:52.854639Z","steps":["trace[1998836964] 'process raft request' (duration: 87.588031ms)","trace[1998836964] 'compare' (duration: 222.170692ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:52.854847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.544387Z","time spent":"310.318096ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:52.899406Z","caller":"traceutil/trace.go:171","msg":"trace[1298215924] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"354.87871ms","start":"2026-03-18T05:36:52.544502Z","end":"2026-03-18T05:36:52.899381Z","steps":["trace[1298215924] 'process raft request' (duration: 354.652724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.899581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.544482Z","time spent":"354.998213ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:52.899745Z","caller":"traceutil/trace.go:171","msg":"trace[196917735] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"355.184127ms","start":"2026-03-18T05:36:52.544537Z","end":"2026-03-18T05:36:52.899721Z","steps":["trace[196917735] 'process raft request' (duration: 354.782597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.89985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.544501Z","time spent":"355.299279ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:52.899844Z","caller":"traceutil/trace.go:171","msg":"trace[1499840638] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1963; }","duration":"235.43107ms","start":"2026-03-18T05:36:52.664336Z","end":"2026-03-18T05:36:52.899767Z","steps":["trace[1499840638] 'read index received' (duration: 234.864616ms)","trace[1499840638] 'applied index is now lower than readState.Index' (duration: 565.584µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:52.899954Z","caller":"traceutil/trace.go:171","msg":"trace[963122340] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"355.168007ms","start":"2026-03-18T05:36:52.544746Z","end":"2026-03-18T05:36:52.899914Z","steps":["trace[963122340] 'process raft request' (duration: 354.85434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.900046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.680466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:36:52.900115Z","caller":"traceutil/trace.go:171","msg":"trace[1812865507] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:1894; }","duration":"235.820299ms","start":"2026-03-18T05:36:52.664277Z","end":"2026-03-18T05:36:52.900097Z","steps":["trace[1812865507] 'agreement among raft nodes before linearized reading' (duration: 235.688056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.900145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:52.544738Z","time spent":"355.3014ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:52.903393Z","caller":"traceutil/trace.go:171","msg":"trace[268932824] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"171.711292ms","start":"2026-03-18T05:36:52.731664Z","end":"2026-03-18T05:36:52.903375Z","steps":["trace[268932824] 'process raft request' (duration: 171.582609ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:52.903609Z","caller":"traceutil/trace.go:171","msg":"trace[890400941] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"154.835591ms","start":"2026-03-18T05:36:52.748738Z","end":"2026-03-18T05:36:52.903574Z","steps":["trace[890400941] 'process raft request' (duration: 154.591305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.903655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.059225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:36:52.903772Z","caller":"traceutil/trace.go:171","msg":"trace[702179175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"205.212979ms","start":"2026-03-18T05:36:52.698541Z","end":"2026-03-18T05:36:52.903754Z","steps":["trace[702179175] 'agreement among raft nodes before linearized reading' (duration: 205.014025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:52.903818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.427572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-18T05:36:52.903927Z","caller":"traceutil/trace.go:171","msg":"trace[325773716] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1896; }","duration":"104.535664ms","start":"2026-03-18T05:36:52.799354Z","end":"2026-03-18T05:36:52.903889Z","steps":["trace[325773716] 'agreement among raft nodes before linearized reading' (duration: 104.298109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:11.649105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.704313ms","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-03-18T05:42:11.649277Z","caller":"traceutil/trace.go:171","msg":"trace[1461516533] linearizableReadLoop","detail":"{readStateIndex:4538; appliedIndex:4537; }","duration":"144.937065ms","start":"2026-03-18T05:42:11.504307Z","end":"2026-03-18T05:42:11.649244Z","steps":["trace[1461516533] 'read index received' (duration: 138.666758ms)","trace[1461516533] 'applied index is now lower than readState.Index' (duration: 6.268476ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:11.649336Z","caller":"traceutil/trace.go:171","msg":"trace[551366823] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4364; }","duration":"139.958509ms","start":"2026-03-18T05:42:11.509352Z","end":"2026-03-18T05:42:11.649311Z","steps":["trace[551366823] 'range keys from in-memory index tree' (duration: 139.682033ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:11.649405Z","caller":"traceutil/trace.go:171","msg":"trace[1984878680] transaction","detail":"{read_only:false; response_revision:4365; number_of_response:1; }","duration":"178.486326ms","start":"2026-03-18T05:42:11.470871Z","end":"2026-03-18T05:42:11.649357Z","steps":["trace[1984878680] 'process raft request' (duration: 172.144838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:11.64951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.186421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-18T05:42:11.649554Z","caller":"traceutil/trace.go:171","msg":"trace[792527588] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4365; }","duration":"145.287693ms","start":"2026-03-18T05:42:11.504251Z","end":"2026-03-18T05:42:11.649538Z","steps":["trace[792527588] 'agreement among raft nodes before linearized reading' (duration: 145.106959ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:13.920991Z","caller":"traceutil/trace.go:171","msg":"trace[2007267234] transaction","detail":"{read_only:false; response_revision:4415; number_of_response:1; }","duration":"182.833827ms","start":"2026-03-18T05:42:13.738129Z","end":"2026-03-18T05:42:13.920963Z","steps":["trace[2007267234] 'process raft request' (duration: 182.675693ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:13.97618Z","caller":"traceutil/trace.go:171","msg":"trace[1433462418] transaction","detail":"{read_only:false; response_revision:4417; number_of_response:1; }","duration":"185.903889ms","start":"2026-03-18T05:42:13.790255Z","end":"2026-03-18T05:42:13.976159Z","steps":["trace[1433462418] 'process raft request' (duration: 185.829727ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:13.976196Z","caller":"traceutil/trace.go:171","msg":"trace[1989066132] transaction","detail":"{read_only:false; response_revision:4416; number_of_response:1; }","duration":"228.888389ms","start":"2026-03-18T05:42:13.747282Z","end":"2026-03-18T05:42:13.97617Z","steps":["trace[1989066132] 'process raft request' (duration: 228.521481ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:18.726876Z","caller":"traceutil/trace.go:171","msg":"trace[787096396] linearizableReadLoop","detail":"{readStateIndex:4778; appliedIndex:4777; }","duration":"221.797544ms","start":"2026-03-18T05:42:18.505058Z","end":"2026-03-18T05:42:18.726855Z","steps":["trace[787096396] 'read index received' (duration: 221.528298ms)","trace[787096396] 'applied index is now lower than readState.Index' (duration: 268.606µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:18.727248Z","caller":"traceutil/trace.go:171","msg":"trace[1783023978] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"318.722501ms","start":"2026-03-18T05:42:18.408515Z","end":"2026-03-18T05:42:18.727238Z","steps":["trace[1783023978] 'process raft request' (duration: 318.166838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:18.727345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:42:18.40849Z","time spent":"318.789003ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:42:18.727594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.535061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-422b9cf4-4be5-4a8e-a9e1-94ea861ebcc3\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-18T05:42:18.727623Z","caller":"traceutil/trace.go:171","msg":"trace[214533272] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-422b9cf4-4be5-4a8e-a9e1-94ea861ebcc3; range_end:; response_count:1; response_revision:4598; }","duration":"222.603293ms","start":"2026-03-18T05:42:18.50501Z","end":"2026-03-18T05:42:18.727614Z","steps":["trace[214533272] 'agreement among raft nodes before linearized reading' (duration: 222.49673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:18.727796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.790554ms","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-03-18T05:42:18.727817Z","caller":"traceutil/trace.go:171","msg":"trace[217727931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4598; }","duration":"221.851605ms","start":"2026-03-18T05:42:18.505958Z","end":"2026-03-18T05:42:18.72781Z","steps":["trace[217727931] 'agreement among raft nodes before linearized reading' (duration: 221.783104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:18.728063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.677955ms","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-03-18T05:42:18.728095Z","caller":"traceutil/trace.go:171","msg":"trace[52562876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4598; }","duration":"137.742467ms","start":"2026-03-18T05:42:18.590345Z","end":"2026-03-18T05:42:18.728087Z","steps":["trace[52562876] 'agreement among raft nodes before linearized reading' (duration: 137.667935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:18.728185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.918024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T05:42:18.72821Z","caller":"traceutil/trace.go:171","msg":"trace[1732257514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4598; }","duration":"211.964466ms","start":"2026-03-18T05:42:18.51624Z","end":"2026-03-18T05:42:18.728205Z","steps":["trace[1732257514] 'agreement among raft nodes before linearized reading' (duration: 211.900894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:18.728321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.864403ms","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-03-18T05:42:18.728344Z","caller":"traceutil/trace.go:171","msg":"trace[1296462879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4598; }","duration":"217.908054ms","start":"2026-03-18T05:42:18.51043Z","end":"2026-03-18T05:42:18.728338Z","steps":["trace[1296462879] 'agreement among raft nodes before linearized reading' (duration: 217.847912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:18.728481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.074808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-18T05:42:18.728501Z","caller":"traceutil/trace.go:171","msg":"trace[132505114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4598; }","duration":"218.117768ms","start":"2026-03-18T05:42:18.510376Z","end":"2026-03-18T05:42:18.728494Z","steps":["trace[132505114] 'agreement among raft nodes before linearized reading' (duration: 218.059117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:18.966847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.461963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:42:18.967082Z","caller":"traceutil/trace.go:171","msg":"trace[577031654] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"107.566485ms","start":"2026-03-18T05:42:18.859489Z","end":"2026-03-18T05:42:18.967055Z","steps":["trace[577031654] 'process raft request' (duration: 107.477433ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:18.967038Z","caller":"traceutil/trace.go:171","msg":"trace[1009173992] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"110.428681ms","start":"2026-03-18T05:42:18.856581Z","end":"2026-03-18T05:42:18.967009Z","steps":["trace[1009173992] 'compare' (duration: 101.284789ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:18.967129Z","caller":"traceutil/trace.go:171","msg":"trace[739603017] linearizableReadLoop","detail":"{readStateIndex:4786; appliedIndex:4785; }","duration":"109.630823ms","start":"2026-03-18T05:42:18.857464Z","end":"2026-03-18T05:42:18.967095Z","steps":["trace[739603017] 'read index received' (duration: 7.827432ms)","trace[739603017] 'applied index is now lower than readState.Index' (duration: 101.800451ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:42:18.96743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.997792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-422b9cf4-4be5-4a8e-a9e1-94ea861ebcc3\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-18T05:42:18.967545Z","caller":"traceutil/trace.go:171","msg":"trace[1654741955] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-422b9cf4-4be5-4a8e-a9e1-94ea861ebcc3; range_end:; response_count:1; response_revision:4607; }","duration":"110.237537ms","start":"2026-03-18T05:42:18.857284Z","end":"2026-03-18T05:42:18.967522Z","steps":["trace[1654741955] 'agreement among raft nodes before linearized reading' (duration: 109.908239ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:19.166754Z","caller":"traceutil/trace.go:171","msg":"trace[431833107] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"192.309058ms","start":"2026-03-18T05:42:18.9744Z","end":"2026-03-18T05:42:19.166709Z","steps":["trace[431833107] 'process raft request' (duration: 116.152535ms)","trace[431833107] 'compare' (duration: 75.559069ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:25.012288Z","caller":"traceutil/trace.go:171","msg":"trace[841392691] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"100.93338ms","start":"2026-03-18T05:42:24.911303Z","end":"2026-03-18T05:42:25.012236Z","steps":["trace[841392691] 'process raft request' (duration: 18.271895ms)","trace[841392691] 'compare' (duration: 82.414429ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:25.056578Z","caller":"traceutil/trace.go:171","msg":"trace[447843452] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"144.904744ms","start":"2026-03-18T05:42:24.911645Z","end":"2026-03-18T05:42:25.056549Z","steps":["trace[447843452] 'process raft request' (duration: 144.667228ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:25.056603Z","caller":"traceutil/trace.go:171","msg":"trace[134636471] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"144.317371ms","start":"2026-03-18T05:42:24.912264Z","end":"2026-03-18T05:42:25.056581Z","steps":["trace[134636471] 'process raft request' (duration: 144.193798ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:25.056793Z","caller":"traceutil/trace.go:171","msg":"trace[1276233612] linearizableReadLoop","detail":"{readStateIndex:4920; appliedIndex:4916; }","duration":"144.022133ms","start":"2026-03-18T05:42:24.912761Z","end":"2026-03-18T05:42:25.056783Z","steps":["trace[1276233612] 'read index received' (duration: 16.820021ms)","trace[1276233612] 'applied index is now lower than readState.Index' (duration: 127.200002ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:42:25.057138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.361532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-03-18T05:42:25.057187Z","caller":"traceutil/trace.go:171","msg":"trace[901575545] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4736; }","duration":"144.462864ms","start":"2026-03-18T05:42:24.912708Z","end":"2026-03-18T05:42:25.057171Z","steps":["trace[901575545] 'agreement among raft nodes before linearized reading' (duration: 144.103186ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:25.057158Z","caller":"traceutil/trace.go:171","msg":"trace[434078767] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"144.614257ms","start":"2026-03-18T05:42:24.912519Z","end":"2026-03-18T05:42:25.057134Z","steps":["trace[434078767] 'process raft request' (duration: 144.010863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:28.264359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.621003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:42:28.264519Z","caller":"traceutil/trace.go:171","msg":"trace[1486124032] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:4789; }","duration":"161.842739ms","start":"2026-03-18T05:42:28.102642Z","end":"2026-03-18T05:42:28.264485Z","steps":["trace[1486124032] 'range keys from in-memory index tree' (duration: 161.515421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:28.264892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.184354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vxw96\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-18T05:42:28.265031Z","caller":"traceutil/trace.go:171","msg":"trace[1743277909] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vxw96; range_end:; response_count:1; response_revision:4789; }","duration":"152.708475ms","start":"2026-03-18T05:42:28.112272Z","end":"2026-03-18T05:42:28.26498Z","steps":["trace[1743277909] 'range keys from in-memory index tree' (duration: 152.006119ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:28.26578Z","caller":"traceutil/trace.go:171","msg":"trace[1140750657] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"153.081234ms","start":"2026-03-18T05:42:28.112512Z","end":"2026-03-18T05:42:28.265593Z","steps":["trace[1140750657] 'process raft request' (duration: 137.558303ms)","trace[1140750657] 'compare' (duration: 13.852483ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:28.395445Z","caller":"traceutil/trace.go:171","msg":"trace[37685750] linearizableReadLoop","detail":"{readStateIndex:4977; appliedIndex:4976; }","duration":"126.310621ms","start":"2026-03-18T05:42:28.269073Z","end":"2026-03-18T05:42:28.395384Z","steps":["trace[37685750] 'read index received' (duration: 105.741193ms)","trace[37685750] 'applied index is now lower than readState.Index' (duration: 20.568218ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:28.39561Z","caller":"traceutil/trace.go:171","msg":"trace[1310579982] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"277.192124ms","start":"2026-03-18T05:42:28.118351Z","end":"2026-03-18T05:42:28.395543Z","steps":["trace[1310579982] 'process raft request' (duration: 256.547874ms)","trace[1310579982] 'compare' (duration: 20.288432ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:42:28.395921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.823173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70791"} {"level":"info","ts":"2026-03-18T05:42:28.396049Z","caller":"traceutil/trace.go:171","msg":"trace[193244418] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:4791; }","duration":"127.016017ms","start":"2026-03-18T05:42:28.269005Z","end":"2026-03-18T05:42:28.396021Z","steps":["trace[193244418] 'agreement among raft nodes before linearized reading' (duration: 126.574567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:28.396158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.951092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125385"} {"level":"info","ts":"2026-03-18T05:42:28.396201Z","caller":"traceutil/trace.go:171","msg":"trace[1352750575] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4791; }","duration":"126.224599ms","start":"2026-03-18T05:42:28.269966Z","end":"2026-03-18T05:42:28.39619Z","steps":["trace[1352750575] 'agreement among raft nodes before linearized reading' (duration: 125.666526ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:35.856623Z","caller":"traceutil/trace.go:171","msg":"trace[1940908149] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"102.527378ms","start":"2026-03-18T05:42:35.754059Z","end":"2026-03-18T05:42:35.856587Z","steps":["trace[1940908149] 'process raft request' (duration: 26.504838ms)","trace[1940908149] 'compare' (duration: 75.746253ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:42:36.828106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.880398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:42:36.82824Z","caller":"traceutil/trace.go:171","msg":"trace[1802625635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4968; }","duration":"128.052462ms","start":"2026-03-18T05:42:36.700161Z","end":"2026-03-18T05:42:36.828213Z","steps":["trace[1802625635] 'range keys from in-memory index tree' (duration: 127.740654ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:37.07191Z","caller":"traceutil/trace.go:171","msg":"trace[1816424001] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"203.025877ms","start":"2026-03-18T05:42:36.868845Z","end":"2026-03-18T05:42:37.07187Z","steps":["trace[1816424001] 'process raft request' (duration: 154.532518ms)","trace[1816424001] 'compare' (duration: 48.376056ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:37.124816Z","caller":"traceutil/trace.go:171","msg":"trace[777148445] linearizableReadLoop","detail":"{readStateIndex:5162; appliedIndex:5160; }","duration":"123.26009ms","start":"2026-03-18T05:42:37.001523Z","end":"2026-03-18T05:42:37.124783Z","steps":["trace[777148445] 'read index received' (duration: 21.868719ms)","trace[777148445] 'applied index is now lower than readState.Index' (duration: 101.389091ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:37.125029Z","caller":"traceutil/trace.go:171","msg":"trace[970109936] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"213.556092ms","start":"2026-03-18T05:42:36.911444Z","end":"2026-03-18T05:42:37.125Z","steps":["trace[970109936] 'process raft request' (duration: 213.121062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:37.125202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.641949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8c92dd0-cdfc-460d-8966-0b96750d502a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-18T05:42:37.125292Z","caller":"traceutil/trace.go:171","msg":"trace[1746208032] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8c92dd0-cdfc-460d-8966-0b96750d502a; range_end:; response_count:1; response_revision:4973; }","duration":"123.771941ms","start":"2026-03-18T05:42:37.001501Z","end":"2026-03-18T05:42:37.125273Z","steps":["trace[1746208032] 'agreement among raft nodes before linearized reading' (duration: 123.495185ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:37.390289Z","caller":"traceutil/trace.go:171","msg":"trace[1432614066] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"257.764672ms","start":"2026-03-18T05:42:37.132494Z","end":"2026-03-18T05:42:37.390259Z","steps":["trace[1432614066] 'process raft request' (duration: 257.568067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:56.365914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.398386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:42:56.366189Z","caller":"traceutil/trace.go:171","msg":"trace[834016241] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"164.251038ms","start":"2026-03-18T05:42:56.201907Z","end":"2026-03-18T05:42:56.366158Z","steps":["trace[834016241] 'process raft request' (duration: 54.506414ms)","trace[834016241] 'compare' (duration: 109.252793ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:56.366171Z","caller":"traceutil/trace.go:171","msg":"trace[1991562734] linearizableReadLoop","detail":"{readStateIndex:5360; appliedIndex:5359; }","duration":"157.347862ms","start":"2026-03-18T05:42:56.208785Z","end":"2026-03-18T05:42:56.366133Z","steps":["trace[1991562734] 'read index received' (duration: 47.597508ms)","trace[1991562734] 'applied index is now lower than readState.Index' (duration: 109.747774ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:42:56.366318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.508196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:42:56.36637Z","caller":"traceutil/trace.go:171","msg":"trace[1422804966] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5164; }","duration":"157.587268ms","start":"2026-03-18T05:42:56.208773Z","end":"2026-03-18T05:42:56.36636Z","steps":["trace[1422804966] 'agreement among raft nodes before linearized reading' (duration: 157.458855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:56.366426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.288563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:42:56.366498Z","caller":"traceutil/trace.go:171","msg":"trace[1818541361] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:5164; }","duration":"129.379965ms","start":"2026-03-18T05:42:56.237099Z","end":"2026-03-18T05:42:56.366479Z","steps":["trace[1818541361] 'agreement among raft nodes before linearized reading' (duration: 129.258712ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:56.5768Z","caller":"traceutil/trace.go:171","msg":"trace[971455512] linearizableReadLoop","detail":"{readStateIndex:5362; appliedIndex:5361; }","duration":"118.870407ms","start":"2026-03-18T05:42:56.457909Z","end":"2026-03-18T05:42:56.57678Z","steps":["trace[971455512] 'read index received' (duration: 76.725531ms)","trace[971455512] 'applied index is now lower than readState.Index' (duration: 42.144086ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:56.576831Z","caller":"traceutil/trace.go:171","msg":"trace[158271155] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"127.062382ms","start":"2026-03-18T05:42:56.44974Z","end":"2026-03-18T05:42:56.576802Z","steps":["trace[158271155] 'process raft request' (duration: 84.856654ms)","trace[158271155] 'compare' (duration: 42.001693ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:42:56.577232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.288018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:42:56.577319Z","caller":"traceutil/trace.go:171","msg":"trace[510868887] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5165; }","duration":"119.43635ms","start":"2026-03-18T05:42:56.457862Z","end":"2026-03-18T05:42:56.577298Z","steps":["trace[510868887] 'agreement among raft nodes before linearized reading' (duration: 119.048511ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:44:16.639829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2796} {"level":"info","ts":"2026-03-18T05:44:16.700803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2796,"took":"59.346664ms","hash":4121335265,"current-db-size-bytes":41123840,"current-db-size":"41 MB","current-db-size-in-use-bytes":30785536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-18T05:44:16.70088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4121335265,"revision":2796,"compact-revision":-1} {"level":"warn","ts":"2026-03-18T05:48:53.761899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.083384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:48:53.761965Z","caller":"traceutil/trace.go:171","msg":"trace[1329045380] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:7673; }","duration":"163.203717ms","start":"2026-03-18T05:48:53.598749Z","end":"2026-03-18T05:48:53.761953Z","steps":["trace[1329045380] 'count revisions from in-memory index tree' (duration: 163.019193ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:48:53.762163Z","caller":"traceutil/trace.go:171","msg":"trace[1566862493] transaction","detail":"{read_only:false; response_revision:7674; number_of_response:1; }","duration":"147.513267ms","start":"2026-03-18T05:48:53.614621Z","end":"2026-03-18T05:48:53.762135Z","steps":["trace[1566862493] 'process raft request' (duration: 146.714089ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:48:53.76224Z","caller":"traceutil/trace.go:171","msg":"trace[768711267] transaction","detail":"{read_only:false; response_revision:7675; number_of_response:1; }","duration":"133.731742ms","start":"2026-03-18T05:48:53.628498Z","end":"2026-03-18T05:48:53.76223Z","steps":["trace[768711267] 'process raft request' (duration: 133.679821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:05.60709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.337597ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T05:49:05.607186Z","caller":"traceutil/trace.go:171","msg":"trace[999110538] linearizableReadLoop","detail":"{readStateIndex:8017; appliedIndex:8016; }","duration":"404.062802ms","start":"2026-03-18T05:49:05.203107Z","end":"2026-03-18T05:49:05.60717Z","steps":["trace[999110538] 'read index received' (duration: 89.5342ms)","trace[999110538] 'applied index is now lower than readState.Index' (duration: 314.527072ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:49:05.607327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.205595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:49:05.607353Z","caller":"traceutil/trace.go:171","msg":"trace[795634314] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:7729; }","duration":"404.300057ms","start":"2026-03-18T05:49:05.203045Z","end":"2026-03-18T05:49:05.607345Z","steps":["trace[795634314] 'agreement among raft nodes before linearized reading' (duration: 404.218845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:05.607387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:05.203016Z","time spent":"404.363489ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-03-18T05:49:07.634287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.536973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:49:07.634415Z","caller":"traceutil/trace.go:171","msg":"trace[1784000986] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:7738; }","duration":"104.709967ms","start":"2026-03-18T05:49:07.529677Z","end":"2026-03-18T05:49:07.634387Z","steps":["trace[1784000986] 'count revisions from in-memory index tree' (duration: 104.426401ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:08.058939Z","caller":"traceutil/trace.go:171","msg":"trace[1650110630] transaction","detail":"{read_only:false; response_revision:7739; number_of_response:1; }","duration":"218.81462ms","start":"2026-03-18T05:49:07.840094Z","end":"2026-03-18T05:49:08.058909Z","steps":["trace[1650110630] 'process raft request' (duration: 218.629856ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:08.064478Z","caller":"traceutil/trace.go:171","msg":"trace[1748882196] transaction","detail":"{read_only:false; response_revision:7741; number_of_response:1; }","duration":"153.191447ms","start":"2026-03-18T05:49:07.911224Z","end":"2026-03-18T05:49:08.064415Z","steps":["trace[1748882196] 'process raft request' (duration: 153.137346ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:08.064511Z","caller":"traceutil/trace.go:171","msg":"trace[104207127] transaction","detail":"{read_only:false; response_revision:7740; number_of_response:1; }","duration":"221.43255ms","start":"2026-03-18T05:49:07.843048Z","end":"2026-03-18T05:49:08.064481Z","steps":["trace[104207127] 'process raft request' (duration: 221.124563ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:16.647779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5761} {"level":"info","ts":"2026-03-18T05:49:16.744113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5761,"took":"94.134362ms","hash":1497304177,"current-db-size-bytes":42680320,"current-db-size":"43 MB","current-db-size-in-use-bytes":23445504,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-18T05:49:16.744184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1497304177,"revision":5761,"compact-revision":2796} {"level":"info","ts":"2026-03-18T05:49:35.417673Z","caller":"traceutil/trace.go:171","msg":"trace[1434518716] transaction","detail":"{read_only:false; response_revision:7927; number_of_response:1; }","duration":"337.398543ms","start":"2026-03-18T05:49:35.080251Z","end":"2026-03-18T05:49:35.41765Z","steps":["trace[1434518716] 'process raft request' (duration: 337.238829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:35.417904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:35.080234Z","time spent":"337.577897ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:35.418438Z","caller":"traceutil/trace.go:171","msg":"trace[1470427089] linearizableReadLoop","detail":"{readStateIndex:8223; appliedIndex:8223; }","duration":"321.440688ms","start":"2026-03-18T05:49:35.096986Z","end":"2026-03-18T05:49:35.418427Z","steps":["trace[1470427089] 'read index received' (duration: 321.435338ms)","trace[1470427089] 'applied index is now lower than readState.Index' (duration: 4.48µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:49:35.41855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.567431ms","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-03-18T05:49:35.418575Z","caller":"traceutil/trace.go:171","msg":"trace[1431515600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7927; }","duration":"321.642043ms","start":"2026-03-18T05:49:35.096927Z","end":"2026-03-18T05:49:35.418569Z","steps":["trace[1431515600] 'agreement among raft nodes before linearized reading' (duration: 321.559801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:35.418593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:35.096905Z","time spent":"321.683634ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:49:35.440076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.665083ms","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-03-18T05:49:35.440136Z","caller":"traceutil/trace.go:171","msg":"trace[728894086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7928; }","duration":"328.779565ms","start":"2026-03-18T05:49:35.111345Z","end":"2026-03-18T05:49:35.440125Z","steps":["trace[728894086] 'agreement among raft nodes before linearized reading' (duration: 328.581101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:35.440161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:35.111321Z","time spent":"328.835757ms","remote":"127.0.0.1:58010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T05:49:38.717346Z","caller":"traceutil/trace.go:171","msg":"trace[522156261] transaction","detail":"{read_only:false; response_revision:7944; number_of_response:1; }","duration":"182.206185ms","start":"2026-03-18T05:49:38.535119Z","end":"2026-03-18T05:49:38.717326Z","steps":["trace[522156261] 'process raft request' (duration: 182.082903ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:47.682407Z","caller":"traceutil/trace.go:171","msg":"trace[65652554] transaction","detail":"{read_only:false; response_revision:8025; number_of_response:1; }","duration":"184.15056ms","start":"2026-03-18T05:49:47.498211Z","end":"2026-03-18T05:49:47.682362Z","steps":["trace[65652554] 'process raft request' (duration: 88.497773ms)","trace[65652554] 'compare' (duration: 95.493623ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:47.686162Z","caller":"traceutil/trace.go:171","msg":"trace[434251570] transaction","detail":"{read_only:false; response_revision:8027; number_of_response:1; }","duration":"186.620106ms","start":"2026-03-18T05:49:47.499514Z","end":"2026-03-18T05:49:47.686134Z","steps":["trace[434251570] 'process raft request' (duration: 186.525414ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:47.686373Z","caller":"traceutil/trace.go:171","msg":"trace[1680326733] transaction","detail":"{read_only:false; response_revision:8026; number_of_response:1; }","duration":"186.918923ms","start":"2026-03-18T05:49:47.499445Z","end":"2026-03-18T05:49:47.686364Z","steps":["trace[1680326733] 'process raft request' (duration: 186.453902ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:47.686579Z","caller":"traceutil/trace.go:171","msg":"trace[1453636026] linearizableReadLoop","detail":"{readStateIndex:8329; appliedIndex:8326; }","duration":"181.07511ms","start":"2026-03-18T05:49:47.50548Z","end":"2026-03-18T05:49:47.686555Z","steps":["trace[1453636026] 'read index received' (duration: 81.307039ms)","trace[1453636026] 'applied index is now lower than readState.Index' (duration: 99.76642ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:47.686592Z","caller":"traceutil/trace.go:171","msg":"trace[1601490117] transaction","detail":"{read_only:false; response_revision:8029; number_of_response:1; }","duration":"114.563109ms","start":"2026-03-18T05:49:47.571999Z","end":"2026-03-18T05:49:47.686563Z","steps":["trace[1601490117] 'process raft request' (duration: 114.368014ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:47.686623Z","caller":"traceutil/trace.go:171","msg":"trace[2039172412] transaction","detail":"{read_only:false; response_revision:8028; number_of_response:1; }","duration":"121.947378ms","start":"2026-03-18T05:49:47.564617Z","end":"2026-03-18T05:49:47.686565Z","steps":["trace[2039172412] 'process raft request' (duration: 121.467537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:47.68699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.479509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T05:49:47.68716Z","caller":"traceutil/trace.go:171","msg":"trace[241994675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8029; }","duration":"181.673783ms","start":"2026-03-18T05:49:47.505464Z","end":"2026-03-18T05:49:47.687138Z","steps":["trace[241994675] 'agreement among raft nodes before linearized reading' (duration: 181.239613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:47.687333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.978841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-m2x45\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-03-18T05:49:47.6879Z","caller":"traceutil/trace.go:171","msg":"trace[1757930457] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-m2x45; range_end:; response_count:1; response_revision:8029; }","duration":"116.399221ms","start":"2026-03-18T05:49:47.571185Z","end":"2026-03-18T05:49:47.687584Z","steps":["trace[1757930457] 'agreement among raft nodes before linearized reading' (duration: 115.857609ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:47.900772Z","caller":"traceutil/trace.go:171","msg":"trace[859821504] transaction","detail":"{read_only:false; response_revision:8030; number_of_response:1; }","duration":"207.482413ms","start":"2026-03-18T05:49:47.693262Z","end":"2026-03-18T05:49:47.900744Z","steps":["trace[859821504] 'process raft request' (duration: 149.18629ms)","trace[859821504] 'compare' (duration: 58.16278ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:47.932298Z","caller":"traceutil/trace.go:171","msg":"trace[2098465831] transaction","detail":"{read_only:false; response_revision:8031; number_of_response:1; }","duration":"235.642497ms","start":"2026-03-18T05:49:47.696616Z","end":"2026-03-18T05:49:47.932259Z","steps":["trace[2098465831] 'process raft request' (duration: 235.423582ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:48.850463Z","caller":"traceutil/trace.go:171","msg":"trace[1472660776] linearizableReadLoop","detail":"{readStateIndex:8341; appliedIndex:8340; }","duration":"151.40385ms","start":"2026-03-18T05:49:48.699025Z","end":"2026-03-18T05:49:48.850429Z","steps":["trace[1472660776] 'read index received' (duration: 151.125114ms)","trace[1472660776] 'applied index is now lower than readState.Index' (duration: 277.296µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:48.850548Z","caller":"traceutil/trace.go:171","msg":"trace[159346223] transaction","detail":"{read_only:false; response_revision:8039; number_of_response:1; }","duration":"202.590601ms","start":"2026-03-18T05:49:48.64793Z","end":"2026-03-18T05:49:48.85052Z","steps":["trace[159346223] 'process raft request' (duration: 202.265893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.850723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.397402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T05:49:48.850741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.677818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:49:48.850784Z","caller":"traceutil/trace.go:171","msg":"trace[1838409275] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8039; }","duration":"120.514084ms","start":"2026-03-18T05:49:48.730253Z","end":"2026-03-18T05:49:48.850767Z","steps":["trace[1838409275] 'agreement among raft nodes before linearized reading' (duration: 120.374141ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:48.850829Z","caller":"traceutil/trace.go:171","msg":"trace[840501177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8039; }","duration":"151.857061ms","start":"2026-03-18T05:49:48.69895Z","end":"2026-03-18T05:49:48.850807Z","steps":["trace[840501177] 'agreement among raft nodes before linearized reading' (duration: 151.663447ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:46.307683Z","caller":"traceutil/trace.go:171","msg":"trace[1193323293] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"121.693141ms","start":"2026-03-18T05:50:46.185964Z","end":"2026-03-18T05:50:46.307657Z","steps":["trace[1193323293] 'process raft request' (duration: 121.271281ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:53:11.06397Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd37ed2859641e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-18T05:53:11.071348Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-18T05:53:11.07145Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-18T05:53:12.836812Z","caller":"traceutil/trace.go:171","msg":"trace[707344195] linearizableReadLoop","detail":"{readStateIndex:10020; appliedIndex:10018; }","duration":"106.03527ms","start":"2026-03-18T05:53:12.730752Z","end":"2026-03-18T05:53:12.836787Z","steps":["trace[707344195] 'read index received' (duration: 47.591087ms)","trace[707344195] 'applied index is now lower than readState.Index' (duration: 58.442533ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:53:12.836961Z","caller":"traceutil/trace.go:171","msg":"trace[842902685] transaction","detail":"{read_only:false; response_revision:9666; number_of_response:1; }","duration":"132.422124ms","start":"2026-03-18T05:53:12.704443Z","end":"2026-03-18T05:53:12.836865Z","steps":["trace[842902685] 'process raft request' (duration: 130.827906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:53:12.837132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.358818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-18T05:53:12.837232Z","caller":"traceutil/trace.go:171","msg":"trace[456290458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9666; }","duration":"106.499541ms","start":"2026-03-18T05:53:12.730709Z","end":"2026-03-18T05:53:12.837209Z","steps":["trace[456290458] 'agreement among raft nodes before linearized reading' (duration: 106.241135ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:54:16.655197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7787} {"level":"info","ts":"2026-03-18T05:54:16.726852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7787,"took":"69.066043ms","hash":4154781619,"current-db-size-bytes":42680320,"current-db-size":"43 MB","current-db-size-in-use-bytes":26398720,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-18T05:54:16.726927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4154781619,"revision":7787,"compact-revision":5761} {"level":"info","ts":"2026-03-18T05:59:16.661423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10029} {"level":"info","ts":"2026-03-18T05:59:16.738776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10029,"took":"75.284505ms","hash":3118327727,"current-db-size-bytes":42680320,"current-db-size":"43 MB","current-db-size-in-use-bytes":17027072,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-18T05:59:16.738858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3118327727,"revision":10029,"compact-revision":7787}