{"level":"warn","ts":"2026-10-19T01:56:53.273315Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-19T01:56:53.273416Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.25: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.25:2380","--initial-cluster=instance=https://162.253.55.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.25:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-19T01:56:53.273508Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-19T01:56:53.273523Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.25:2380"]} {"level":"info","ts":"2026-10-19T01:56:53.27355Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-19T01:56:53.274496Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"]} {"level":"info","ts":"2026-10-19T01:56:53.274598Z","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.25:2380"],"listen-peer-urls":["https://162.253.55.25:2380"],"advertise-client-urls":["https://162.253.55.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.25:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-19T01:56:53.277182Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.296914ms"} {"level":"info","ts":"2026-10-19T01:56:53.283284Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"afeb1bf8b692f9e8","cluster-id":"6bfcc13baae99738"} {"level":"info","ts":"2026-10-19T01:56:53.283417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=()"} {"level":"info","ts":"2026-10-19T01:56:53.283463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became follower at term 0"} {"level":"info","ts":"2026-10-19T01:56:53.283482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft afeb1bf8b692f9e8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-19T01:56:53.283502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became follower at term 1"} {"level":"info","ts":"2026-10-19T01:56:53.283554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=(12676256331193317864)"} {"level":"warn","ts":"2026-10-19T01:56:53.29054Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-19T01:56:53.292596Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-19T01:56:53.294357Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-19T01:56:53.297108Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"afeb1bf8b692f9e8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-19T01:56:53.297316Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"afeb1bf8b692f9e8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-19T01:56:53.29738Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-19T01:56:53.297616Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-19T01:56:53.297635Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-19T01:56:53.29933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=(12676256331193317864)"} {"level":"info","ts":"2026-10-19T01:56:53.299985Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6bfcc13baae99738","local-member-id":"afeb1bf8b692f9e8","added-peer-id":"afeb1bf8b692f9e8","added-peer-peer-urls":["https://162.253.55.25:2380"]} {"level":"info","ts":"2026-10-19T01:56:53.301256Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-19T01:56:53.301332Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.25:2380"} {"level":"info","ts":"2026-10-19T01:56:53.301374Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.25:2380"} {"level":"info","ts":"2026-10-19T01:56:53.301816Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"afeb1bf8b692f9e8","initial-advertise-peer-urls":["https://162.253.55.25:2380"],"listen-peer-urls":["https://162.253.55.25:2380"],"advertise-client-urls":["https://162.253.55.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-19T01:56:53.301872Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-19T01:56:53.684482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 is starting a new election at term 1"} {"level":"info","ts":"2026-10-19T01:56:53.684579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-19T01:56:53.684616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 received MsgPreVoteResp from afeb1bf8b692f9e8 at term 1"} {"level":"info","ts":"2026-10-19T01:56:53.684645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became candidate at term 2"} {"level":"info","ts":"2026-10-19T01:56:53.68466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 received MsgVoteResp from afeb1bf8b692f9e8 at term 2"} {"level":"info","ts":"2026-10-19T01:56:53.684687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became leader at term 2"} {"level":"info","ts":"2026-10-19T01:56:53.684705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: afeb1bf8b692f9e8 elected leader afeb1bf8b692f9e8 at term 2"} {"level":"info","ts":"2026-10-19T01:56:53.692553Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"afeb1bf8b692f9e8","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.25:2379]}","request-path":"/0/members/afeb1bf8b692f9e8/attributes","cluster-id":"6bfcc13baae99738","publish-timeout":"7s"} {"level":"info","ts":"2026-10-19T01:56:53.692564Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:56:53.692654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-19T01:56:53.692582Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-19T01:56:53.692921Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-19T01:56:53.69297Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-19T01:56:53.696145Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-19T01:56:53.697475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.25:2379"} {"level":"info","ts":"2026-10-19T01:56:53.699738Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6bfcc13baae99738","local-member-id":"afeb1bf8b692f9e8","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:56:53.699864Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:56:53.699915Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:56:55.574823Z","caller":"traceutil/trace.go:171","msg":"trace[1290970639] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"112.662852ms","start":"2026-10-19T01:56:55.462135Z","end":"2026-10-19T01:56:55.574798Z","steps":["trace[1290970639] 'process raft request' (duration: 112.60876ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:56:55.574872Z","caller":"traceutil/trace.go:171","msg":"trace[1210905844] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"115.806675ms","start":"2026-10-19T01:56:55.459043Z","end":"2026-10-19T01:56:55.57485Z","steps":["trace[1210905844] 'process raft request' (duration: 112.655061ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:09.391636Z","caller":"traceutil/trace.go:171","msg":"trace[842222280] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"245.770693ms","start":"2026-10-19T01:57:09.145845Z","end":"2026-10-19T01:57:09.391616Z","steps":["trace[842222280] 'process raft request' (duration: 245.607459ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:20.005187Z","caller":"traceutil/trace.go:171","msg":"trace[1501723882] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"149.168915ms","start":"2026-10-19T01:57:19.855973Z","end":"2026-10-19T01:57:20.005142Z","steps":["trace[1501723882] 'process raft request' (duration: 148.904779ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:20.143734Z","caller":"traceutil/trace.go:171","msg":"trace[915022341] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"132.67085ms","start":"2026-10-19T01:57:20.011021Z","end":"2026-10-19T01:57:20.143692Z","steps":["trace[915022341] 'process raft request' (duration: 76.270792ms)","trace[915022341] 'compare' (duration: 56.000029ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:57:48.258271Z","caller":"traceutil/trace.go:171","msg":"trace[878162336] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"200.558576ms","start":"2026-10-19T01:57:48.057681Z","end":"2026-10-19T01:57:48.258239Z","steps":["trace[878162336] 'process raft request' (duration: 199.414569ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:48.864199Z","caller":"traceutil/trace.go:171","msg":"trace[1079330897] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:686; }","duration":"478.270292ms","start":"2026-10-19T01:57:48.3859Z","end":"2026-10-19T01:57:48.86417Z","steps":["trace[1079330897] 'read index received' (duration: 463.631741ms)","trace[1079330897] 'applied index is now lower than readState.Index' (duration: 14.637151ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:57:48.864325Z","caller":"traceutil/trace.go:171","msg":"trace[1647063677] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"486.229533ms","start":"2026-10-19T01:57:48.378039Z","end":"2026-10-19T01:57:48.864269Z","steps":["trace[1647063677] 'process raft request' (duration: 486.071699ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:48.864359Z","caller":"traceutil/trace.go:171","msg":"trace[1691335756] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"490.248029ms","start":"2026-10-19T01:57:48.374035Z","end":"2026-10-19T01:57:48.864283Z","steps":["trace[1691335756] 'process raft request' (duration: 475.549597ms)","trace[1691335756] 'compare' (duration: 14.336674ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:57:48.864476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.544339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:48.86457Z","caller":"traceutil/trace.go:171","msg":"trace[1746148688] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:661; }","duration":"478.692262ms","start":"2026-10-19T01:57:48.385858Z","end":"2026-10-19T01:57:48.86455Z","steps":["trace[1746148688] 'agreement among raft nodes before linearized reading' (duration: 478.519218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:48.864615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:48.385843Z","time spent":"478.763044ms","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-10-19T01:57:48.865094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.269365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:48.865132Z","caller":"traceutil/trace.go:171","msg":"trace[629869734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"247.357727ms","start":"2026-10-19T01:57:48.617763Z","end":"2026-10-19T01:57:48.865121Z","steps":["trace[629869734] 'agreement among raft nodes before linearized reading' (duration: 247.293976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:48.86524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.925795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-19T01:57:48.865317Z","caller":"traceutil/trace.go:171","msg":"trace[118294069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"375.09281ms","start":"2026-10-19T01:57:48.490205Z","end":"2026-10-19T01:57:48.865298Z","steps":["trace[118294069] 'agreement among raft nodes before linearized reading' (duration: 374.807963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:48.865328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:48.374003Z","time spent":"490.471994ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T01:57:48.865396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:48.490176Z","time spent":"375.209302ms","remote":"127.0.0.1:50602","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-10-19T01:57:48.865255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:48.378016Z","time spent":"486.446018ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:57:49.458794Z","caller":"traceutil/trace.go:171","msg":"trace[57764995] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"174.097612ms","start":"2026-10-19T01:57:49.284667Z","end":"2026-10-19T01:57:49.458764Z","steps":["trace[57764995] 'process raft request' (duration: 173.312053ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:59.954661Z","caller":"traceutil/trace.go:171","msg":"trace[1594299528] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"110.381755ms","start":"2026-10-19T01:57:59.844253Z","end":"2026-10-19T01:57:59.954635Z","steps":["trace[1594299528] 'process raft request' (duration: 76.546535ms)","trace[1594299528] 'compare' (duration: 33.727268ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:58:05.787552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.958229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:58:05.787664Z","caller":"traceutil/trace.go:171","msg":"trace[1600651041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"169.124853ms","start":"2026-10-19T01:58:05.618514Z","end":"2026-10-19T01:58:05.787638Z","steps":["trace[1600651041] 'range keys from in-memory index tree' (duration: 168.856427ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:35.483482Z","caller":"traceutil/trace.go:171","msg":"trace[1835842298] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"329.303132ms","start":"2026-10-19T01:58:35.15415Z","end":"2026-10-19T01:58:35.483454Z","steps":["trace[1835842298] 'read index received' (duration: 329.151468ms)","trace[1835842298] 'applied index is now lower than readState.Index' (duration: 150.514µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:58:35.48363Z","caller":"traceutil/trace.go:171","msg":"trace[1990730637] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"337.749854ms","start":"2026-10-19T01:58:35.145866Z","end":"2026-10-19T01:58:35.483616Z","steps":["trace[1990730637] 'process raft request' (duration: 337.413246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:35.483789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:58:35.145849Z","time spent":"337.833035ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T01:58:35.483867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.19806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-19T01:58:35.483951Z","caller":"traceutil/trace.go:171","msg":"trace[1888149113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1229; }","duration":"196.325573ms","start":"2026-10-19T01:58:35.287594Z","end":"2026-10-19T01:58:35.48392Z","steps":["trace[1888149113] 'agreement among raft nodes before linearized reading' (duration: 196.106248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:35.484111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.943047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-19T01:58:35.484211Z","caller":"traceutil/trace.go:171","msg":"trace[1235532053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"330.09516ms","start":"2026-10-19T01:58:35.154089Z","end":"2026-10-19T01:58:35.484184Z","steps":["trace[1235532053] 'agreement among raft nodes before linearized reading' (duration: 329.803583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:35.484284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:58:35.154067Z","time spent":"330.183953ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-19T01:59:15.188718Z","caller":"traceutil/trace.go:171","msg":"trace[39296412] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"133.869689ms","start":"2026-10-19T01:59:15.054817Z","end":"2026-10-19T01:59:15.188686Z","steps":["trace[39296412] 'process raft request' (duration: 131.40197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:26.88166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.888958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:59:26.881764Z","caller":"traceutil/trace.go:171","msg":"trace[317718390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1926; }","duration":"264.054262ms","start":"2026-10-19T01:59:26.617691Z","end":"2026-10-19T01:59:26.881745Z","steps":["trace[317718390] 'range keys from in-memory index tree' (duration: 263.742864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:26.882057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.626733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-19T01:59:26.882258Z","caller":"traceutil/trace.go:171","msg":"trace[1492967190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1926; }","duration":"343.872799ms","start":"2026-10-19T01:59:26.538348Z","end":"2026-10-19T01:59:26.882221Z","steps":["trace[1492967190] 'range keys from in-memory index tree' (duration: 343.433559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:26.882323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:26.538322Z","time spent":"343.984872ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-19T01:59:27.072537Z","caller":"traceutil/trace.go:171","msg":"trace[1483914610] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"181.628582ms","start":"2026-10-19T01:59:26.890857Z","end":"2026-10-19T01:59:27.072486Z","steps":["trace[1483914610] 'process raft request' (duration: 91.558809ms)","trace[1483914610] 'compare' (duration: 89.838628ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:27.400118Z","caller":"traceutil/trace.go:171","msg":"trace[1488340092] linearizableReadLoop","detail":"{readStateIndex:2003; appliedIndex:2002; }","duration":"182.980794ms","start":"2026-10-19T01:59:27.217113Z","end":"2026-10-19T01:59:27.400094Z","steps":["trace[1488340092] 'read index received' (duration: 134.145567ms)","trace[1488340092] 'applied index is now lower than readState.Index' (duration: 48.833797ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:27.400263Z","caller":"traceutil/trace.go:171","msg":"trace[402269919] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"304.010738ms","start":"2026-10-19T01:59:27.096197Z","end":"2026-10-19T01:59:27.400208Z","steps":["trace[402269919] 'process raft request' (duration: 255.185451ms)","trace[402269919] 'compare' (duration: 48.590121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:59:27.400452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:27.096161Z","time spent":"304.185301ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T01:59:27.400745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.593109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-19T01:59:27.400891Z","caller":"traceutil/trace.go:171","msg":"trace[1682805285] range","detail":"{range_begin:/registry/masterleases/162.253.55.25; range_end:; response_count:1; response_revision:1929; }","duration":"183.822875ms","start":"2026-10-19T01:59:27.21704Z","end":"2026-10-19T01:59:27.400863Z","steps":["trace[1682805285] 'agreement among raft nodes before linearized reading' (duration: 183.160459ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:40.386281Z","caller":"traceutil/trace.go:171","msg":"trace[1477440056] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"120.254549ms","start":"2026-10-19T01:59:40.265998Z","end":"2026-10-19T01:59:40.386252Z","steps":["trace[1477440056] 'process raft request' (duration: 118.053566ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:40.399068Z","caller":"traceutil/trace.go:171","msg":"trace[721002189] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"114.136673ms","start":"2026-10-19T01:59:40.284904Z","end":"2026-10-19T01:59:40.399041Z","steps":["trace[721002189] 'process raft request' (duration: 114.003089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:40.720524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.697222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-19T01:59:40.72061Z","caller":"traceutil/trace.go:171","msg":"trace[1384381712] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2048; }","duration":"196.794295ms","start":"2026-10-19T01:59:40.523794Z","end":"2026-10-19T01:59:40.720588Z","steps":["trace[1384381712] 'range keys from in-memory index tree' (duration: 196.541189ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:53.227887Z","caller":"traceutil/trace.go:171","msg":"trace[84526821] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"124.042049ms","start":"2026-10-19T01:59:53.103806Z","end":"2026-10-19T01:59:53.227848Z","steps":["trace[84526821] 'read index received' (duration: 79.64484ms)","trace[84526821] 'applied index is now lower than readState.Index' (duration: 44.395519ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:53.228019Z","caller":"traceutil/trace.go:171","msg":"trace[710666857] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"141.796893ms","start":"2026-10-19T01:59:53.086184Z","end":"2026-10-19T01:59:53.227981Z","steps":["trace[710666857] 'process raft request' (duration: 97.238139ms)","trace[710666857] 'compare' (duration: 44.115533ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:59:53.228226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.395147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-19T01:59:53.228291Z","caller":"traceutil/trace.go:171","msg":"trace[165760259] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2123; }","duration":"124.48831ms","start":"2026-10-19T01:59:53.103784Z","end":"2026-10-19T01:59:53.228272Z","steps":["trace[165760259] 'agreement among raft nodes before linearized reading' (duration: 124.225743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:54.674817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.854449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T01:59:54.675065Z","caller":"traceutil/trace.go:171","msg":"trace[881249728] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"218.711466ms","start":"2026-10-19T01:59:54.456337Z","end":"2026-10-19T01:59:54.675048Z","steps":["trace[881249728] 'process raft request' (duration: 218.637565ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:54.675091Z","caller":"traceutil/trace.go:171","msg":"trace[148818348] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"226.013571ms","start":"2026-10-19T01:59:54.449053Z","end":"2026-10-19T01:59:54.675067Z","steps":["trace[148818348] 'process raft request' (duration: 61.702151ms)","trace[148818348] 'compare' (duration: 163.735866ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:54.675168Z","caller":"traceutil/trace.go:171","msg":"trace[1001695351] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"221.198106ms","start":"2026-10-19T01:59:54.453881Z","end":"2026-10-19T01:59:54.675079Z","steps":["trace[1001695351] 'process raft request' (duration: 221.045513ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:54.675324Z","caller":"traceutil/trace.go:171","msg":"trace[1911587632] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"183.510427ms","start":"2026-10-19T01:59:54.491761Z","end":"2026-10-19T01:59:54.675271Z","steps":["trace[1911587632] 'process raft request' (duration: 183.257181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:00:22.903647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.991764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-19T02:00:22.903949Z","caller":"traceutil/trace.go:171","msg":"trace[1516888060] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"106.903871ms","start":"2026-10-19T02:00:22.797026Z","end":"2026-10-19T02:00:22.90393Z","steps":["trace[1516888060] 'process raft request' (duration: 106.758927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:00:22.904248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.700216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:00:22.904342Z","caller":"traceutil/trace.go:171","msg":"trace[585489127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2279; }","duration":"285.84945ms","start":"2026-10-19T02:00:22.618468Z","end":"2026-10-19T02:00:22.904317Z","steps":["trace[585489127] 'agreement among raft nodes before linearized reading' (duration: 285.638215ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:00:22.903887Z","caller":"traceutil/trace.go:171","msg":"trace[149557751] linearizableReadLoop","detail":"{readStateIndex:2365; appliedIndex:2364; }","duration":"285.333437ms","start":"2026-10-19T02:00:22.618525Z","end":"2026-10-19T02:00:22.903859Z","steps":["trace[149557751] 'read index received' (duration: 131.008845ms)","trace[149557751] 'applied index is now lower than readState.Index' (duration: 154.321322ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:00:23.290163Z","caller":"traceutil/trace.go:171","msg":"trace[640705411] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"127.128922ms","start":"2026-10-19T02:00:23.163007Z","end":"2026-10-19T02:00:23.290136Z","steps":["trace[640705411] 'process raft request' (duration: 126.907877ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:01:10.220542Z","caller":"traceutil/trace.go:171","msg":"trace[988035959] linearizableReadLoop","detail":"{readStateIndex:2809; appliedIndex:2808; }","duration":"281.723086ms","start":"2026-10-19T02:01:09.938799Z","end":"2026-10-19T02:01:10.220522Z","steps":["trace[988035959] 'read index received' (duration: 274.103005ms)","trace[988035959] 'applied index is now lower than readState.Index' (duration: 7.619361ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:01:10.220615Z","caller":"traceutil/trace.go:171","msg":"trace[2078152003] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"372.790447ms","start":"2026-10-19T02:01:09.847818Z","end":"2026-10-19T02:01:10.220608Z","steps":["trace[2078152003] 'process raft request' (duration: 365.072584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:01:10.220675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:01:09.8478Z","time spent":"372.827618ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:01:10.220904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.052164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-19T02:01:10.221061Z","caller":"traceutil/trace.go:171","msg":"trace[1961121783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2706; }","duration":"282.259738ms","start":"2026-10-19T02:01:09.938769Z","end":"2026-10-19T02:01:10.221029Z","steps":["trace[1961121783] 'agreement among raft nodes before linearized reading' (duration: 281.849329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:01:10.221881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.447522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-19T02:01:10.221912Z","caller":"traceutil/trace.go:171","msg":"trace[756767380] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2707; }","duration":"172.525424ms","start":"2026-10-19T02:01:10.04938Z","end":"2026-10-19T02:01:10.221905Z","steps":["trace[756767380] 'agreement among raft nodes before linearized reading' (duration: 172.411131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:01:10.222049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.62272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-19T02:01:10.222062Z","caller":"traceutil/trace.go:171","msg":"trace[482355047] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2707; }","duration":"106.676332ms","start":"2026-10-19T02:01:10.115381Z","end":"2026-10-19T02:01:10.222058Z","steps":["trace[482355047] 'agreement among raft nodes before linearized reading' (duration: 106.629331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:01:43.091385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.120869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-19T02:01:43.091571Z","caller":"traceutil/trace.go:171","msg":"trace[564260844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2905; }","duration":"215.375265ms","start":"2026-10-19T02:01:42.876177Z","end":"2026-10-19T02:01:43.091553Z","steps":["trace[564260844] 'range keys from in-memory index tree' (duration: 214.960115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:01:43.091399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.273492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-19T02:01:43.091777Z","caller":"traceutil/trace.go:171","msg":"trace[1431512357] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2905; }","duration":"220.733593ms","start":"2026-10-19T02:01:42.871012Z","end":"2026-10-19T02:01:43.091746Z","steps":["trace[1431512357] 'range keys from in-memory index tree' (duration: 220.053007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:01:43.333211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.999188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-19T02:01:43.333311Z","caller":"traceutil/trace.go:171","msg":"trace[1221242331] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2907; }","duration":"129.171302ms","start":"2026-10-19T02:01:43.20412Z","end":"2026-10-19T02:01:43.333291Z","steps":["trace[1221242331] 'count revisions from in-memory index tree' (duration: 128.834593ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:01:43.522755Z","caller":"traceutil/trace.go:171","msg":"trace[110151126] linearizableReadLoop","detail":"{readStateIndex:3020; appliedIndex:3018; }","duration":"143.098855ms","start":"2026-10-19T02:01:43.379627Z","end":"2026-10-19T02:01:43.522726Z","steps":["trace[110151126] 'read index received' (duration: 108.521517ms)","trace[110151126] 'applied index is now lower than readState.Index' (duration: 34.575948ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:01:43.522902Z","caller":"traceutil/trace.go:171","msg":"trace[817288195] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"230.289822ms","start":"2026-10-19T02:01:43.292564Z","end":"2026-10-19T02:01:43.522854Z","steps":["trace[817288195] 'process raft request' (duration: 195.685063ms)","trace[817288195] 'compare' (duration: 34.161118ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:01:43.523074Z","caller":"traceutil/trace.go:171","msg":"trace[1190876217] transaction","detail":"{read_only:false; response_revision:2910; number_of_response:1; }","duration":"216.374228ms","start":"2026-10-19T02:01:43.306678Z","end":"2026-10-19T02:01:43.523052Z","steps":["trace[1190876217] 'process raft request' (duration: 215.947868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:01:43.523501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.256109ms","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-10-19T02:01:43.52357Z","caller":"traceutil/trace.go:171","msg":"trace[941663077] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2910; }","duration":"143.975406ms","start":"2026-10-19T02:01:43.379576Z","end":"2026-10-19T02:01:43.523551Z","steps":["trace[941663077] 'agreement among raft nodes before linearized reading' (duration: 143.260079ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:04:55.906238Z","caller":"traceutil/trace.go:171","msg":"trace[1036873194] linearizableReadLoop","detail":"{readStateIndex:4272; appliedIndex:4271; }","duration":"108.685936ms","start":"2026-10-19T02:04:55.797528Z","end":"2026-10-19T02:04:55.906214Z","steps":["trace[1036873194] 'read index received' (duration: 108.487782ms)","trace[1036873194] 'applied index is now lower than readState.Index' (duration: 196.624µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:04:55.906294Z","caller":"traceutil/trace.go:171","msg":"trace[210565655] transaction","detail":"{read_only:false; response_revision:4118; number_of_response:1; }","duration":"111.228258ms","start":"2026-10-19T02:04:55.795034Z","end":"2026-10-19T02:04:55.906262Z","steps":["trace[210565655] 'process raft request' (duration: 110.963981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:04:55.906724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.185758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-19T02:04:55.907102Z","caller":"traceutil/trace.go:171","msg":"trace[451720734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4118; }","duration":"109.604938ms","start":"2026-10-19T02:04:55.797474Z","end":"2026-10-19T02:04:55.907079Z","steps":["trace[451720734] 'agreement among raft nodes before linearized reading' (duration: 109.052475ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:05:01.278487Z","caller":"traceutil/trace.go:171","msg":"trace[1542522018] transaction","detail":"{read_only:false; response_revision:4150; number_of_response:1; }","duration":"116.015524ms","start":"2026-10-19T02:05:01.16245Z","end":"2026-10-19T02:05:01.278465Z","steps":["trace[1542522018] 'process raft request' (duration: 115.87893ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:28.18584Z","caller":"traceutil/trace.go:171","msg":"trace[590765306] linearizableReadLoop","detail":"{readStateIndex:5296; appliedIndex:5295; }","duration":"101.097833ms","start":"2026-10-19T02:06:28.084714Z","end":"2026-10-19T02:06:28.185812Z","steps":["trace[590765306] 'read index received' (duration: 71.369198ms)","trace[590765306] 'applied index is now lower than readState.Index' (duration: 29.727265ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:06:28.186101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.367171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-10-19T02:06:28.186148Z","caller":"traceutil/trace.go:171","msg":"trace[285760209] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5102; }","duration":"101.468613ms","start":"2026-10-19T02:06:28.084665Z","end":"2026-10-19T02:06:28.186133Z","steps":["trace[285760209] 'agreement among raft nodes before linearized reading' (duration: 101.254668ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:28.186065Z","caller":"traceutil/trace.go:171","msg":"trace[1920957223] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"111.365911ms","start":"2026-10-19T02:06:28.07451Z","end":"2026-10-19T02:06:28.185875Z","steps":["trace[1920957223] 'process raft request' (duration: 81.723557ms)","trace[1920957223] 'compare' (duration: 29.350297ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:06:29.28472Z","caller":"traceutil/trace.go:171","msg":"trace[1749669906] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"140.978694ms","start":"2026-10-19T02:06:29.143705Z","end":"2026-10-19T02:06:29.284684Z","steps":["trace[1749669906] 'process raft request' (duration: 140.688177ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:35.420953Z","caller":"traceutil/trace.go:171","msg":"trace[569947736] linearizableReadLoop","detail":"{readStateIndex:5471; appliedIndex:5470; }","duration":"105.034078ms","start":"2026-10-19T02:06:35.315894Z","end":"2026-10-19T02:06:35.420928Z","steps":["trace[569947736] 'read index received' (duration: 104.645678ms)","trace[569947736] 'applied index is now lower than readState.Index' (duration: 387.03µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:06:35.421077Z","caller":"traceutil/trace.go:171","msg":"trace[1857407350] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"142.340926ms","start":"2026-10-19T02:06:35.278679Z","end":"2026-10-19T02:06:35.42102Z","steps":["trace[1857407350] 'process raft request' (duration: 142.047269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:35.421288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.375236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tfstx\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-19T02:06:35.42133Z","caller":"traceutil/trace.go:171","msg":"trace[332781169] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tfstx; range_end:; response_count:1; response_revision:5270; }","duration":"105.461428ms","start":"2026-10-19T02:06:35.315858Z","end":"2026-10-19T02:06:35.42132Z","steps":["trace[332781169] 'agreement among raft nodes before linearized reading' (duration: 105.237343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:35.567347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.768136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-10-19T02:06:35.567613Z","caller":"traceutil/trace.go:171","msg":"trace[2068523392] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5275; }","duration":"106.074814ms","start":"2026-10-19T02:06:35.461512Z","end":"2026-10-19T02:06:35.567587Z","steps":["trace[2068523392] 'agreement among raft nodes before linearized reading' (duration: 105.618653ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:36.38455Z","caller":"traceutil/trace.go:171","msg":"trace[1106952642] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"196.06234ms","start":"2026-10-19T02:06:36.188461Z","end":"2026-10-19T02:06:36.384524Z","steps":["trace[1106952642] 'process raft request' (duration: 104.896235ms)","trace[1106952642] 'compare' (duration: 91.002242ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:06:38.385564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.907344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:06:38.385815Z","caller":"traceutil/trace.go:171","msg":"trace[1114685826] linearizableReadLoop","detail":"{readStateIndex:5601; appliedIndex:5598; }","duration":"252.472908ms","start":"2026-10-19T02:06:38.133324Z","end":"2026-10-19T02:06:38.385797Z","steps":["trace[1114685826] 'read index received' (duration: 128.375251ms)","trace[1114685826] 'applied index is now lower than readState.Index' (duration: 124.096807ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:06:38.385805Z","caller":"traceutil/trace.go:171","msg":"trace[401782094] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"289.962412ms","start":"2026-10-19T02:06:38.095805Z","end":"2026-10-19T02:06:38.385768Z","steps":["trace[401782094] 'process raft request' (duration: 20.783101ms)","trace[401782094] 'compare' (duration: 268.696739ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:06:38.385891Z","caller":"traceutil/trace.go:171","msg":"trace[2078087862] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"289.979271ms","start":"2026-10-19T02:06:38.095902Z","end":"2026-10-19T02:06:38.385881Z","steps":["trace[2078087862] 'process raft request' (duration: 289.851368ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:38.38602Z","caller":"traceutil/trace.go:171","msg":"trace[190143476] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"290.122225ms","start":"2026-10-19T02:06:38.095871Z","end":"2026-10-19T02:06:38.385993Z","steps":["trace[190143476] 'process raft request' (duration: 289.794047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:38.386204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.869798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b611a280-123f-4b4c-a295-0ea3a55f1caf\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-19T02:06:38.386239Z","caller":"traceutil/trace.go:171","msg":"trace[462314152] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b611a280-123f-4b4c-a295-0ea3a55f1caf; range_end:; response_count:1; response_revision:5397; }","duration":"252.93574ms","start":"2026-10-19T02:06:38.133292Z","end":"2026-10-19T02:06:38.386228Z","steps":["trace[462314152] 'agreement among raft nodes before linearized reading' (duration: 252.781616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:38.386237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.071056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:06:38.386295Z","caller":"traceutil/trace.go:171","msg":"trace[1717534896] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:5397; }","duration":"180.188428ms","start":"2026-10-19T02:06:38.206091Z","end":"2026-10-19T02:06:38.386279Z","steps":["trace[1717534896] 'agreement among raft nodes before linearized reading' (duration: 180.075375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:38.386329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.51282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b611a280-123f-4b4c-a295-0ea3a55f1caf\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-19T02:06:38.386421Z","caller":"traceutil/trace.go:171","msg":"trace[1626808279] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b611a280-123f-4b4c-a295-0ea3a55f1caf; range_end:; response_count:1; response_revision:5397; }","duration":"208.640504ms","start":"2026-10-19T02:06:38.177753Z","end":"2026-10-19T02:06:38.386393Z","steps":["trace[1626808279] 'agreement among raft nodes before linearized reading' (duration: 208.414808ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:38.608146Z","caller":"traceutil/trace.go:171","msg":"trace[508489064] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"214.838843ms","start":"2026-10-19T02:06:38.393276Z","end":"2026-10-19T02:06:38.608115Z","steps":["trace[508489064] 'process raft request' (duration: 214.669999ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:38.616977Z","caller":"traceutil/trace.go:171","msg":"trace[634324761] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"221.209106ms","start":"2026-10-19T02:06:38.395744Z","end":"2026-10-19T02:06:38.616953Z","steps":["trace[634324761] 'process raft request' (duration: 220.989231ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:38.617345Z","caller":"traceutil/trace.go:171","msg":"trace[498317481] linearizableReadLoop","detail":"{readStateIndex:5604; appliedIndex:5601; }","duration":"167.951733ms","start":"2026-10-19T02:06:38.449344Z","end":"2026-10-19T02:06:38.617296Z","steps":["trace[498317481] 'read index received' (duration: 158.69312ms)","trace[498317481] 'applied index is now lower than readState.Index' (duration: 9.257063ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:06:38.61764Z","caller":"traceutil/trace.go:171","msg":"trace[1262705807] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"204.246588ms","start":"2026-10-19T02:06:38.413377Z","end":"2026-10-19T02:06:38.617623Z","steps":["trace[1262705807] 'process raft request' (duration: 203.50506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:38.617714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.345594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-19T02:06:38.61777Z","caller":"traceutil/trace.go:171","msg":"trace[711372884] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5401; }","duration":"168.419635ms","start":"2026-10-19T02:06:38.449336Z","end":"2026-10-19T02:06:38.617756Z","steps":["trace[711372884] 'agreement among raft nodes before linearized reading' (duration: 168.305913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:40.551628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.356271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b611a280-123f-4b4c-a295-0ea3a55f1caf\" ","response":"range_response_count:1 size:3903"} {"level":"info","ts":"2026-10-19T02:06:40.551718Z","caller":"traceutil/trace.go:171","msg":"trace[257526916] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b611a280-123f-4b4c-a295-0ea3a55f1caf; range_end:; response_count:1; response_revision:5421; }","duration":"155.500654ms","start":"2026-10-19T02:06:40.396196Z","end":"2026-10-19T02:06:40.551697Z","steps":["trace[257526916] 'range keys from in-memory index tree' (duration: 155.144255ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:42.848365Z","caller":"traceutil/trace.go:171","msg":"trace[685298567] linearizableReadLoop","detail":"{readStateIndex:5688; appliedIndex:5686; }","duration":"203.998292ms","start":"2026-10-19T02:06:42.644349Z","end":"2026-10-19T02:06:42.848347Z","steps":["trace[685298567] 'read index received' (duration: 203.91044ms)","trace[685298567] 'applied index is now lower than readState.Index' (duration: 87.212µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:06:42.848451Z","caller":"traceutil/trace.go:171","msg":"trace[1333480903] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"206.732358ms","start":"2026-10-19T02:06:42.641711Z","end":"2026-10-19T02:06:42.848444Z","steps":["trace[1333480903] 'process raft request' (duration: 206.585454ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:42.848552Z","caller":"traceutil/trace.go:171","msg":"trace[2072024894] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"206.807689ms","start":"2026-10-19T02:06:42.641701Z","end":"2026-10-19T02:06:42.848509Z","steps":["trace[2072024894] 'process raft request' (duration: 206.470771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:42.848659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.283509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-19T02:06:42.848741Z","caller":"traceutil/trace.go:171","msg":"trace[731725747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5481; }","duration":"204.431852ms","start":"2026-10-19T02:06:42.644292Z","end":"2026-10-19T02:06:42.848724Z","steps":["trace[731725747] 'agreement among raft nodes before linearized reading' (duration: 204.185096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:42.86168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.317398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-19T02:06:42.861759Z","caller":"traceutil/trace.go:171","msg":"trace[1943279977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5483; }","duration":"211.424741ms","start":"2026-10-19T02:06:42.650318Z","end":"2026-10-19T02:06:42.861742Z","steps":["trace[1943279977] 'agreement among raft nodes before linearized reading' (duration: 211.192995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:42.861704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.73415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-19T02:06:42.86188Z","caller":"traceutil/trace.go:171","msg":"trace[1422880842] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5483; }","duration":"106.940765ms","start":"2026-10-19T02:06:42.754925Z","end":"2026-10-19T02:06:42.861866Z","steps":["trace[1422880842] 'agreement among raft nodes before linearized reading' (duration: 106.681088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:06:52.727797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.552554ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-19T02:06:52.727952Z","caller":"traceutil/trace.go:171","msg":"trace[261607143] linearizableReadLoop","detail":"{readStateIndex:5862; appliedIndex:5861; }","duration":"110.249865ms","start":"2026-10-19T02:06:52.617672Z","end":"2026-10-19T02:06:52.727922Z","steps":["trace[261607143] 'read index received' (duration: 28.1µs)","trace[261607143] 'applied index is now lower than readState.Index' (duration: 110.219855ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:06:52.728072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.379017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:06:52.728122Z","caller":"traceutil/trace.go:171","msg":"trace[2119667390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5650; }","duration":"110.48118ms","start":"2026-10-19T02:06:52.617622Z","end":"2026-10-19T02:06:52.728103Z","steps":["trace[2119667390] 'agreement among raft nodes before linearized reading' (duration: 110.372087ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:53.219744Z","caller":"traceutil/trace.go:171","msg":"trace[1280515019] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"254.889707ms","start":"2026-10-19T02:06:52.964801Z","end":"2026-10-19T02:06:53.219691Z","steps":["trace[1280515019] 'process raft request' (duration: 160.30649ms)","trace[1280515019] 'compare' (duration: 94.190197ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:06:53.805189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-10-19T02:06:53.906806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"99.459345ms","hash":3046567740,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":30986240,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-19T02:06:53.906905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3046567740,"revision":2965,"compact-revision":-1} {"level":"info","ts":"2026-10-19T02:06:59.009478Z","caller":"traceutil/trace.go:171","msg":"trace[570865724] transaction","detail":"{read_only:false; response_revision:5722; number_of_response:1; }","duration":"102.469877ms","start":"2026-10-19T02:06:58.906983Z","end":"2026-10-19T02:06:59.009453Z","steps":["trace[570865724] 'process raft request' (duration: 102.301243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:07:01.201901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.684467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:07:01.201995Z","caller":"traceutil/trace.go:171","msg":"trace[1224970826] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5798; }","duration":"108.830131ms","start":"2026-10-19T02:07:01.093144Z","end":"2026-10-19T02:07:01.201975Z","steps":["trace[1224970826] 'count revisions from in-memory index tree' (duration: 108.543524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:07:01.489896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.030064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:07:01.49016Z","caller":"traceutil/trace.go:171","msg":"trace[1363953755] transaction","detail":"{read_only:false; response_revision:5800; number_of_response:1; }","duration":"229.124596ms","start":"2026-10-19T02:07:01.261002Z","end":"2026-10-19T02:07:01.490127Z","steps":["trace[1363953755] 'process raft request' (duration: 124.758204ms)","trace[1363953755] 'compare' (duration: 103.86733ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:07:01.93709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.428173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:07:01.937296Z","caller":"traceutil/trace.go:171","msg":"trace[1688740268] linearizableReadLoop","detail":"{readStateIndex:6015; appliedIndex:6014; }","duration":"384.952809ms","start":"2026-10-19T02:07:01.552322Z","end":"2026-10-19T02:07:01.937275Z","steps":["trace[1688740268] 'read index received' (duration: 40.191628ms)","trace[1688740268] 'applied index is now lower than readState.Index' (duration: 344.759291ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:07:01.937319Z","caller":"traceutil/trace.go:171","msg":"trace[587192792] transaction","detail":"{read_only:false; response_revision:5801; number_of_response:1; }","duration":"439.788519ms","start":"2026-10-19T02:07:01.497492Z","end":"2026-10-19T02:07:01.937281Z","steps":["trace[587192792] 'process raft request' (duration: 95.072749ms)","trace[587192792] 'compare' (duration: 344.30076ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:07:01.937519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:07:01.497466Z","time spent":"439.937893ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:07:01.937593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.261626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tfstx\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-19T02:07:01.937661Z","caller":"traceutil/trace.go:171","msg":"trace[1911135125] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tfstx; range_end:; response_count:1; response_revision:5801; }","duration":"385.370699ms","start":"2026-10-19T02:07:01.552275Z","end":"2026-10-19T02:07:01.937646Z","steps":["trace[1911135125] 'agreement among raft nodes before linearized reading' (duration: 385.131423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:07:01.937697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:07:01.55226Z","time spent":"385.42888ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-tfstx\" "} {"level":"warn","ts":"2026-10-19T02:07:01.961046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.319966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:07:01.961094Z","caller":"traceutil/trace.go:171","msg":"trace[1749779383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5802; }","duration":"343.373937ms","start":"2026-10-19T02:07:01.617706Z","end":"2026-10-19T02:07:01.96108Z","steps":["trace[1749779383] 'agreement among raft nodes before linearized reading' (duration: 343.248054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:07:01.961116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:07:01.617639Z","time spent":"343.4719ms","remote":"127.0.0.1:50334","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-10-19T02:07:01.961236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.740732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-19T02:07:01.961323Z","caller":"traceutil/trace.go:171","msg":"trace[1933417838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5802; }","duration":"136.904126ms","start":"2026-10-19T02:07:01.824399Z","end":"2026-10-19T02:07:01.961304Z","steps":["trace[1933417838] 'agreement among raft nodes before linearized reading' (duration: 136.710272ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:53.811986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5667} {"level":"info","ts":"2026-10-19T02:11:53.921205Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5667,"took":"107.447521ms","hash":1413877312,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":28667904,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-19T02:11:53.921531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1413877312,"revision":5667,"compact-revision":2965} {"level":"info","ts":"2026-10-19T02:13:16.799741Z","caller":"traceutil/trace.go:171","msg":"trace[807841389] transaction","detail":"{read_only:false; response_revision:8708; number_of_response:1; }","duration":"204.76989ms","start":"2026-10-19T02:13:16.594946Z","end":"2026-10-19T02:13:16.799716Z","steps":["trace[807841389] 'process raft request' (duration: 204.629667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:16.992969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.857144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:13:16.993219Z","caller":"traceutil/trace.go:171","msg":"trace[244110594] linearizableReadLoop","detail":"{readStateIndex:9016; appliedIndex:9014; }","duration":"377.127161ms","start":"2026-10-19T02:13:16.616067Z","end":"2026-10-19T02:13:16.993194Z","steps":["trace[244110594] 'read index received' (duration: 183.617442ms)","trace[244110594] 'applied index is now lower than readState.Index' (duration: 193.507869ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:13:16.993354Z","caller":"traceutil/trace.go:171","msg":"trace[1385273116] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"288.968533ms","start":"2026-10-19T02:13:16.704372Z","end":"2026-10-19T02:13:16.99334Z","steps":["trace[1385273116] 'process raft request' (duration: 288.744118ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:13:16.993344Z","caller":"traceutil/trace.go:171","msg":"trace[916339178] transaction","detail":"{read_only:false; response_revision:8709; number_of_response:1; }","duration":"391.106438ms","start":"2026-10-19T02:13:16.602178Z","end":"2026-10-19T02:13:16.993285Z","steps":["trace[916339178] 'process raft request' (duration: 239.781382ms)","trace[916339178] 'compare' (duration: 150.641609ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:13:16.99371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.844411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:13:16.993799Z","caller":"traceutil/trace.go:171","msg":"trace[1923342999] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8710; }","duration":"376.20039ms","start":"2026-10-19T02:13:16.617579Z","end":"2026-10-19T02:13:16.993779Z","steps":["trace[1923342999] 'agreement among raft nodes before linearized reading' (duration: 375.824671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:16.993811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:13:16.60215Z","time spent":"391.301723ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:13:16.993843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:13:16.617561Z","time spent":"376.269171ms","remote":"127.0.0.1:43646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-10-19T02:13:16.993921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.816249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T02:13:16.994017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.228665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-19T02:13:16.994078Z","caller":"traceutil/trace.go:171","msg":"trace[1348942216] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8710; }","duration":"258.315437ms","start":"2026-10-19T02:13:16.735743Z","end":"2026-10-19T02:13:16.994059Z","steps":["trace[1348942216] 'agreement among raft nodes before linearized reading' (duration: 258.100342ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:13:16.994084Z","caller":"traceutil/trace.go:171","msg":"trace[357660494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8710; }","duration":"378.027353ms","start":"2026-10-19T02:13:16.616022Z","end":"2026-10-19T02:13:16.99405Z","steps":["trace[357660494] 'agreement among raft nodes before linearized reading' (duration: 377.764717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:16.994151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:13:16.616009Z","time spent":"378.127996ms","remote":"127.0.0.1:50334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-19T02:13:28.321874Z","caller":"traceutil/trace.go:171","msg":"trace[260956151] linearizableReadLoop","detail":"{readStateIndex:9077; appliedIndex:9076; }","duration":"210.074658ms","start":"2026-10-19T02:13:28.111769Z","end":"2026-10-19T02:13:28.321844Z","steps":["trace[260956151] 'read index received' (duration: 209.789631ms)","trace[260956151] 'applied index is now lower than readState.Index' (duration: 283.737µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:13:28.321919Z","caller":"traceutil/trace.go:171","msg":"trace[1582928184] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"247.311512ms","start":"2026-10-19T02:13:28.074585Z","end":"2026-10-19T02:13:28.321896Z","steps":["trace[1582928184] 'process raft request' (duration: 247.083317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:28.322178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.397685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-19T02:13:28.322175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.468193ms","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-10-19T02:13:28.322236Z","caller":"traceutil/trace.go:171","msg":"trace[1301281569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8767; }","duration":"210.500648ms","start":"2026-10-19T02:13:28.11172Z","end":"2026-10-19T02:13:28.322221Z","steps":["trace[1301281569] 'agreement among raft nodes before linearized reading' (duration: 210.259202ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:13:28.322274Z","caller":"traceutil/trace.go:171","msg":"trace[6767195] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8767; }","duration":"132.623617ms","start":"2026-10-19T02:13:28.189626Z","end":"2026-10-19T02:13:28.322249Z","steps":["trace[6767195] 'agreement among raft nodes before linearized reading' (duration: 132.459053ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:13:29.037651Z","caller":"traceutil/trace.go:171","msg":"trace[1819802495] linearizableReadLoop","detail":"{readStateIndex:9080; appliedIndex:9079; }","duration":"132.327ms","start":"2026-10-19T02:13:28.9053Z","end":"2026-10-19T02:13:29.037627Z","steps":["trace[1819802495] 'read index received' (duration: 132.111305ms)","trace[1819802495] 'applied index is now lower than readState.Index' (duration: 214.315µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:13:29.037695Z","caller":"traceutil/trace.go:171","msg":"trace[1579087190] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"188.495729ms","start":"2026-10-19T02:13:28.849185Z","end":"2026-10-19T02:13:29.037681Z","steps":["trace[1579087190] 'process raft request' (duration: 188.225223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:29.037766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.461453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:13:29.037789Z","caller":"traceutil/trace.go:171","msg":"trace[1308721103] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8770; }","duration":"132.530725ms","start":"2026-10-19T02:13:28.90525Z","end":"2026-10-19T02:13:29.037781Z","steps":["trace[1308721103] 'agreement among raft nodes before linearized reading' (duration: 132.475453ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:13:55.499724Z","caller":"traceutil/trace.go:171","msg":"trace[1805609116] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"111.303193ms","start":"2026-10-19T02:13:55.388388Z","end":"2026-10-19T02:13:55.499691Z","steps":["trace[1805609116] 'process raft request' (duration: 111.119109ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:13:55.500995Z","caller":"traceutil/trace.go:171","msg":"trace[974198480] transaction","detail":"{read_only:false; response_revision:8966; number_of_response:1; }","duration":"111.918538ms","start":"2026-10-19T02:13:55.389062Z","end":"2026-10-19T02:13:55.500981Z","steps":["trace[974198480] 'process raft request' (duration: 111.716513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:59.024409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18007820483067639686,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-19T02:13:59.084409Z","caller":"traceutil/trace.go:171","msg":"trace[1173309417] linearizableReadLoop","detail":"{readStateIndex:9302; appliedIndex:9301; }","duration":"560.728128ms","start":"2026-10-19T02:13:58.523663Z","end":"2026-10-19T02:13:59.084391Z","steps":["trace[1173309417] 'read index received' (duration: 560.592185ms)","trace[1173309417] 'applied index is now lower than readState.Index' (duration: 135.113µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:13:59.084479Z","caller":"traceutil/trace.go:171","msg":"trace[1709734473] transaction","detail":"{read_only:false; response_revision:8983; number_of_response:1; }","duration":"581.398024ms","start":"2026-10-19T02:13:58.503075Z","end":"2026-10-19T02:13:59.084473Z","steps":["trace[1709734473] 'process raft request' (duration: 581.23265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:59.084536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:13:58.503054Z","time spent":"581.437305ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:13:59.084598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.32891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tfstx\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-19T02:13:59.084634Z","caller":"traceutil/trace.go:171","msg":"trace[551732733] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tfstx; range_end:; response_count:1; response_revision:8983; }","duration":"558.383781ms","start":"2026-10-19T02:13:58.52624Z","end":"2026-10-19T02:13:59.084624Z","steps":["trace[551732733] 'agreement among raft nodes before linearized reading' (duration: 558.252508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:59.08463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.342654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-19T02:13:59.084639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.972584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-19T02:13:59.084661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:13:58.52623Z","time spent":"558.422622ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-tfstx\" "} {"level":"info","ts":"2026-10-19T02:13:59.084664Z","caller":"traceutil/trace.go:171","msg":"trace[1395841598] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8983; }","duration":"248.404036ms","start":"2026-10-19T02:13:58.836252Z","end":"2026-10-19T02:13:59.084656Z","steps":["trace[1395841598] 'agreement among raft nodes before linearized reading' (duration: 248.258323ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:13:59.084667Z","caller":"traceutil/trace.go:171","msg":"trace[610124135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8983; }","duration":"561.023435ms","start":"2026-10-19T02:13:58.523636Z","end":"2026-10-19T02:13:59.084659Z","steps":["trace[610124135] 'agreement among raft nodes before linearized reading' (duration: 560.953824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:59.084686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:13:58.523624Z","time spent":"561.057176ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-19T02:13:59.084684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.192634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-19T02:13:59.084713Z","caller":"traceutil/trace.go:171","msg":"trace[1879354122] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8983; }","duration":"398.261285ms","start":"2026-10-19T02:13:58.686443Z","end":"2026-10-19T02:13:59.084704Z","steps":["trace[1879354122] 'agreement among raft nodes before linearized reading' (duration: 398.170133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:59.084695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.155256ms","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-10-19T02:13:59.08477Z","caller":"traceutil/trace.go:171","msg":"trace[1627366759] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8983; }","duration":"272.2779ms","start":"2026-10-19T02:13:58.812476Z","end":"2026-10-19T02:13:59.084754Z","steps":["trace[1627366759] 'agreement among raft nodes before linearized reading' (duration: 272.128876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:13:59.084731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:13:58.686425Z","time spent":"398.301177ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-10-19T02:13:59.26615Z","caller":"traceutil/trace.go:171","msg":"trace[931439650] transaction","detail":"{read_only:false; response_revision:8985; number_of_response:1; }","duration":"177.827329ms","start":"2026-10-19T02:13:59.08829Z","end":"2026-10-19T02:13:59.266117Z","steps":["trace[931439650] 'process raft request' (duration: 82.137022ms)","trace[931439650] 'compare' (duration: 95.458102ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:13:59.292523Z","caller":"traceutil/trace.go:171","msg":"trace[1993961107] transaction","detail":"{read_only:false; response_revision:8986; number_of_response:1; }","duration":"140.996995ms","start":"2026-10-19T02:13:59.151504Z","end":"2026-10-19T02:13:59.292501Z","steps":["trace[1993961107] 'process raft request' (duration: 140.864522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:14:01.733451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.396405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T02:14:01.733513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.932309ms","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-10-19T02:14:01.733506Z","caller":"traceutil/trace.go:171","msg":"trace[455880800] transaction","detail":"{read_only:false; response_revision:9001; number_of_response:1; }","duration":"114.821168ms","start":"2026-10-19T02:14:01.618654Z","end":"2026-10-19T02:14:01.733476Z","steps":["trace[455880800] 'process raft request' (duration: 90.663647ms)","trace[455880800] 'compare' (duration: 24.046618ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:14:01.733534Z","caller":"traceutil/trace.go:171","msg":"trace[102039388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9000; }","duration":"116.517568ms","start":"2026-10-19T02:14:01.616997Z","end":"2026-10-19T02:14:01.733515Z","steps":["trace[102039388] 'range keys from in-memory index tree' (duration: 116.293173ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:14:01.733562Z","caller":"traceutil/trace.go:171","msg":"trace[1671220830] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:9000; }","duration":"117.062891ms","start":"2026-10-19T02:14:01.616483Z","end":"2026-10-19T02:14:01.733546Z","steps":["trace[1671220830] 'count revisions from in-memory index tree' (duration: 116.871817ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:14:01.777632Z","caller":"traceutil/trace.go:171","msg":"trace[1332259152] transaction","detail":"{read_only:false; response_revision:9002; number_of_response:1; }","duration":"101.351614ms","start":"2026-10-19T02:14:01.67625Z","end":"2026-10-19T02:14:01.777602Z","steps":["trace[1332259152] 'process raft request' (duration: 101.120699ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:14:09.476779Z","caller":"traceutil/trace.go:171","msg":"trace[107821491] transaction","detail":"{read_only:false; response_revision:9042; number_of_response:1; }","duration":"102.509402ms","start":"2026-10-19T02:14:09.374239Z","end":"2026-10-19T02:14:09.476749Z","steps":["trace[107821491] 'process raft request' (duration: 59.084399ms)","trace[107821491] 'compare' (duration: 43.190817ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:14:09.764191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.050708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:14:09.764274Z","caller":"traceutil/trace.go:171","msg":"trace[1629704403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9042; }","duration":"146.188801ms","start":"2026-10-19T02:14:09.618066Z","end":"2026-10-19T02:14:09.764254Z","steps":["trace[1629704403] 'range keys from in-memory index tree' (duration: 145.929114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:14:09.764413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.027973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-19T02:14:09.764495Z","caller":"traceutil/trace.go:171","msg":"trace[1033217061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9042; }","duration":"120.163196ms","start":"2026-10-19T02:14:09.644313Z","end":"2026-10-19T02:14:09.764476Z","steps":["trace[1033217061] 'range keys from in-memory index tree' (duration: 119.817958ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:14:10.058759Z","caller":"traceutil/trace.go:171","msg":"trace[1186175388] linearizableReadLoop","detail":"{readStateIndex:9364; appliedIndex:9363; }","duration":"283.651482ms","start":"2026-10-19T02:14:09.77508Z","end":"2026-10-19T02:14:10.058732Z","steps":["trace[1186175388] 'read index received' (duration: 283.536899ms)","trace[1186175388] 'applied index is now lower than readState.Index' (duration: 113.453µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:14:10.058946Z","caller":"traceutil/trace.go:171","msg":"trace[572434159] transaction","detail":"{read_only:false; response_revision:9043; number_of_response:1; }","duration":"290.186668ms","start":"2026-10-19T02:14:09.768727Z","end":"2026-10-19T02:14:10.058914Z","steps":["trace[572434159] 'process raft request' (duration: 289.81904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:14:10.059064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.963559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-19T02:14:10.059121Z","caller":"traceutil/trace.go:171","msg":"trace[1172470348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9043; }","duration":"284.061402ms","start":"2026-10-19T02:14:09.775045Z","end":"2026-10-19T02:14:10.059106Z","steps":["trace[1172470348] 'agreement among raft nodes before linearized reading' (duration: 283.880768ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:14:10.231705Z","caller":"traceutil/trace.go:171","msg":"trace[311184403] linearizableReadLoop","detail":"{readStateIndex:9366; appliedIndex:9364; }","duration":"172.848482ms","start":"2026-10-19T02:14:10.058828Z","end":"2026-10-19T02:14:10.231677Z","steps":["trace[311184403] 'read index received' (duration: 106.075278ms)","trace[311184403] 'applied index is now lower than readState.Index' (duration: 66.772074ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:14:10.231768Z","caller":"traceutil/trace.go:171","msg":"trace[1898413589] transaction","detail":"{read_only:false; response_revision:9045; number_of_response:1; }","duration":"289.84083ms","start":"2026-10-19T02:14:09.941895Z","end":"2026-10-19T02:14:10.231736Z","steps":["trace[1898413589] 'process raft request' (duration: 289.706087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:14:10.231846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.735756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:14:10.231894Z","caller":"traceutil/trace.go:171","msg":"trace[665840677] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:9045; }","duration":"306.844389ms","start":"2026-10-19T02:14:09.925033Z","end":"2026-10-19T02:14:10.231877Z","steps":["trace[665840677] 'agreement among raft nodes before linearized reading' (duration: 306.754657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:14:10.231889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.933058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-19T02:14:10.23192Z","caller":"traceutil/trace.go:171","msg":"trace[924042653] transaction","detail":"{read_only:false; response_revision:9044; number_of_response:1; }","duration":"419.831773ms","start":"2026-10-19T02:14:09.81206Z","end":"2026-10-19T02:14:10.231892Z","steps":["trace[924042653] 'process raft request' (duration: 352.828084ms)","trace[924042653] 'compare' (duration: 66.550928ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:14:10.231944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:14:09.925009Z","time spent":"306.922391ms","remote":"127.0.0.1:45884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-10-19T02:14:10.231958Z","caller":"traceutil/trace.go:171","msg":"trace[935693899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9045; }","duration":"199.050991ms","start":"2026-10-19T02:14:10.032886Z","end":"2026-10-19T02:14:10.231937Z","steps":["trace[935693899] 'agreement among raft nodes before linearized reading' (duration: 198.844796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:14:10.232057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:14:09.812038Z","time spent":"419.941125ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:14:10.25366Z","caller":"traceutil/trace.go:171","msg":"trace[1789772774] transaction","detail":"{read_only:false; response_revision:9046; number_of_response:1; }","duration":"189.957432ms","start":"2026-10-19T02:14:10.063675Z","end":"2026-10-19T02:14:10.253633Z","steps":["trace[1789772774] 'process raft request' (duration: 189.781268ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:14:10.44268Z","caller":"traceutil/trace.go:171","msg":"trace[2120612004] transaction","detail":"{read_only:false; response_revision:9047; number_of_response:1; }","duration":"205.999398ms","start":"2026-10-19T02:14:10.236657Z","end":"2026-10-19T02:14:10.442656Z","steps":["trace[2120612004] 'process raft request' (duration: 125.650248ms)","trace[2120612004] 'compare' (duration: 79.94999ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:14:12.897634Z","caller":"traceutil/trace.go:171","msg":"trace[997379492] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9065; }","duration":"174.165843ms","start":"2026-10-19T02:14:12.723401Z","end":"2026-10-19T02:14:12.897567Z","steps":["trace[997379492] 'process raft request' (duration: 103.594268ms)","trace[997379492] 'compare' (duration: 70.415251ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:14:12.924782Z","caller":"traceutil/trace.go:171","msg":"trace[954718767] linearizableReadLoop","detail":"{readStateIndex:9388; appliedIndex:9386; }","duration":"121.498538ms","start":"2026-10-19T02:14:12.803249Z","end":"2026-10-19T02:14:12.924748Z","steps":["trace[954718767] 'read index received' (duration: 23.888054ms)","trace[954718767] 'applied index is now lower than readState.Index' (duration: 97.608654ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:14:12.924819Z","caller":"traceutil/trace.go:171","msg":"trace[764345643] transaction","detail":"{read_only:false; response_revision:9066; number_of_response:1; }","duration":"200.555407ms","start":"2026-10-19T02:14:12.724234Z","end":"2026-10-19T02:14:12.924789Z","steps":["trace[764345643] 'process raft request' (duration: 200.27259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:14:12.925043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.773504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-10-19T02:14:12.925114Z","caller":"traceutil/trace.go:171","msg":"trace[1313456129] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:9066; }","duration":"121.910869ms","start":"2026-10-19T02:14:12.803191Z","end":"2026-10-19T02:14:12.925102Z","steps":["trace[1313456129] 'agreement among raft nodes before linearized reading' (duration: 121.680003ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:14:18.494307Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024fe.wal"} {"level":"info","ts":"2026-10-19T02:15:13.034608Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"afeb1bf8b692f9e8","local-member-applied-index":10006,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:15:13.042107Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10006} {"level":"info","ts":"2026-10-19T02:15:13.042186Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5006} {"level":"info","ts":"2026-10-19T02:15:28.626936Z","caller":"traceutil/trace.go:171","msg":"trace[134845746] transaction","detail":"{read_only:false; response_revision:9837; number_of_response:1; }","duration":"111.57206ms","start":"2026-10-19T02:15:28.515331Z","end":"2026-10-19T02:15:28.626903Z","steps":["trace[134845746] 'process raft request' (duration: 111.366945ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:16:53.820603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8040} {"level":"info","ts":"2026-10-19T02:16:53.910447Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8040,"took":"87.657199ms","hash":692731304,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":24961024,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-19T02:16:53.910534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":692731304,"revision":8040,"compact-revision":5667} {"level":"info","ts":"2026-10-19T02:19:50.887934Z","caller":"traceutil/trace.go:171","msg":"trace[97188934] transaction","detail":"{read_only:false; response_revision:11517; number_of_response:1; }","duration":"101.341026ms","start":"2026-10-19T02:19:50.78655Z","end":"2026-10-19T02:19:50.887891Z","steps":["trace[97188934] 'process raft request' (duration: 101.221783ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:19:50.887934Z","caller":"traceutil/trace.go:171","msg":"trace[827426307] transaction","detail":"{read_only:false; response_revision:11516; number_of_response:1; }","duration":"102.36777ms","start":"2026-10-19T02:19:50.785488Z","end":"2026-10-19T02:19:50.887856Z","steps":["trace[827426307] 'process raft request' (duration: 93.170903ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:21:53.828025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10403} {"level":"info","ts":"2026-10-19T02:21:53.914745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10403,"took":"83.651043ms","hash":2164900792,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":25399296,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-19T02:21:53.914795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2164900792,"revision":10403,"compact-revision":8040} {"level":"info","ts":"2026-10-19T02:26:53.833819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12576} {"level":"info","ts":"2026-10-19T02:26:53.914642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12576,"took":"78.279975ms","hash":776334531,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":27881472,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-19T02:26:53.914718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":776334531,"revision":12576,"compact-revision":10403} {"level":"warn","ts":"2026-10-19T02:27:13.666722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.093286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-19T02:27:13.666836Z","caller":"traceutil/trace.go:171","msg":"trace[1922505300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14717; }","duration":"218.26899ms","start":"2026-10-19T02:27:13.448542Z","end":"2026-10-19T02:27:13.666811Z","steps":["trace[1922505300] 'range keys from in-memory index tree' (duration: 217.827259ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:27:21.446053Z","caller":"traceutil/trace.go:171","msg":"trace[600837117] linearizableReadLoop","detail":"{readStateIndex:15301; appliedIndex:15300; }","duration":"135.42748ms","start":"2026-10-19T02:27:21.310592Z","end":"2026-10-19T02:27:21.44602Z","steps":["trace[600837117] 'read index received' (duration: 135.080292ms)","trace[600837117] 'applied index is now lower than readState.Index' (duration: 345.478µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:27:21.446112Z","caller":"traceutil/trace.go:171","msg":"trace[157698607] transaction","detail":"{read_only:false; response_revision:14792; number_of_response:1; }","duration":"136.106666ms","start":"2026-10-19T02:27:21.309962Z","end":"2026-10-19T02:27:21.446069Z","steps":["trace[157698607] 'process raft request' (duration: 135.82746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:27:21.446248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.609914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:27:21.446299Z","caller":"traceutil/trace.go:171","msg":"trace[404780951] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:14792; }","duration":"135.721507ms","start":"2026-10-19T02:27:21.310561Z","end":"2026-10-19T02:27:21.446283Z","steps":["trace[404780951] 'agreement among raft nodes before linearized reading' (duration: 135.598134ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:27:24.776692Z","caller":"traceutil/trace.go:171","msg":"trace[891087656] transaction","detail":"{read_only:false; response_revision:14807; number_of_response:1; }","duration":"129.945191ms","start":"2026-10-19T02:27:24.64672Z","end":"2026-10-19T02:27:24.776666Z","steps":["trace[891087656] 'process raft request' (duration: 128.793444ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:27:24.783221Z","caller":"traceutil/trace.go:171","msg":"trace[837178877] transaction","detail":"{read_only:false; response_revision:14808; number_of_response:1; }","duration":"131.024537ms","start":"2026-10-19T02:27:24.652176Z","end":"2026-10-19T02:27:24.783201Z","steps":["trace[837178877] 'process raft request' (duration: 130.884583ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:27:24.783436Z","caller":"traceutil/trace.go:171","msg":"trace[1103180536] transaction","detail":"{read_only:false; response_revision:14809; number_of_response:1; }","duration":"103.656273ms","start":"2026-10-19T02:27:24.679759Z","end":"2026-10-19T02:27:24.783415Z","steps":["trace[1103180536] 'process raft request' (duration: 103.396857ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:27:54.941513Z","caller":"traceutil/trace.go:171","msg":"trace[1252539423] transaction","detail":"{read_only:false; response_revision:14966; number_of_response:1; }","duration":"127.271168ms","start":"2026-10-19T02:27:54.814204Z","end":"2026-10-19T02:27:54.941475Z","steps":["trace[1252539423] 'process raft request' (duration: 123.446608ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:28:03.712926Z","caller":"traceutil/trace.go:171","msg":"trace[581500845] transaction","detail":"{read_only:false; response_revision:15031; number_of_response:1; }","duration":"112.541602ms","start":"2026-10-19T02:28:03.600365Z","end":"2026-10-19T02:28:03.712907Z","steps":["trace[581500845] 'process raft request' (duration: 112.402129ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:28:13.928222Z","caller":"traceutil/trace.go:171","msg":"trace[371977718] linearizableReadLoop","detail":"{readStateIndex:15725; appliedIndex:15724; }","duration":"140.205124ms","start":"2026-10-19T02:28:13.787981Z","end":"2026-10-19T02:28:13.928186Z","steps":["trace[371977718] 'read index received' (duration: 139.887946ms)","trace[371977718] 'applied index is now lower than readState.Index' (duration: 315.698µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:28:13.928338Z","caller":"traceutil/trace.go:171","msg":"trace[444623565] transaction","detail":"{read_only:false; response_revision:15202; number_of_response:1; }","duration":"180.673767ms","start":"2026-10-19T02:28:13.747594Z","end":"2026-10-19T02:28:13.928267Z","steps":["trace[444623565] 'process raft request' (duration: 180.344499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:28:13.928626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.619873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-19T02:28:13.928673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.579296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-69rtc.18dfcd4980dd992e\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-19T02:28:13.9287Z","caller":"traceutil/trace.go:171","msg":"trace[605650528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15202; }","duration":"140.753186ms","start":"2026-10-19T02:28:13.787927Z","end":"2026-10-19T02:28:13.92868Z","steps":["trace[605650528] 'agreement among raft nodes before linearized reading' (duration: 140.49435ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:28:13.928761Z","caller":"traceutil/trace.go:171","msg":"trace[1108266266] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-69rtc.18dfcd4980dd992e; range_end:; response_count:1; response_revision:15202; }","duration":"102.699479ms","start":"2026-10-19T02:28:13.826014Z","end":"2026-10-19T02:28:13.928713Z","steps":["trace[1108266266] 'agreement among raft nodes before linearized reading' (duration: 102.482944ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:29:49.519895Z","caller":"traceutil/trace.go:171","msg":"trace[584876509] transaction","detail":"{read_only:false; response_revision:16270; number_of_response:1; }","duration":"135.690787ms","start":"2026-10-19T02:29:49.384184Z","end":"2026-10-19T02:29:49.519875Z","steps":["trace[584876509] 'process raft request' (duration: 125.869715ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:29:49.519856Z","caller":"traceutil/trace.go:171","msg":"trace[1132738800] transaction","detail":"{read_only:false; response_revision:16271; number_of_response:1; }","duration":"113.564676ms","start":"2026-10-19T02:29:49.406267Z","end":"2026-10-19T02:29:49.519831Z","steps":["trace[1132738800] 'process raft request' (duration: 113.509455ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:29:50.518489Z","caller":"traceutil/trace.go:171","msg":"trace[217264602] linearizableReadLoop","detail":"{readStateIndex:16829; appliedIndex:16828; }","duration":"157.099651ms","start":"2026-10-19T02:29:50.361363Z","end":"2026-10-19T02:29:50.518463Z","steps":["trace[217264602] 'read index received' (duration: 156.936357ms)","trace[217264602] 'applied index is now lower than readState.Index' (duration: 161.624µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:29:50.518579Z","caller":"traceutil/trace.go:171","msg":"trace[1935378965] transaction","detail":"{read_only:false; response_revision:16275; number_of_response:1; }","duration":"208.584894ms","start":"2026-10-19T02:29:50.309968Z","end":"2026-10-19T02:29:50.518553Z","steps":["trace[1935378965] 'process raft request' (duration: 208.325158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:29:50.518937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.516691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-19T02:29:50.519134Z","caller":"traceutil/trace.go:171","msg":"trace[84255295] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16275; }","duration":"157.745226ms","start":"2026-10-19T02:29:50.361357Z","end":"2026-10-19T02:29:50.519102Z","steps":["trace[84255295] 'agreement among raft nodes before linearized reading' (duration: 157.304216ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:29:50.672008Z","caller":"traceutil/trace.go:171","msg":"trace[282776493] linearizableReadLoop","detail":"{readStateIndex:16830; appliedIndex:16829; }","duration":"115.220024ms","start":"2026-10-19T02:29:50.556737Z","end":"2026-10-19T02:29:50.671957Z","steps":["trace[282776493] 'read index received' (duration: 66.776623ms)","trace[282776493] 'applied index is now lower than readState.Index' (duration: 48.441941ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:29:50.672135Z","caller":"traceutil/trace.go:171","msg":"trace[568037484] transaction","detail":"{read_only:false; response_revision:16276; number_of_response:1; }","duration":"149.080312ms","start":"2026-10-19T02:29:50.522993Z","end":"2026-10-19T02:29:50.672073Z","steps":["trace[568037484] 'process raft request' (duration: 100.566599ms)","trace[568037484] 'compare' (duration: 48.126174ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:29:50.672561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.769817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-19T02:29:50.67267Z","caller":"traceutil/trace.go:171","msg":"trace[2053821010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16276; }","duration":"115.969433ms","start":"2026-10-19T02:29:50.556675Z","end":"2026-10-19T02:29:50.672644Z","steps":["trace[2053821010] 'agreement among raft nodes before linearized reading' (duration: 115.499011ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:31:53.839402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14577} {"level":"info","ts":"2026-10-19T02:31:53.917355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14577,"took":"75.478397ms","hash":1215829784,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-19T02:31:53.917415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1215829784,"revision":14577,"compact-revision":12576} {"level":"info","ts":"2026-10-19T02:32:57.466806Z","caller":"traceutil/trace.go:171","msg":"trace[886867015] linearizableReadLoop","detail":"{readStateIndex:18335; appliedIndex:18333; }","duration":"162.003984ms","start":"2026-10-19T02:32:57.304776Z","end":"2026-10-19T02:32:57.46678Z","steps":["trace[886867015] 'read index received' (duration: 14.526419ms)","trace[886867015] 'applied index is now lower than readState.Index' (duration: 147.476455ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:32:57.466972Z","caller":"traceutil/trace.go:171","msg":"trace[1667343113] transaction","detail":"{read_only:false; response_revision:17732; number_of_response:1; }","duration":"219.578047ms","start":"2026-10-19T02:32:57.24735Z","end":"2026-10-19T02:32:57.466929Z","steps":["trace[1667343113] 'process raft request' (duration: 219.201409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:32:57.46716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.29734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-19T02:32:57.467218Z","caller":"traceutil/trace.go:171","msg":"trace[1562903763] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:17732; }","duration":"162.437343ms","start":"2026-10-19T02:32:57.304762Z","end":"2026-10-19T02:32:57.4672Z","steps":["trace[1562903763] 'agreement among raft nodes before linearized reading' (duration: 162.206088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:32:57.467409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.078325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.25\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-19T02:32:57.467633Z","caller":"traceutil/trace.go:171","msg":"trace[1456242809] range","detail":"{range_begin:/registry/masterleases/162.253.55.25; range_end:; response_count:1; response_revision:17732; }","duration":"126.338401ms","start":"2026-10-19T02:32:57.341235Z","end":"2026-10-19T02:32:57.467573Z","steps":["trace[1456242809] 'agreement among raft nodes before linearized reading' (duration: 125.856461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:02.785364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.748753ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-19T02:35:02.785641Z","caller":"traceutil/trace.go:171","msg":"trace[62198812] linearizableReadLoop","detail":"{readStateIndex:19181; appliedIndex:19180; }","duration":"167.925288ms","start":"2026-10-19T02:35:02.617676Z","end":"2026-10-19T02:35:02.785601Z","steps":["trace[62198812] 'read index received' (duration: 20.915208ms)","trace[62198812] 'applied index is now lower than readState.Index' (duration: 147.005369ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:35:02.786016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.320166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7384"} {"level":"info","ts":"2026-10-19T02:35:02.786052Z","caller":"traceutil/trace.go:171","msg":"trace[1565558839] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18553; }","duration":"168.46871ms","start":"2026-10-19T02:35:02.617573Z","end":"2026-10-19T02:35:02.786042Z","steps":["trace[1565558839] 'agreement among raft nodes before linearized reading' (duration: 168.211204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:02.786409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.300506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:35:02.786496Z","caller":"traceutil/trace.go:171","msg":"trace[324234246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18554; }","duration":"168.46145ms","start":"2026-10-19T02:35:02.618014Z","end":"2026-10-19T02:35:02.786476Z","steps":["trace[324234246] 'agreement among raft nodes before linearized reading' (duration: 168.264916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:02.786812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.846664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-19T02:35:02.786876Z","caller":"traceutil/trace.go:171","msg":"trace[295549291] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:18554; }","duration":"122.939636ms","start":"2026-10-19T02:35:02.663905Z","end":"2026-10-19T02:35:02.786845Z","steps":["trace[295549291] 'agreement among raft nodes before linearized reading' (duration: 122.848844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:32.36058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.740226ms","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:2083"} {"level":"info","ts":"2026-10-19T02:35:32.361189Z","caller":"traceutil/trace.go:171","msg":"trace[288759634] 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:18769; }","duration":"159.386501ms","start":"2026-10-19T02:35:32.201749Z","end":"2026-10-19T02:35:32.361136Z","steps":["trace[288759634] 'range keys from in-memory index tree' (duration: 158.46097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:32.361246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.278291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-10-19T02:35:32.361376Z","caller":"traceutil/trace.go:171","msg":"trace[1825934943] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18769; }","duration":"127.469646ms","start":"2026-10-19T02:35:32.233872Z","end":"2026-10-19T02:35:32.361341Z","steps":["trace[1825934943] 'range keys from in-memory index tree' (duration: 127.066207ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:35:39.319185Z","caller":"traceutil/trace.go:171","msg":"trace[648078733] linearizableReadLoop","detail":"{readStateIndex:19458; appliedIndex:19457; }","duration":"120.374551ms","start":"2026-10-19T02:35:39.198785Z","end":"2026-10-19T02:35:39.31916Z","steps":["trace[648078733] 'read index received' (duration: 120.071704ms)","trace[648078733] 'applied index is now lower than readState.Index' (duration: 301.617µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:35:39.319294Z","caller":"traceutil/trace.go:171","msg":"trace[312401445] transaction","detail":"{read_only:false; response_revision:18817; number_of_response:1; }","duration":"223.401946ms","start":"2026-10-19T02:35:39.095857Z","end":"2026-10-19T02:35:39.319259Z","steps":["trace[312401445] 'process raft request' (duration: 223.13973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:39.319395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.564355ms","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-10-19T02:35:39.319428Z","caller":"traceutil/trace.go:171","msg":"trace[1191313536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18817; }","duration":"120.679818ms","start":"2026-10-19T02:35:39.198738Z","end":"2026-10-19T02:35:39.319418Z","steps":["trace[1191313536] 'agreement among raft nodes before linearized reading' (duration: 120.516994ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:35:39.720613Z","caller":"traceutil/trace.go:171","msg":"trace[1117878393] linearizableReadLoop","detail":"{readStateIndex:19460; appliedIndex:19459; }","duration":"107.702114ms","start":"2026-10-19T02:35:39.612885Z","end":"2026-10-19T02:35:39.720587Z","steps":["trace[1117878393] 'read index received' (duration: 61.927626ms)","trace[1117878393] 'applied index is now lower than readState.Index' (duration: 45.773338ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:35:39.720694Z","caller":"traceutil/trace.go:171","msg":"trace[1940968116] transaction","detail":"{read_only:false; response_revision:18819; number_of_response:1; }","duration":"199.267002ms","start":"2026-10-19T02:35:39.521382Z","end":"2026-10-19T02:35:39.720649Z","steps":["trace[1940968116] 'process raft request' (duration: 153.404971ms)","trace[1940968116] 'compare' (duration: 45.254686ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:35:39.720887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.987432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:8057"} {"level":"info","ts":"2026-10-19T02:35:39.721201Z","caller":"traceutil/trace.go:171","msg":"trace[658639149] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:18819; }","duration":"108.34593ms","start":"2026-10-19T02:35:39.612834Z","end":"2026-10-19T02:35:39.72118Z","steps":["trace[658639149] 'agreement among raft nodes before linearized reading' (duration: 107.89671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:39.72094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.358483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:35:39.721353Z","caller":"traceutil/trace.go:171","msg":"trace[546222464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18819; }","duration":"103.764252ms","start":"2026-10-19T02:35:39.617544Z","end":"2026-10-19T02:35:39.721309Z","steps":["trace[546222464] 'agreement among raft nodes before linearized reading' (duration: 103.332232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:40.392132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.254041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-19T02:35:40.392208Z","caller":"traceutil/trace.go:171","msg":"trace[1468427327] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18820; }","duration":"429.391704ms","start":"2026-10-19T02:35:39.9628Z","end":"2026-10-19T02:35:40.392191Z","steps":["trace[1468427327] 'count revisions from in-memory index tree' (duration: 429.17839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:40.392241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:35:39.962781Z","time spent":"429.452225ms","remote":"127.0.0.1:38390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-19T02:35:40.392492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.938578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-dz5pb\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-10-19T02:35:40.3926Z","caller":"traceutil/trace.go:171","msg":"trace[1448584934] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-dz5pb; range_end:; response_count:1; response_revision:18820; }","duration":"499.146162ms","start":"2026-10-19T02:35:39.893429Z","end":"2026-10-19T02:35:40.392575Z","steps":["trace[1448584934] 'range keys from in-memory index tree' (duration: 498.731373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:40.392659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:35:39.893409Z","time spent":"499.234395ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22145,"request content":"key:\"/registry/pods/openstack/nova-compute-default-dz5pb\" "} {"level":"info","ts":"2026-10-19T02:35:40.392735Z","caller":"traceutil/trace.go:171","msg":"trace[1524066818] linearizableReadLoop","detail":"{readStateIndex:19462; appliedIndex:19461; }","duration":"298.698553ms","start":"2026-10-19T02:35:40.094028Z","end":"2026-10-19T02:35:40.392726Z","steps":["trace[1524066818] 'read index received' (duration: 284.276286ms)","trace[1524066818] 'applied index is now lower than readState.Index' (duration: 14.421647ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:35:40.392794Z","caller":"traceutil/trace.go:171","msg":"trace[1465989851] transaction","detail":"{read_only:false; response_revision:18821; number_of_response:1; }","duration":"316.57501ms","start":"2026-10-19T02:35:40.076211Z","end":"2026-10-19T02:35:40.392786Z","steps":["trace[1465989851] 'process raft request' (duration: 302.076192ms)","trace[1465989851] 'compare' (duration: 14.291133ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:35:40.392858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:35:40.076187Z","time spent":"316.627582ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:35:40.392934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.906948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:35:40.392959Z","caller":"traceutil/trace.go:171","msg":"trace[2086017696] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:18821; }","duration":"298.962689ms","start":"2026-10-19T02:35:40.093987Z","end":"2026-10-19T02:35:40.39295Z","steps":["trace[2086017696] 'agreement among raft nodes before linearized reading' (duration: 298.923338ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:35:40.461657Z","caller":"traceutil/trace.go:171","msg":"trace[1723041093] transaction","detail":"{read_only:false; response_revision:18822; number_of_response:1; }","duration":"207.872884ms","start":"2026-10-19T02:35:40.253741Z","end":"2026-10-19T02:35:40.461614Z","steps":["trace[1723041093] 'process raft request' (duration: 207.639568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:35:40.462093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.32201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-19T02:35:40.462237Z","caller":"traceutil/trace.go:171","msg":"trace[689243116] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:18822; }","duration":"183.516284ms","start":"2026-10-19T02:35:40.278696Z","end":"2026-10-19T02:35:40.462212Z","steps":["trace[689243116] 'agreement among raft nodes before linearized reading' (duration: 183.221368ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:36:26.208459Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"afeb1bf8b692f9e8","local-member-applied-index":20007,"local-member-snapshot-index":10006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:36:26.216911Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20007} {"level":"info","ts":"2026-10-19T02:36:26.217Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15007} {"level":"info","ts":"2026-10-19T02:36:53.849009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17047} {"level":"info","ts":"2026-10-19T02:36:53.946115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17047,"took":"93.537664ms","hash":3886244935,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":34414592,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-19T02:36:53.94617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3886244935,"revision":17047,"compact-revision":14577} {"level":"info","ts":"2026-10-19T02:41:29.121935Z","caller":"traceutil/trace.go:171","msg":"trace[273841551] transaction","detail":"{read_only:false; response_revision:21543; number_of_response:1; }","duration":"173.402282ms","start":"2026-10-19T02:41:28.948455Z","end":"2026-10-19T02:41:29.121857Z","steps":["trace[273841551] 'process raft request' (duration: 173.219837ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:45.400343Z","caller":"traceutil/trace.go:171","msg":"trace[1499316752] transaction","detail":"{read_only:false; response_revision:21625; number_of_response:1; }","duration":"202.17165ms","start":"2026-10-19T02:41:45.19813Z","end":"2026-10-19T02:41:45.400302Z","steps":["trace[1499316752] 'process raft request' (duration: 201.709779ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:45.50866Z","caller":"traceutil/trace.go:171","msg":"trace[2103321115] transaction","detail":"{read_only:false; response_revision:21626; number_of_response:1; }","duration":"101.624138ms","start":"2026-10-19T02:41:45.406989Z","end":"2026-10-19T02:41:45.508614Z","steps":["trace[2103321115] 'process raft request' (duration: 79.621859ms)","trace[2103321115] 'compare' (duration: 21.797854ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:41:45.858826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.030868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-19T02:41:45.858977Z","caller":"traceutil/trace.go:171","msg":"trace[8500946] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:21626; }","duration":"158.218333ms","start":"2026-10-19T02:41:45.700745Z","end":"2026-10-19T02:41:45.858964Z","steps":["trace[8500946] 'range keys from in-memory index tree' (duration: 157.900185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:41:45.858901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.379933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-19T02:41:45.859133Z","caller":"traceutil/trace.go:171","msg":"trace[332007627] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:21626; }","duration":"224.66368ms","start":"2026-10-19T02:41:45.634437Z","end":"2026-10-19T02:41:45.859101Z","steps":["trace[332007627] 'range keys from in-memory index tree' (duration: 224.2372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:41:45.858908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.555672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-19T02:41:45.859314Z","caller":"traceutil/trace.go:171","msg":"trace[129117954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21626; }","duration":"175.991252ms","start":"2026-10-19T02:41:45.683306Z","end":"2026-10-19T02:41:45.859298Z","steps":["trace[129117954] 'range keys from in-memory index tree' (duration: 175.262735ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:46.259412Z","caller":"traceutil/trace.go:171","msg":"trace[1219540399] transaction","detail":"{read_only:false; response_revision:21628; number_of_response:1; }","duration":"395.750648ms","start":"2026-10-19T02:41:45.863587Z","end":"2026-10-19T02:41:46.259337Z","steps":["trace[1219540399] 'process raft request' (duration: 395.449621ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:46.259477Z","caller":"traceutil/trace.go:171","msg":"trace[272483450] transaction","detail":"{read_only:false; response_revision:21629; number_of_response:1; }","duration":"395.383448ms","start":"2026-10-19T02:41:45.864067Z","end":"2026-10-19T02:41:46.25945Z","steps":["trace[272483450] 'process raft request' (duration: 395.144743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:41:46.259592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:41:45.863579Z","time spent":"395.921342ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:41:46.259611Z","caller":"traceutil/trace.go:171","msg":"trace[1355315399] transaction","detail":"{read_only:false; response_revision:21630; number_of_response:1; }","duration":"381.087782ms","start":"2026-10-19T02:41:45.878494Z","end":"2026-10-19T02:41:46.259582Z","steps":["trace[1355315399] 'process raft request' (duration: 380.780994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:41:46.25972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:41:45.878471Z","time spent":"381.189703ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:41:46.259756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:41:45.864059Z","time spent":"395.473681ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:41:46.491988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.175303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:41:46.492406Z","caller":"traceutil/trace.go:171","msg":"trace[1248302525] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21630; }","duration":"161.527081ms","start":"2026-10-19T02:41:46.330764Z","end":"2026-10-19T02:41:46.492291Z","steps":["trace[1248302525] 'range keys from in-memory index tree' (duration: 161.03713ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:53.856873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19657} {"level":"info","ts":"2026-10-19T02:41:53.997826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19657,"took":"137.515694ms","hash":1228006911,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":29446144,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-19T02:41:53.997938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1228006911,"revision":19657,"compact-revision":17047} {"level":"warn","ts":"2026-10-19T02:44:49.755006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.696376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-19T02:44:49.755083Z","caller":"traceutil/trace.go:171","msg":"trace[1101985731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23043; }","duration":"106.819349ms","start":"2026-10-19T02:44:49.648248Z","end":"2026-10-19T02:44:49.755068Z","steps":["trace[1101985731] 'range keys from in-memory index tree' (duration: 106.530302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:48.240266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.327915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-19T02:45:48.240362Z","caller":"traceutil/trace.go:171","msg":"trace[1689624368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24135; }","duration":"123.505029ms","start":"2026-10-19T02:45:48.116834Z","end":"2026-10-19T02:45:48.240339Z","steps":["trace[1689624368] 'range keys from in-memory index tree' (duration: 123.130611ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:48.660806Z","caller":"traceutil/trace.go:171","msg":"trace[1306109683] transaction","detail":"{read_only:false; response_revision:24137; number_of_response:1; }","duration":"314.500696ms","start":"2026-10-19T02:45:48.346284Z","end":"2026-10-19T02:45:48.660785Z","steps":["trace[1306109683] 'process raft request' (duration: 313.699317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:48.66098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:45:48.34625Z","time spent":"314.67202ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:46:53.864534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21670} {"level":"info","ts":"2026-10-19T02:46:53.981374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21670,"took":"112.835133ms","hash":3352520732,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":43335680,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-19T02:46:53.981445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3352520732,"revision":21670,"compact-revision":19657} {"level":"warn","ts":"2026-10-19T02:47:08.689645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.112882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:47:08.689735Z","caller":"traceutil/trace.go:171","msg":"trace[1246963827] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25008; }","duration":"111.266096ms","start":"2026-10-19T02:47:08.57845Z","end":"2026-10-19T02:47:08.689716Z","steps":["trace[1246963827] 'count revisions from in-memory index tree' (duration: 111.042621ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:47:59.130373Z","caller":"traceutil/trace.go:171","msg":"trace[578220023] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"112.617918ms","start":"2026-10-19T02:47:59.017727Z","end":"2026-10-19T02:47:59.130345Z","steps":["trace[578220023] 'process raft request' (duration: 101.046571ms)","trace[578220023] 'compare' (duration: 11.449684ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:47:59.157876Z","caller":"traceutil/trace.go:171","msg":"trace[1178352140] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"108.768497ms","start":"2026-10-19T02:47:59.049051Z","end":"2026-10-19T02:47:59.15782Z","steps":["trace[1178352140] 'process raft request' (duration: 108.636323ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:07.063784Z","caller":"traceutil/trace.go:171","msg":"trace[466423101] transaction","detail":"{read_only:false; response_revision:26086; number_of_response:1; }","duration":"161.160573ms","start":"2026-10-19T02:49:06.902584Z","end":"2026-10-19T02:49:07.063744Z","steps":["trace[466423101] 'process raft request' (duration: 160.956388ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:12.420517Z","caller":"traceutil/trace.go:171","msg":"trace[1122417020] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"137.445033ms","start":"2026-10-19T02:49:12.28303Z","end":"2026-10-19T02:49:12.420475Z","steps":["trace[1122417020] 'process raft request' (duration: 136.991082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:49:12.616275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.08283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:49:12.616394Z","caller":"traceutil/trace.go:171","msg":"trace[422993942] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"160.148239ms","start":"2026-10-19T02:49:12.456221Z","end":"2026-10-19T02:49:12.616369Z","steps":["trace[422993942] 'process raft request' (duration: 32.830433ms)","trace[422993942] 'compare' (duration: 126.948937ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:49:12.621983Z","caller":"traceutil/trace.go:171","msg":"trace[1151174383] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"159.199876ms","start":"2026-10-19T02:49:12.462767Z","end":"2026-10-19T02:49:12.621967Z","steps":["trace[1151174383] 'process raft request' (duration: 159.108604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:49:13.098711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.190112ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-19T02:49:13.098812Z","caller":"traceutil/trace.go:171","msg":"trace[1113718886] linearizableReadLoop","detail":"{readStateIndex:26994; appliedIndex:26993; }","duration":"350.24494ms","start":"2026-10-19T02:49:12.748549Z","end":"2026-10-19T02:49:13.098794Z","steps":["trace[1113718886] 'read index received' (duration: 61.857374ms)","trace[1113718886] 'applied index is now lower than readState.Index' (duration: 288.386086ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:49:13.09915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.578168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-19T02:49:13.099229Z","caller":"traceutil/trace.go:171","msg":"trace[1825164421] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26132; }","duration":"350.709142ms","start":"2026-10-19T02:49:12.748504Z","end":"2026-10-19T02:49:13.099213Z","steps":["trace[1825164421] 'agreement among raft nodes before linearized reading' (duration: 350.545508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:49:13.099268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:49:12.748482Z","time spent":"350.774204ms","remote":"127.0.0.1:53486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-10-19T02:49:13.394881Z","caller":"traceutil/trace.go:171","msg":"trace[1689567580] linearizableReadLoop","detail":"{readStateIndex:26995; appliedIndex:26994; }","duration":"296.028124ms","start":"2026-10-19T02:49:13.098836Z","end":"2026-10-19T02:49:13.394864Z","steps":["trace[1689567580] 'read index received' (duration: 295.806259ms)","trace[1689567580] 'applied index is now lower than readState.Index' (duration: 221.345µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:49:13.394944Z","caller":"traceutil/trace.go:171","msg":"trace[459817883] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"299.809666ms","start":"2026-10-19T02:49:13.095084Z","end":"2026-10-19T02:49:13.394894Z","steps":["trace[459817883] 'process raft request' (duration: 299.654292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:49:13.395044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.525135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-19T02:49:13.395069Z","caller":"traceutil/trace.go:171","msg":"trace[1319064689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26133; }","duration":"609.597977ms","start":"2026-10-19T02:49:12.785461Z","end":"2026-10-19T02:49:13.395059Z","steps":["trace[1319064689] 'agreement among raft nodes before linearized reading' (duration: 609.491654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:49:13.395076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.339611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-19T02:49:13.395089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:49:12.785438Z","time spent":"609.646267ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-19T02:49:13.395104Z","caller":"traceutil/trace.go:171","msg":"trace[1128213032] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26133; }","duration":"155.373452ms","start":"2026-10-19T02:49:13.239721Z","end":"2026-10-19T02:49:13.395095Z","steps":["trace[1128213032] 'agreement among raft nodes before linearized reading' (duration: 155.31578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:49:13.395127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.844577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-10-19T02:49:13.39511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.280645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-19T02:49:13.395172Z","caller":"traceutil/trace.go:171","msg":"trace[2125115587] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26133; }","duration":"234.894708ms","start":"2026-10-19T02:49:13.160257Z","end":"2026-10-19T02:49:13.395152Z","steps":["trace[2125115587] 'agreement among raft nodes before linearized reading' (duration: 234.767555ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:13.395205Z","caller":"traceutil/trace.go:171","msg":"trace[1736706835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26133; }","duration":"304.410029ms","start":"2026-10-19T02:49:13.090777Z","end":"2026-10-19T02:49:13.395187Z","steps":["trace[1736706835] 'agreement among raft nodes before linearized reading' (duration: 304.124032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:49:13.395246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:49:13.090764Z","time spent":"304.47233ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-19T02:49:13.395351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.535576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-19T02:49:13.395458Z","caller":"traceutil/trace.go:171","msg":"trace[809437962] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:26133; }","duration":"191.765582ms","start":"2026-10-19T02:49:13.203671Z","end":"2026-10-19T02:49:13.395437Z","steps":["trace[809437962] 'agreement among raft nodes before linearized reading' (duration: 191.410653ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:24.793152Z","caller":"traceutil/trace.go:171","msg":"trace[177155890] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"127.452329ms","start":"2026-10-19T02:49:24.665671Z","end":"2026-10-19T02:49:24.793123Z","steps":["trace[177155890] 'process raft request' (duration: 127.357917ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:24.793241Z","caller":"traceutil/trace.go:171","msg":"trace[220268916] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"127.954671ms","start":"2026-10-19T02:49:24.665221Z","end":"2026-10-19T02:49:24.793176Z","steps":["trace[220268916] 'process raft request' (duration: 120.375456ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:34.017774Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a64.wal"} {"level":"info","ts":"2026-10-19T02:50:03.772999Z","caller":"traceutil/trace.go:171","msg":"trace[1488653749] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"152.147993ms","start":"2026-10-19T02:50:03.620775Z","end":"2026-10-19T02:50:03.772923Z","steps":["trace[1488653749] 'process raft request' (duration: 151.945018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:03.999011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.381038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:50:03.999124Z","caller":"traceutil/trace.go:171","msg":"trace[2024832053] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"335.126881ms","start":"2026-10-19T02:50:03.663981Z","end":"2026-10-19T02:50:03.999107Z","steps":["trace[2024832053] 'process raft request' (duration: 227.589259ms)","trace[2024832053] 'compare' (duration: 106.670631ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:50:03.999162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:03.663948Z","time spent":"335.195452ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:50:03.99944Z","caller":"traceutil/trace.go:171","msg":"trace[153289641] linearizableReadLoop","detail":"{readStateIndex:27491; appliedIndex:27489; }","duration":"327.137305ms","start":"2026-10-19T02:50:03.672211Z","end":"2026-10-19T02:50:03.999349Z","steps":["trace[153289641] 'read index received' (duration: 100.461858ms)","trace[153289641] 'applied index is now lower than readState.Index' (duration: 226.674077ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:50:03.999455Z","caller":"traceutil/trace.go:171","msg":"trace[319815115] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"324.555381ms","start":"2026-10-19T02:50:03.674859Z","end":"2026-10-19T02:50:03.999415Z","steps":["trace[319815115] 'process raft request' (duration: 324.219823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:03.999509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.290128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-19T02:50:03.999568Z","caller":"traceutil/trace.go:171","msg":"trace[149791344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26619; }","duration":"327.393531ms","start":"2026-10-19T02:50:03.672158Z","end":"2026-10-19T02:50:03.999552Z","steps":["trace[149791344] 'agreement among raft nodes before linearized reading' (duration: 327.236077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:03.999601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:03.672143Z","time spent":"327.451833ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-19T02:50:03.999639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:03.674839Z","time spent":"324.699285ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:50:03.99974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.645622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-19T02:50:03.999785Z","caller":"traceutil/trace.go:171","msg":"trace[466422502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26619; }","duration":"299.733354ms","start":"2026-10-19T02:50:03.700041Z","end":"2026-10-19T02:50:03.999774Z","steps":["trace[466422502] 'agreement among raft nodes before linearized reading' (duration: 299.526759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:03.999846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.472188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-19T02:50:03.999957Z","caller":"traceutil/trace.go:171","msg":"trace[1848818191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26619; }","duration":"299.597101ms","start":"2026-10-19T02:50:03.700331Z","end":"2026-10-19T02:50:03.999928Z","steps":["trace[1848818191] 'agreement among raft nodes before linearized reading' (duration: 299.295384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:04.357648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.448807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:50:04.357753Z","caller":"traceutil/trace.go:171","msg":"trace[555662115] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"353.292315ms","start":"2026-10-19T02:50:04.004442Z","end":"2026-10-19T02:50:04.357735Z","steps":["trace[555662115] 'process raft request' (duration: 203.680034ms)","trace[555662115] 'compare' (duration: 149.329734ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:50:04.35781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:04.004419Z","time spent":"353.365806ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:50:04.453073Z","caller":"traceutil/trace.go:171","msg":"trace[23271458] linearizableReadLoop","detail":"{readStateIndex:27495; appliedIndex:27492; }","duration":"291.360059ms","start":"2026-10-19T02:50:04.161688Z","end":"2026-10-19T02:50:04.453048Z","steps":["trace[23271458] 'read index received' (duration: 46.448437ms)","trace[23271458] 'applied index is now lower than readState.Index' (duration: 244.910562ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:50:04.453152Z","caller":"traceutil/trace.go:171","msg":"trace[1140237532] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"446.219189ms","start":"2026-10-19T02:50:04.006886Z","end":"2026-10-19T02:50:04.453105Z","steps":["trace[1140237532] 'process raft request' (duration: 446.120756ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:04.45317Z","caller":"traceutil/trace.go:171","msg":"trace[1046879523] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"447.963622ms","start":"2026-10-19T02:50:04.005142Z","end":"2026-10-19T02:50:04.453105Z","steps":["trace[1046879523] 'process raft request' (duration: 447.725516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:04.453281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.583084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-19T02:50:04.45332Z","caller":"traceutil/trace.go:171","msg":"trace[1205192363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26622; }","duration":"291.633386ms","start":"2026-10-19T02:50:04.161674Z","end":"2026-10-19T02:50:04.453307Z","steps":["trace[1205192363] 'agreement among raft nodes before linearized reading' (duration: 291.486922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:04.453306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:04.005128Z","time spent":"448.109695ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:50:04.453337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:04.00687Z","time spent":"446.350962ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:50:04.454271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.772126ms","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-10-19T02:50:04.454333Z","caller":"traceutil/trace.go:171","msg":"trace[1673209423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26624; }","duration":"194.882798ms","start":"2026-10-19T02:50:04.259434Z","end":"2026-10-19T02:50:04.454317Z","steps":["trace[1673209423] 'agreement among raft nodes before linearized reading' (duration: 194.687154ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:04.4546Z","caller":"traceutil/trace.go:171","msg":"trace[983833295] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"186.969465ms","start":"2026-10-19T02:50:04.267616Z","end":"2026-10-19T02:50:04.454586Z","steps":["trace[983833295] 'process raft request' (duration: 186.335119ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:08.529953Z","caller":"traceutil/trace.go:171","msg":"trace[1712720636] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"152.294186ms","start":"2026-10-19T02:50:08.377634Z","end":"2026-10-19T02:50:08.529928Z","steps":["trace[1712720636] 'process raft request' (duration: 152.113762ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:08.726941Z","caller":"traceutil/trace.go:171","msg":"trace[1818877845] transaction","detail":"{read_only:false; response_revision:26659; number_of_response:1; }","duration":"187.822776ms","start":"2026-10-19T02:50:08.539093Z","end":"2026-10-19T02:50:08.726915Z","steps":["trace[1818877845] 'process raft request' (duration: 187.646482ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:08.727004Z","caller":"traceutil/trace.go:171","msg":"trace[192486684] transaction","detail":"{read_only:false; response_revision:26658; number_of_response:1; }","duration":"187.890178ms","start":"2026-10-19T02:50:08.539088Z","end":"2026-10-19T02:50:08.726978Z","steps":["trace[192486684] 'process raft request' (duration: 163.354208ms)","trace[192486684] 'compare' (duration: 23.613547ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:50:08.777801Z","caller":"traceutil/trace.go:171","msg":"trace[653004807] transaction","detail":"{read_only:false; response_revision:26660; number_of_response:1; }","duration":"238.305662ms","start":"2026-10-19T02:50:08.539454Z","end":"2026-10-19T02:50:08.77776Z","steps":["trace[653004807] 'process raft request' (duration: 238.103767ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:08.778065Z","caller":"traceutil/trace.go:171","msg":"trace[832082644] linearizableReadLoop","detail":"{readStateIndex:27535; appliedIndex:27531; }","duration":"203.90894ms","start":"2026-10-19T02:50:08.574135Z","end":"2026-10-19T02:50:08.778044Z","steps":["trace[832082644] 'read index received' (duration: 128.32296ms)","trace[832082644] 'applied index is now lower than readState.Index' (duration: 75.58475ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:50:08.778087Z","caller":"traceutil/trace.go:171","msg":"trace[1904057453] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"238.289651ms","start":"2026-10-19T02:50:08.539781Z","end":"2026-10-19T02:50:08.778071Z","steps":["trace[1904057453] 'process raft request' (duration: 237.925482ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:08.778115Z","caller":"traceutil/trace.go:171","msg":"trace[219730019] transaction","detail":"{read_only:false; response_revision:26662; number_of_response:1; }","duration":"135.052555ms","start":"2026-10-19T02:50:08.643057Z","end":"2026-10-19T02:50:08.77811Z","steps":["trace[219730019] 'process raft request' (duration: 134.892411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:08.778267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.105844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:50:08.778295Z","caller":"traceutil/trace.go:171","msg":"trace[137002366] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:26662; }","duration":"204.197327ms","start":"2026-10-19T02:50:08.574088Z","end":"2026-10-19T02:50:08.778285Z","steps":["trace[137002366] 'agreement among raft nodes before linearized reading' (duration: 204.076424ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:51:53.870928Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24896} {"level":"info","ts":"2026-10-19T02:51:54.039281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24896,"took":"164.315739ms","hash":4036073590,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":35954688,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-19T02:51:54.039388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4036073590,"revision":24896,"compact-revision":21670} {"level":"info","ts":"2026-10-19T02:52:46.760068Z","caller":"traceutil/trace.go:171","msg":"trace[1273435373] linearizableReadLoop","detail":"{readStateIndex:29039; appliedIndex:29038; }","duration":"144.530292ms","start":"2026-10-19T02:52:46.615495Z","end":"2026-10-19T02:52:46.760025Z","steps":["trace[1273435373] 'read index received' (duration: 144.216734ms)","trace[1273435373] 'applied index is now lower than readState.Index' (duration: 311.338µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:52:46.760244Z","caller":"traceutil/trace.go:171","msg":"trace[728172977] transaction","detail":"{read_only:false; response_revision:28130; number_of_response:1; }","duration":"145.207427ms","start":"2026-10-19T02:52:46.614937Z","end":"2026-10-19T02:52:46.760145Z","steps":["trace[728172977] 'process raft request' (duration: 144.742816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:52:46.760356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.833779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:52:46.760579Z","caller":"traceutil/trace.go:171","msg":"trace[1567841500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28130; }","duration":"145.116385ms","start":"2026-10-19T02:52:46.615442Z","end":"2026-10-19T02:52:46.760559Z","steps":["trace[1567841500] 'agreement among raft nodes before linearized reading' (duration: 144.809038ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:52:46.765408Z","caller":"traceutil/trace.go:171","msg":"trace[1351071100] transaction","detail":"{read_only:false; response_revision:28131; number_of_response:1; }","duration":"104.275648ms","start":"2026-10-19T02:52:46.661115Z","end":"2026-10-19T02:52:46.765391Z","steps":["trace[1351071100] 'process raft request' (duration: 104.095824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:52:46.765708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.821536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/manila-ks-user-mvvpm\" ","response":"range_response_count:1 size:2759"} {"level":"info","ts":"2026-10-19T02:52:46.765778Z","caller":"traceutil/trace.go:171","msg":"trace[312018416] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/manila-ks-user-mvvpm; range_end:; response_count:1; response_revision:28131; }","duration":"127.959869ms","start":"2026-10-19T02:52:46.637799Z","end":"2026-10-19T02:52:46.765759Z","steps":["trace[312018416] 'agreement among raft nodes before linearized reading' (duration: 127.728484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:52:46.765778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.546976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:52:46.765849Z","caller":"traceutil/trace.go:171","msg":"trace[908135635] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:28131; }","duration":"107.670369ms","start":"2026-10-19T02:52:46.658168Z","end":"2026-10-19T02:52:46.765838Z","steps":["trace[908135635] 'agreement among raft nodes before linearized reading' (duration: 107.571817ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:53:49.75726Z","caller":"traceutil/trace.go:171","msg":"trace[1556357620] transaction","detail":"{read_only:false; response_revision:28795; number_of_response:1; }","duration":"117.618805ms","start":"2026-10-19T02:53:49.639614Z","end":"2026-10-19T02:53:49.757233Z","steps":["trace[1556357620] 'process raft request' (duration: 117.414911ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:54:08.076532Z","caller":"traceutil/trace.go:171","msg":"trace[1168382107] transaction","detail":"{read_only:false; response_revision:29034; number_of_response:1; }","duration":"101.527363ms","start":"2026-10-19T02:54:07.974984Z","end":"2026-10-19T02:54:08.076511Z","steps":["trace[1168382107] 'process raft request' (duration: 101.474632ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:54:08.076743Z","caller":"traceutil/trace.go:171","msg":"trace[205973809] linearizableReadLoop","detail":"{readStateIndex:29967; appliedIndex:29966; }","duration":"101.792349ms","start":"2026-10-19T02:54:07.974936Z","end":"2026-10-19T02:54:08.076728Z","steps":["trace[205973809] 'read index received' (duration: 34.658881ms)","trace[205973809] 'applied index is now lower than readState.Index' (duration: 67.132538ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:54:08.076562Z","caller":"traceutil/trace.go:171","msg":"trace[220785645] transaction","detail":"{read_only:false; response_revision:29033; number_of_response:1; }","duration":"101.649766ms","start":"2026-10-19T02:54:07.974876Z","end":"2026-10-19T02:54:08.076526Z","steps":["trace[220785645] 'process raft request' (duration: 34.63298ms)","trace[220785645] 'compare' (duration: 66.821201ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:54:08.077024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.065946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-19T02:54:08.077117Z","caller":"traceutil/trace.go:171","msg":"trace[440169244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29034; }","duration":"102.183209ms","start":"2026-10-19T02:54:07.974888Z","end":"2026-10-19T02:54:08.077072Z","steps":["trace[440169244] 'agreement among raft nodes before linearized reading' (duration: 101.959233ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:54:12.548513Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"afeb1bf8b692f9e8","local-member-applied-index":30008,"local-member-snapshot-index":20007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:54:12.554919Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30008} {"level":"info","ts":"2026-10-19T02:54:12.55503Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25008} {"level":"info","ts":"2026-10-19T02:56:53.8778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27673} {"level":"info","ts":"2026-10-19T02:56:54.022013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27673,"took":"140.475004ms","hash":878700792,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":33832960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-19T02:56:54.022083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":878700792,"revision":27673,"compact-revision":24896} {"level":"info","ts":"2026-10-19T03:01:53.884946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30311} {"level":"info","ts":"2026-10-19T03:01:54.029907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30311,"took":"140.471246ms","hash":1329967055,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":30932992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-19T03:01:54.029981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1329967055,"revision":30311,"compact-revision":27673} {"level":"info","ts":"2026-10-19T03:06:53.892745Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32624} {"level":"info","ts":"2026-10-19T03:06:54.04817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32624,"took":"152.44636ms","hash":1352229479,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":30429184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-19T03:06:54.048218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1352229479,"revision":32624,"compact-revision":30311} {"level":"info","ts":"2026-10-19T03:11:53.901648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34884} {"level":"info","ts":"2026-10-19T03:11:54.040254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34884,"took":"134.75607ms","hash":18018458,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-19T03:11:54.04031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":18018458,"revision":34884,"compact-revision":32624} {"level":"info","ts":"2026-10-19T03:15:25.967815Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"afeb1bf8b692f9e8","local-member-applied-index":40009,"local-member-snapshot-index":30008,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T03:15:25.975413Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40009} {"level":"info","ts":"2026-10-19T03:15:25.975796Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35009} {"level":"info","ts":"2026-10-19T03:16:53.909439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37179} {"level":"info","ts":"2026-10-19T03:16:54.044701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37179,"took":"130.594989ms","hash":141940453,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":32251904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-19T03:16:54.044763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":141940453,"revision":37179,"compact-revision":34884} {"level":"info","ts":"2026-10-19T03:21:53.918417Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39451} {"level":"info","ts":"2026-10-19T03:21:54.062003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39451,"took":"139.301606ms","hash":4063728419,"current-db-size-bytes":60264448,"current-db-size":"60 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-19T03:21:54.06208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4063728419,"revision":39451,"compact-revision":37179}