{"level":"warn","ts":"2026-09-08T22:51:30.757926Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T22:51:30.758166Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.59: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.59:2380","--initial-cluster=instance=https://162.253.55.59:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.59:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.59:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-08T22:51:30.758278Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T22:51:30.758335Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.59:2380"]} {"level":"info","ts":"2026-09-08T22:51:30.758383Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-08T22:51:30.759341Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"]} {"level":"info","ts":"2026-09-08T22:51:30.759512Z","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.59:2380"],"listen-peer-urls":["https://162.253.55.59:2380"],"advertise-client-urls":["https://162.253.55.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.59:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-08T22:51:30.764685Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.777156ms"} {"level":"info","ts":"2026-09-08T22:51:30.771765Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f5132dc9ac390b66","cluster-id":"458d1cb56ab56fc4"} {"level":"info","ts":"2026-09-08T22:51:30.77184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T22:51:30.771857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became follower at term 0"} {"level":"info","ts":"2026-09-08T22:51:30.771869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f5132dc9ac390b66 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T22:51:30.771874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became follower at term 1"} {"level":"info","ts":"2026-09-08T22:51:30.77191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=(17659508908050942822)"} {"level":"warn","ts":"2026-09-08T22:51:30.779568Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T22:51:30.781687Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T22:51:30.783287Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-08T22:51:30.787364Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f5132dc9ac390b66","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T22:51:30.787647Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f5132dc9ac390b66","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T22:51:30.787798Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T22:51:30.788035Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T22:51:30.788055Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T22:51:30.789295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=(17659508908050942822)"} {"level":"info","ts":"2026-09-08T22:51:30.789701Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"458d1cb56ab56fc4","local-member-id":"f5132dc9ac390b66","added-peer-id":"f5132dc9ac390b66","added-peer-peer-urls":["https://162.253.55.59:2380"]} {"level":"info","ts":"2026-09-08T22:51:30.793351Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-08T22:51:30.793547Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.59:2380"} {"level":"info","ts":"2026-09-08T22:51:30.793649Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.59:2380"} {"level":"info","ts":"2026-09-08T22:51:30.794105Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f5132dc9ac390b66","initial-advertise-peer-urls":["https://162.253.55.59:2380"],"listen-peer-urls":["https://162.253.55.59:2380"],"advertise-client-urls":["https://162.253.55.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T22:51:30.794193Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T22:51:31.673122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T22:51:31.673183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T22:51:31.673221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 received MsgPreVoteResp from f5132dc9ac390b66 at term 1"} {"level":"info","ts":"2026-09-08T22:51:31.67324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became candidate at term 2"} {"level":"info","ts":"2026-09-08T22:51:31.673252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 received MsgVoteResp from f5132dc9ac390b66 at term 2"} {"level":"info","ts":"2026-09-08T22:51:31.673269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became leader at term 2"} {"level":"info","ts":"2026-09-08T22:51:31.673284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f5132dc9ac390b66 elected leader f5132dc9ac390b66 at term 2"} {"level":"info","ts":"2026-09-08T22:51:31.674493Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f5132dc9ac390b66","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.59:2379]}","request-path":"/0/members/f5132dc9ac390b66/attributes","cluster-id":"458d1cb56ab56fc4","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T22:51:31.674506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:51:31.674575Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:51:31.674548Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:51:31.674873Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T22:51:31.674985Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T22:51:31.675869Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"458d1cb56ab56fc4","local-member-id":"f5132dc9ac390b66","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:51:31.676047Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:51:31.676092Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:51:31.676978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.59:2379"} {"level":"info","ts":"2026-09-08T22:51:31.678286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-08T22:51:54.396884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.310949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:51:54.397094Z","caller":"traceutil/trace.go:171","msg":"trace[2122468809] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"380.715222ms","start":"2026-09-08T22:51:54.016344Z","end":"2026-09-08T22:51:54.397059Z","steps":["trace[2122468809] 'process raft request' (duration: 113.465851ms)","trace[2122468809] 'compare' (duration: 266.152875ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:54.397209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:51:54.016315Z","time spent":"380.834085ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:52:09.570313Z","caller":"traceutil/trace.go:171","msg":"trace[970488257] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"146.853592ms","start":"2026-09-08T22:52:09.423429Z","end":"2026-09-08T22:52:09.570283Z","steps":["trace[970488257] 'process raft request' (duration: 71.118478ms)","trace[970488257] 'compare' (duration: 75.562099ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:11.816478Z","caller":"traceutil/trace.go:171","msg":"trace[209589194] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"106.512504ms","start":"2026-09-08T22:52:11.709939Z","end":"2026-09-08T22:52:11.816452Z","steps":["trace[209589194] 'process raft request' (duration: 102.451481ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:11.816824Z","caller":"traceutil/trace.go:171","msg":"trace[364006175] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"106.765119ms","start":"2026-09-08T22:52:11.710027Z","end":"2026-09-08T22:52:11.816792Z","steps":["trace[364006175] 'process raft request' (duration: 106.519824ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:18.331135Z","caller":"traceutil/trace.go:171","msg":"trace[10339744] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"121.137467ms","start":"2026-09-08T22:52:18.209972Z","end":"2026-09-08T22:52:18.33111Z","steps":["trace[10339744] 'read index received' (duration: 121.065605ms)","trace[10339744] 'applied index is now lower than readState.Index' (duration: 70.402µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:18.331298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.28813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mlv2p\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-08T22:52:18.331348Z","caller":"traceutil/trace.go:171","msg":"trace[196683314] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mlv2p; range_end:; response_count:1; response_revision:599; }","duration":"121.357421ms","start":"2026-09-08T22:52:18.209967Z","end":"2026-09-08T22:52:18.331324Z","steps":["trace[196683314] 'agreement among raft nodes before linearized reading' (duration: 121.239459ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:18.331376Z","caller":"traceutil/trace.go:171","msg":"trace[1438566491] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"146.441612ms","start":"2026-09-08T22:52:18.184902Z","end":"2026-09-08T22:52:18.331344Z","steps":["trace[1438566491] 'process raft request' (duration: 146.037812ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:18.439053Z","caller":"traceutil/trace.go:171","msg":"trace[1362818609] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"122.807084ms","start":"2026-09-08T22:52:18.316205Z","end":"2026-09-08T22:52:18.439012Z","steps":["trace[1362818609] 'process raft request' (duration: 121.983775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.505464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.851427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:19.505745Z","caller":"traceutil/trace.go:171","msg":"trace[646456037] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"943.874106ms","start":"2026-09-08T22:52:18.56185Z","end":"2026-09-08T22:52:19.505724Z","steps":["trace[646456037] 'read index received' (duration: 107.65381ms)","trace[646456037] 'applied index is now lower than readState.Index' (duration: 836.219166ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:19.505753Z","caller":"traceutil/trace.go:171","msg":"trace[2091390114] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.156367829s","start":"2026-09-08T22:52:18.349354Z","end":"2026-09-08T22:52:19.505722Z","steps":["trace[2091390114] 'process raft request' (duration: 320.156643ms)","trace[2091390114] 'compare' (duration: 835.694103ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:19.505813Z","caller":"traceutil/trace.go:171","msg":"trace[1684104185] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"989.674927ms","start":"2026-09-08T22:52:18.516089Z","end":"2026-09-08T22:52:19.505764Z","steps":["trace[1684104185] 'process raft request' (duration: 989.566113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.505946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:18.349328Z","time spent":"1.156535893s","remote":"127.0.0.1:48186","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-09-08T22:52:19.505963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.839337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T22:52:19.505988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:18.516068Z","time spent":"989.81958ms","remote":"127.0.0.1:40834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T22:52:19.506011Z","caller":"traceutil/trace.go:171","msg":"trace[1740349250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"813.928729ms","start":"2026-09-08T22:52:18.692066Z","end":"2026-09-08T22:52:19.505994Z","steps":["trace[1740349250] 'agreement among raft nodes before linearized reading' (duration: 813.792866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.50605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:18.692043Z","time spent":"813.997391ms","remote":"127.0.0.1:47992","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-09-08T22:52:19.506146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.269614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:52:19.506262Z","caller":"traceutil/trace.go:171","msg":"trace[60182596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"944.436758ms","start":"2026-09-08T22:52:18.561791Z","end":"2026-09-08T22:52:19.506228Z","steps":["trace[60182596] 'agreement among raft nodes before linearized reading' (duration: 944.024519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.506235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.311704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-08T22:52:19.506316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:18.561767Z","time spent":"944.539809ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-08T22:52:19.506363Z","caller":"traceutil/trace.go:171","msg":"trace[1225497908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"464.505598ms","start":"2026-09-08T22:52:19.041823Z","end":"2026-09-08T22:52:19.506329Z","steps":["trace[1225497908] 'agreement among raft nodes before linearized reading' (duration: 464.080589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.506424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:19.041797Z","time spent":"464.616841ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T22:52:19.721151Z","caller":"traceutil/trace.go:171","msg":"trace[1508036390] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"208.905203ms","start":"2026-09-08T22:52:19.512206Z","end":"2026-09-08T22:52:19.721112Z","steps":["trace[1508036390] 'process raft request' (duration: 161.260319ms)","trace[1508036390] 'compare' (duration: 47.319457ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:19.721377Z","caller":"traceutil/trace.go:171","msg":"trace[686241260] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"203.91044ms","start":"2026-09-08T22:52:19.517438Z","end":"2026-09-08T22:52:19.721348Z","steps":["trace[686241260] 'read index received' (duration: 156.173113ms)","trace[686241260] 'applied index is now lower than readState.Index' (duration: 47.735417ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:19.721405Z","caller":"traceutil/trace.go:171","msg":"trace[877421646] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"208.857972ms","start":"2026-09-08T22:52:19.512509Z","end":"2026-09-08T22:52:19.721367Z","steps":["trace[877421646] 'process raft request' (duration: 208.531454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.721549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.089284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:19.721598Z","caller":"traceutil/trace.go:171","msg":"trace[1644595553] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:604; }","duration":"204.193216ms","start":"2026-09-08T22:52:19.51739Z","end":"2026-09-08T22:52:19.721583Z","steps":["trace[1644595553] 'agreement among raft nodes before linearized reading' (duration: 204.095074ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:36.356096Z","caller":"traceutil/trace.go:171","msg":"trace[663167173] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"177.020237ms","start":"2026-09-08T22:52:36.17904Z","end":"2026-09-08T22:52:36.35606Z","steps":["trace[663167173] 'process raft request' (duration: 176.758001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:07.139069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.314774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:53:07.139177Z","caller":"traceutil/trace.go:171","msg":"trace[14852214] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"377.364055ms","start":"2026-09-08T22:53:06.761794Z","end":"2026-09-08T22:53:07.139158Z","steps":["trace[14852214] 'process raft request' (duration: 144.880307ms)","trace[14852214] 'compare' (duration: 232.180042ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:53:07.139233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:53:06.761751Z","time spent":"377.454977ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:53:28.850189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.868556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:53:28.850392Z","caller":"traceutil/trace.go:171","msg":"trace[226727106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1429; }","duration":"159.150204ms","start":"2026-09-08T22:53:28.691211Z","end":"2026-09-08T22:53:28.850362Z","steps":["trace[226727106] 'range keys from in-memory index tree' (duration: 158.725194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:28.8504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.685815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T22:53:28.850533Z","caller":"traceutil/trace.go:171","msg":"trace[764539623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1429; }","duration":"169.902771ms","start":"2026-09-08T22:53:28.680599Z","end":"2026-09-08T22:53:28.850502Z","steps":["trace[764539623] 'range keys from in-memory index tree' (duration: 169.424088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:55.217669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.360801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:53:55.217904Z","caller":"traceutil/trace.go:171","msg":"trace[1497032179] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"404.052846ms","start":"2026-09-08T22:53:54.813799Z","end":"2026-09-08T22:53:55.217852Z","steps":["trace[1497032179] 'read index received' (duration: 49.218564ms)","trace[1497032179] 'applied index is now lower than readState.Index' (duration: 354.832112ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:53:55.217948Z","caller":"traceutil/trace.go:171","msg":"trace[1272322274] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"461.694871ms","start":"2026-09-08T22:53:54.756211Z","end":"2026-09-08T22:53:55.217905Z","steps":["trace[1272322274] 'process raft request' (duration: 106.90141ms)","trace[1272322274] 'compare' (duration: 354.057524ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:53:55.218193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.396323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-09-08T22:53:55.218238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:53:54.756179Z","time spent":"461.890356ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:53:55.218265Z","caller":"traceutil/trace.go:171","msg":"trace[2051779074] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1838; }","duration":"404.510446ms","start":"2026-09-08T22:53:54.813726Z","end":"2026-09-08T22:53:55.218236Z","steps":["trace[2051779074] 'agreement among raft nodes before linearized reading' (duration: 404.26307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:55.218325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:53:54.813683Z","time spent":"404.630628ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-08T22:53:55.219941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.480915ms","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-09-08T22:53:55.220046Z","caller":"traceutil/trace.go:171","msg":"trace[666655411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1839; }","duration":"203.68219ms","start":"2026-09-08T22:53:55.016337Z","end":"2026-09-08T22:53:55.220019Z","steps":["trace[666655411] 'agreement among raft nodes before linearized reading' (duration: 203.354573ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:53:55.220086Z","caller":"traceutil/trace.go:171","msg":"trace[739947273] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"232.684042ms","start":"2026-09-08T22:53:54.987386Z","end":"2026-09-08T22:53:55.22007Z","steps":["trace[739947273] 'process raft request' (duration: 232.112499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:55.220193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.672369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T22:53:55.220299Z","caller":"traceutil/trace.go:171","msg":"trace[915411885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1839; }","duration":"118.823323ms","start":"2026-09-08T22:53:55.101458Z","end":"2026-09-08T22:53:55.220281Z","steps":["trace[915411885] 'agreement among raft nodes before linearized reading' (duration: 118.614078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:55.220299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.596069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.59\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-08T22:53:55.220374Z","caller":"traceutil/trace.go:171","msg":"trace[1085673961] range","detail":"{range_begin:/registry/masterleases/162.253.55.59; range_end:; response_count:1; response_revision:1839; }","duration":"147.723382ms","start":"2026-09-08T22:53:55.072632Z","end":"2026-09-08T22:53:55.220355Z","steps":["trace[1085673961] 'agreement among raft nodes before linearized reading' (duration: 147.509628ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:53:55.413724Z","caller":"traceutil/trace.go:171","msg":"trace[892352281] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"119.235832ms","start":"2026-09-08T22:53:55.294463Z","end":"2026-09-08T22:53:55.413699Z","steps":["trace[892352281] 'read index received' (duration: 119.193921ms)","trace[892352281] 'applied index is now lower than readState.Index' (duration: 40.551µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:53:55.413843Z","caller":"traceutil/trace.go:171","msg":"trace[482566805] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"119.753785ms","start":"2026-09-08T22:53:55.294036Z","end":"2026-09-08T22:53:55.413789Z","steps":["trace[482566805] 'process raft request' (duration: 119.525539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:55.413972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.467039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T22:53:55.414072Z","caller":"traceutil/trace.go:171","msg":"trace[2102569933] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1843; }","duration":"119.631492ms","start":"2026-09-08T22:53:55.294425Z","end":"2026-09-08T22:53:55.414057Z","steps":["trace[2102569933] 'agreement among raft nodes before linearized reading' (duration: 119.380506ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:53:55.444012Z","caller":"traceutil/trace.go:171","msg":"trace[1171733865] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"119.371516ms","start":"2026-09-08T22:53:55.324607Z","end":"2026-09-08T22:53:55.443979Z","steps":["trace[1171733865] 'process raft request' (duration: 119.10225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:55.444045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.188839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:53:55.444107Z","caller":"traceutil/trace.go:171","msg":"trace[273024180] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1844; }","duration":"104.346072ms","start":"2026-09-08T22:53:55.339745Z","end":"2026-09-08T22:53:55.444091Z","steps":["trace[273024180] 'agreement among raft nodes before linearized reading' (duration: 104.145758ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:08.626362Z","caller":"traceutil/trace.go:171","msg":"trace[1855887474] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"154.759887ms","start":"2026-09-08T22:54:08.471562Z","end":"2026-09-08T22:54:08.626322Z","steps":["trace[1855887474] 'process raft request' (duration: 154.545312ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:38.842715Z","caller":"traceutil/trace.go:171","msg":"trace[1096863911] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"111.709062ms","start":"2026-09-08T22:54:38.730973Z","end":"2026-09-08T22:54:38.842682Z","steps":["trace[1096863911] 'process raft request' (duration: 111.477536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:54:44.849826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.324443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:54:44.849976Z","caller":"traceutil/trace.go:171","msg":"trace[2118110412] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2245; }","duration":"239.035687ms","start":"2026-09-08T22:54:44.610922Z","end":"2026-09-08T22:54:44.849958Z","steps":["trace[2118110412] 'read index received' (duration: 138.417376ms)","trace[2118110412] 'applied index is now lower than readState.Index' (duration: 100.617051ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:54:44.85004Z","caller":"traceutil/trace.go:171","msg":"trace[1031962636] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"250.234301ms","start":"2026-09-08T22:54:44.59975Z","end":"2026-09-08T22:54:44.849984Z","steps":["trace[1031962636] 'process raft request' (duration: 149.671332ms)","trace[1031962636] 'compare' (duration: 100.218851ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:54:44.85008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.934179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T22:54:44.850223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.269902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-08T22:54:44.850264Z","caller":"traceutil/trace.go:171","msg":"trace[300758418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2166; }","duration":"158.149344ms","start":"2026-09-08T22:54:44.692091Z","end":"2026-09-08T22:54:44.85024Z","steps":["trace[300758418] 'agreement among raft nodes before linearized reading' (duration: 157.95781ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:44.850322Z","caller":"traceutil/trace.go:171","msg":"trace[421071222] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2166; }","duration":"239.415095ms","start":"2026-09-08T22:54:44.610879Z","end":"2026-09-08T22:54:44.850294Z","steps":["trace[421071222] 'agreement among raft nodes before linearized reading' (duration: 239.141419ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:11.25437Z","caller":"traceutil/trace.go:171","msg":"trace[1711994955] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"100.604714ms","start":"2026-09-08T22:56:11.153738Z","end":"2026-09-08T22:56:11.254343Z","steps":["trace[1711994955] 'process raft request' (duration: 100.444121ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:11.49979Z","caller":"traceutil/trace.go:171","msg":"trace[1648029898] linearizableReadLoop","detail":"{readStateIndex:2932; appliedIndex:2931; }","duration":"194.756671ms","start":"2026-09-08T22:56:11.305009Z","end":"2026-09-08T22:56:11.499766Z","steps":["trace[1648029898] 'read index received' (duration: 194.440484ms)","trace[1648029898] 'applied index is now lower than readState.Index' (duration: 314.837µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:56:11.49992Z","caller":"traceutil/trace.go:171","msg":"trace[1471757311] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"202.130419ms","start":"2026-09-08T22:56:11.297746Z","end":"2026-09-08T22:56:11.499877Z","steps":["trace[1471757311] 'process raft request' (duration: 201.823332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:56:11.500003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.971216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T22:56:11.500038Z","caller":"traceutil/trace.go:171","msg":"trace[1766994636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2826; }","duration":"195.070368ms","start":"2026-09-08T22:56:11.304956Z","end":"2026-09-08T22:56:11.500026Z","steps":["trace[1766994636] 'agreement among raft nodes before linearized reading' (duration: 194.922345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:28.912323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.858903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:59:28.912441Z","caller":"traceutil/trace.go:171","msg":"trace[787988052] linearizableReadLoop","detail":"{readStateIndex:4218; appliedIndex:4217; }","duration":"311.245835ms","start":"2026-09-08T22:59:28.601176Z","end":"2026-09-08T22:59:28.912422Z","steps":["trace[787988052] 'read index received' (duration: 142.351191ms)","trace[787988052] 'applied index is now lower than readState.Index' (duration: 168.893184ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:59:28.912494Z","caller":"traceutil/trace.go:171","msg":"trace[498244634] transaction","detail":"{read_only:false; response_revision:4066; number_of_response:1; }","duration":"331.533195ms","start":"2026-09-08T22:59:28.580927Z","end":"2026-09-08T22:59:28.91246Z","steps":["trace[498244634] 'process raft request' (duration: 162.417046ms)","trace[498244634] 'compare' (duration: 168.73229ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:59:28.912644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.46144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-09-08T22:59:28.912681Z","caller":"traceutil/trace.go:171","msg":"trace[1002191861] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4066; }","duration":"311.519441ms","start":"2026-09-08T22:59:28.601152Z","end":"2026-09-08T22:59:28.912671Z","steps":["trace[1002191861] 'agreement among raft nodes before linearized reading' (duration: 311.359888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:28.912683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:59:28.5809Z","time spent":"331.678358ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:59:28.912743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:59:28.601141Z","time spent":"311.593114ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12232,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-08T22:59:28.926581Z","caller":"traceutil/trace.go:171","msg":"trace[698224456] transaction","detail":"{read_only:false; response_revision:4067; number_of_response:1; }","duration":"237.279246ms","start":"2026-09-08T22:59:28.689246Z","end":"2026-09-08T22:59:28.926526Z","steps":["trace[698224456] 'process raft request' (duration: 237.157273ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:59:28.935017Z","caller":"traceutil/trace.go:171","msg":"trace[932805] transaction","detail":"{read_only:false; response_revision:4068; number_of_response:1; }","duration":"110.515879ms","start":"2026-09-08T22:59:28.824465Z","end":"2026-09-08T22:59:28.934981Z","steps":["trace[932805] 'process raft request' (duration: 110.273673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:28.935154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.779064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:59:28.935268Z","caller":"traceutil/trace.go:171","msg":"trace[1657923197] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4068; }","duration":"310.929288ms","start":"2026-09-08T22:59:28.624305Z","end":"2026-09-08T22:59:28.935235Z","steps":["trace[1657923197] 'agreement among raft nodes before linearized reading' (duration: 310.691252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:28.935326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:59:28.62429Z","time spent":"311.024711ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-09-08T22:59:28.935631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.705892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:59:28.935716Z","caller":"traceutil/trace.go:171","msg":"trace[792724038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4068; }","duration":"243.793024ms","start":"2026-09-08T22:59:28.691882Z","end":"2026-09-08T22:59:28.935675Z","steps":["trace[792724038] 'agreement among raft nodes before linearized reading' (duration: 243.685782ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:00.615823Z","caller":"traceutil/trace.go:171","msg":"trace[735734239] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"111.695696ms","start":"2026-09-08T23:01:00.504086Z","end":"2026-09-08T23:01:00.615782Z","steps":["trace[735734239] 'process raft request' (duration: 110.98788ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:03.437192Z","caller":"traceutil/trace.go:171","msg":"trace[1222863162] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"104.726727ms","start":"2026-09-08T23:01:03.332446Z","end":"2026-09-08T23:01:03.437172Z","steps":["trace[1222863162] 'process raft request' (duration: 104.604934ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:05.932671Z","caller":"traceutil/trace.go:171","msg":"trace[1031831863] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"240.07539ms","start":"2026-09-08T23:01:05.692534Z","end":"2026-09-08T23:01:05.932609Z","steps":["trace[1031831863] 'process raft request' (duration: 239.784353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:05.932837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.574548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T23:01:05.932906Z","caller":"traceutil/trace.go:171","msg":"trace[703789326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5275; }","duration":"239.715961ms","start":"2026-09-08T23:01:05.693175Z","end":"2026-09-08T23:01:05.932891Z","steps":["trace[703789326] 'agreement among raft nodes before linearized reading' (duration: 239.517497ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:05.932651Z","caller":"traceutil/trace.go:171","msg":"trace[498387218] linearizableReadLoop","detail":"{readStateIndex:5474; appliedIndex:5473; }","duration":"239.349443ms","start":"2026-09-08T23:01:05.693214Z","end":"2026-09-08T23:01:05.932563Z","steps":["trace[498387218] 'read index received' (duration: 239.191859ms)","trace[498387218] 'applied index is now lower than readState.Index' (duration: 155.524µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:01:05.962659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.009754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-08T23:01:05.962654Z","caller":"traceutil/trace.go:171","msg":"trace[1060457592] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"231.983896ms","start":"2026-09-08T23:01:05.7306Z","end":"2026-09-08T23:01:05.962584Z","steps":["trace[1060457592] 'process raft request' (duration: 231.576917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:05.962659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.08727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-69wrl\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-08T23:01:05.96275Z","caller":"traceutil/trace.go:171","msg":"trace[2117220759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5276; }","duration":"146.194638ms","start":"2026-09-08T23:01:05.816535Z","end":"2026-09-08T23:01:05.96273Z","steps":["trace[2117220759] 'agreement among raft nodes before linearized reading' (duration: 145.894312ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:05.962777Z","caller":"traceutil/trace.go:171","msg":"trace[518028647] range","detail":"{range_begin:/registry/pods/openstack/keepalived-69wrl; range_end:; response_count:1; response_revision:5276; }","duration":"140.279014ms","start":"2026-09-08T23:01:05.822483Z","end":"2026-09-08T23:01:05.962762Z","steps":["trace[518028647] 'agreement among raft nodes before linearized reading' (duration: 139.917076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:05.962861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.804428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-08T23:01:05.963042Z","caller":"traceutil/trace.go:171","msg":"trace[529765959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5276; }","duration":"149.055153ms","start":"2026-09-08T23:01:05.813959Z","end":"2026-09-08T23:01:05.963014Z","steps":["trace[529765959] 'agreement among raft nodes before linearized reading' (duration: 148.554572ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:06.058023Z","caller":"traceutil/trace.go:171","msg":"trace[580147003] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"116.836892ms","start":"2026-09-08T23:01:05.941151Z","end":"2026-09-08T23:01:06.057988Z","steps":["trace[580147003] 'process raft request' (duration: 92.895998ms)","trace[580147003] 'compare' (duration: 23.702059ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:01:06.058552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.249379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T23:01:06.058612Z","caller":"traceutil/trace.go:171","msg":"trace[564664791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5277; }","duration":"124.324403ms","start":"2026-09-08T23:01:05.934272Z","end":"2026-09-08T23:01:06.058596Z","steps":["trace[564664791] 'agreement among raft nodes before linearized reading' (duration: 124.205509ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:06.071043Z","caller":"traceutil/trace.go:171","msg":"trace[682503836] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"102.536227ms","start":"2026-09-08T23:01:05.968474Z","end":"2026-09-08T23:01:06.07101Z","steps":["trace[682503836] 'process raft request' (duration: 102.22712ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:06.07106Z","caller":"traceutil/trace.go:171","msg":"trace[1383142585] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"102.269891ms","start":"2026-09-08T23:01:05.968755Z","end":"2026-09-08T23:01:06.071025Z","steps":["trace[1383142585] 'process raft request' (duration: 102.126998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:06.071469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.330669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62865"} {"level":"info","ts":"2026-09-08T23:01:06.071562Z","caller":"traceutil/trace.go:171","msg":"trace[367935078] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5280; }","duration":"104.465442ms","start":"2026-09-08T23:01:05.967077Z","end":"2026-09-08T23:01:06.071542Z","steps":["trace[367935078] 'agreement among raft nodes before linearized reading' (duration: 104.136804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:07.388802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.08801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T23:01:07.388904Z","caller":"traceutil/trace.go:171","msg":"trace[2090945062] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5310; }","duration":"110.288634ms","start":"2026-09-08T23:01:07.278595Z","end":"2026-09-08T23:01:07.388883Z","steps":["trace[2090945062] 'count revisions from in-memory index tree' (duration: 109.973287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:07.38896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.0086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786901"} {"level":"info","ts":"2026-09-08T23:01:07.389067Z","caller":"traceutil/trace.go:171","msg":"trace[1641010497] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5310; }","duration":"159.192484ms","start":"2026-09-08T23:01:07.229847Z","end":"2026-09-08T23:01:07.389039Z","steps":["trace[1641010497] 'range keys from in-memory index tree' (duration: 158.480718ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:14.43738Z","caller":"traceutil/trace.go:171","msg":"trace[426795705] linearizableReadLoop","detail":"{readStateIndex:5623; appliedIndex:5622; }","duration":"219.881201ms","start":"2026-09-08T23:01:14.217467Z","end":"2026-09-08T23:01:14.437349Z","steps":["trace[426795705] 'read index received' (duration: 204.467921ms)","trace[426795705] 'applied index is now lower than readState.Index' (duration: 15.41151ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:01:14.437417Z","caller":"traceutil/trace.go:171","msg":"trace[923792500] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"220.977356ms","start":"2026-09-08T23:01:14.216406Z","end":"2026-09-08T23:01:14.437383Z","steps":["trace[923792500] 'process raft request' (duration: 205.593757ms)","trace[923792500] 'compare' (duration: 15.013281ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:01:14.437909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.298764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-08T23:01:14.437967Z","caller":"traceutil/trace.go:171","msg":"trace[79053617] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5419; }","duration":"188.406966ms","start":"2026-09-08T23:01:14.249543Z","end":"2026-09-08T23:01:14.43795Z","steps":["trace[79053617] 'agreement among raft nodes before linearized reading' (duration: 188.000928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:14.438035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.504675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-08T23:01:14.438151Z","caller":"traceutil/trace.go:171","msg":"trace[1150474976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5419; }","duration":"220.705749ms","start":"2026-09-08T23:01:14.217419Z","end":"2026-09-08T23:01:14.438125Z","steps":["trace[1150474976] 'agreement among raft nodes before linearized reading' (duration: 220.089246ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:31.716706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"info","ts":"2026-09-08T23:01:31.796762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"77.415677ms","hash":1393128738,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":31756288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-08T23:01:31.79684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1393128738,"revision":2939,"compact-revision":-1} {"level":"info","ts":"2026-09-08T23:01:33.529204Z","caller":"traceutil/trace.go:171","msg":"trace[1600016681] transaction","detail":"{read_only:false; response_revision:5749; number_of_response:1; }","duration":"109.537166ms","start":"2026-09-08T23:01:33.41964Z","end":"2026-09-08T23:01:33.529177Z","steps":["trace[1600016681] 'process raft request' (duration: 109.368842ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:33.698087Z","caller":"traceutil/trace.go:171","msg":"trace[1939964480] transaction","detail":"{read_only:false; response_revision:5751; number_of_response:1; }","duration":"135.286902ms","start":"2026-09-08T23:01:33.562568Z","end":"2026-09-08T23:01:33.697855Z","steps":["trace[1939964480] 'process raft request' (duration: 134.893973ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:35.738194Z","caller":"traceutil/trace.go:171","msg":"trace[510649333] linearizableReadLoop","detail":"{readStateIndex:6004; appliedIndex:6003; }","duration":"215.671286ms","start":"2026-09-08T23:01:35.522496Z","end":"2026-09-08T23:01:35.738167Z","steps":["trace[510649333] 'read index received' (duration: 215.520423ms)","trace[510649333] 'applied index is now lower than readState.Index' (duration: 149.343µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:01:35.73844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.910391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-08T23:01:35.738389Z","caller":"traceutil/trace.go:171","msg":"trace[1182536869] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"246.824793ms","start":"2026-09-08T23:01:35.491508Z","end":"2026-09-08T23:01:35.738333Z","steps":["trace[1182536869] 'process raft request' (duration: 246.471665ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:35.738489Z","caller":"traceutil/trace.go:171","msg":"trace[551622399] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5791; }","duration":"216.010483ms","start":"2026-09-08T23:01:35.522464Z","end":"2026-09-08T23:01:35.738474Z","steps":["trace[551622399] 'agreement among raft nodes before linearized reading' (duration: 215.835399ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:35.739171Z","caller":"traceutil/trace.go:171","msg":"trace[1039533099] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"214.356046ms","start":"2026-09-08T23:01:35.524795Z","end":"2026-09-08T23:01:35.739151Z","steps":["trace[1039533099] 'process raft request' (duration: 214.192822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:35.739347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.722129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-08T23:01:35.73943Z","caller":"traceutil/trace.go:171","msg":"trace[528166669] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5794; }","duration":"216.857382ms","start":"2026-09-08T23:01:35.522554Z","end":"2026-09-08T23:01:35.739412Z","steps":["trace[528166669] 'agreement among raft nodes before linearized reading' (duration: 216.639998ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:35.739484Z","caller":"traceutil/trace.go:171","msg":"trace[1288988017] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"174.296946ms","start":"2026-09-08T23:01:35.565162Z","end":"2026-09-08T23:01:35.739459Z","steps":["trace[1288988017] 'process raft request' (duration: 173.933068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:01:35.739709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.555693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2265"} {"level":"info","ts":"2026-09-08T23:01:35.739758Z","caller":"traceutil/trace.go:171","msg":"trace[1694183916] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5794; }","duration":"211.618774ms","start":"2026-09-08T23:01:35.528125Z","end":"2026-09-08T23:01:35.739743Z","steps":["trace[1694183916] 'agreement among raft nodes before linearized reading' (duration: 211.475451ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:02:06.948035Z","caller":"traceutil/trace.go:171","msg":"trace[1528992116] linearizableReadLoop","detail":"{readStateIndex:6272; appliedIndex:6271; }","duration":"415.840479ms","start":"2026-09-08T23:02:06.532168Z","end":"2026-09-08T23:02:06.948008Z","steps":["trace[1528992116] 'read index received' (duration: 415.763948ms)","trace[1528992116] 'applied index is now lower than readState.Index' (duration: 75.671µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:02:06.948546Z","caller":"traceutil/trace.go:171","msg":"trace[1047101896] transaction","detail":"{read_only:false; response_revision:6050; number_of_response:1; }","duration":"417.055328ms","start":"2026-09-08T23:02:06.531466Z","end":"2026-09-08T23:02:06.948521Z","steps":["trace[1047101896] 'process raft request' (duration: 416.401913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:02:06.948715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.501404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81807"} {"level":"info","ts":"2026-09-08T23:02:06.948844Z","caller":"traceutil/trace.go:171","msg":"trace[1547965723] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:6050; }","duration":"416.678138ms","start":"2026-09-08T23:02:06.532138Z","end":"2026-09-08T23:02:06.948816Z","steps":["trace[1547965723] 'agreement among raft nodes before linearized reading' (duration: 416.215708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:02:06.948922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:02:06.532127Z","time spent":"416.77003ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":81830,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-09-08T23:02:06.949266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:02:06.531448Z","time spent":"417.687991ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T23:02:06.949277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.135918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T23:02:06.949292Z","caller":"traceutil/trace.go:171","msg":"trace[1357515326] transaction","detail":"{read_only:false; response_revision:6051; number_of_response:1; }","duration":"414.844038ms","start":"2026-09-08T23:02:06.534418Z","end":"2026-09-08T23:02:06.949262Z","steps":["trace[1357515326] 'process raft request' (duration: 414.597232ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:02:06.949352Z","caller":"traceutil/trace.go:171","msg":"trace[2140205391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6051; }","duration":"257.266801ms","start":"2026-09-08T23:02:06.692069Z","end":"2026-09-08T23:02:06.949336Z","steps":["trace[2140205391] 'agreement among raft nodes before linearized reading' (duration: 257.128318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:02:06.949444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:02:06.534401Z","time spent":"414.96502ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T23:02:25.9681Z","caller":"traceutil/trace.go:171","msg":"trace[1895810340] transaction","detail":"{read_only:false; response_revision:6259; number_of_response:1; }","duration":"112.560675ms","start":"2026-09-08T23:02:25.855498Z","end":"2026-09-08T23:02:25.968059Z","steps":["trace[1895810340] 'process raft request' (duration: 112.237948ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:06:31.725373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5687} {"level":"info","ts":"2026-09-08T23:06:31.817104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5687,"took":"89.705904ms","hash":3210287539,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":26447872,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-08T23:06:31.817189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3210287539,"revision":5687,"compact-revision":2939} {"level":"warn","ts":"2026-09-08T23:07:41.733735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.115871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-08T23:07:41.733831Z","caller":"traceutil/trace.go:171","msg":"trace[424762762] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8588; }","duration":"490.249725ms","start":"2026-09-08T23:07:41.24356Z","end":"2026-09-08T23:07:41.73381Z","steps":["trace[424762762] 'range keys from in-memory index tree' (duration: 489.948838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:07:41.733872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:07:41.243548Z","time spent":"490.314666ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-08T23:07:41.734362Z","caller":"traceutil/trace.go:171","msg":"trace[1636220528] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"433.365431ms","start":"2026-09-08T23:07:41.300969Z","end":"2026-09-08T23:07:41.734335Z","steps":["trace[1636220528] 'process raft request' (duration: 433.155797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:07:41.734592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:07:41.300928Z","time spent":"433.551446ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T23:07:41.735072Z","caller":"traceutil/trace.go:171","msg":"trace[1242750865] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"430.473647ms","start":"2026-09-08T23:07:41.304551Z","end":"2026-09-08T23:07:41.735024Z","steps":["trace[1242750865] 'process raft request' (duration: 430.279041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:07:41.735263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:07:41.304531Z","time spent":"430.63501ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T23:07:53.202692Z","caller":"traceutil/trace.go:171","msg":"trace[1961738174] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"237.143769ms","start":"2026-09-08T23:07:52.965517Z","end":"2026-09-08T23:07:53.202661Z","steps":["trace[1961738174] 'process raft request' (duration: 236.952215ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:07:53.322975Z","caller":"traceutil/trace.go:171","msg":"trace[1075334350] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"116.18626ms","start":"2026-09-08T23:07:53.20676Z","end":"2026-09-08T23:07:53.322946Z","steps":["trace[1075334350] 'process raft request' (duration: 77.290459ms)","trace[1075334350] 'compare' (duration: 38.766878ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:07:53.359678Z","caller":"traceutil/trace.go:171","msg":"trace[1444285413] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"152.430161ms","start":"2026-09-08T23:07:53.207195Z","end":"2026-09-08T23:07:53.359625Z","steps":["trace[1444285413] 'process raft request' (duration: 152.076853ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:07:53.360287Z","caller":"traceutil/trace.go:171","msg":"trace[1682775273] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"153.011054ms","start":"2026-09-08T23:07:53.207254Z","end":"2026-09-08T23:07:53.360265Z","steps":["trace[1682775273] 'process raft request' (duration: 152.227875ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:07:54.051424Z","caller":"traceutil/trace.go:171","msg":"trace[209586169] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"278.285832ms","start":"2026-09-08T23:07:53.773112Z","end":"2026-09-08T23:07:54.051398Z","steps":["trace[209586169] 'process raft request' (duration: 278.087997ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:07:54.100008Z","caller":"traceutil/trace.go:171","msg":"trace[1595914562] linearizableReadLoop","detail":"{readStateIndex:8958; appliedIndex:8956; }","duration":"255.620183ms","start":"2026-09-08T23:07:53.844361Z","end":"2026-09-08T23:07:54.099981Z","steps":["trace[1595914562] 'read index received' (duration: 206.847526ms)","trace[1595914562] 'applied index is now lower than readState.Index' (duration: 48.771557ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:07:54.100079Z","caller":"traceutil/trace.go:171","msg":"trace[128050515] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"318.046972ms","start":"2026-09-08T23:07:53.781997Z","end":"2026-09-08T23:07:54.100044Z","steps":["trace[128050515] 'process raft request' (duration: 317.806446ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:07:54.100272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:07:53.781982Z","time spent":"318.172864ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T23:07:54.100387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.869638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T23:07:54.100612Z","caller":"traceutil/trace.go:171","msg":"trace[158689518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8652; }","duration":"256.155905ms","start":"2026-09-08T23:07:53.844279Z","end":"2026-09-08T23:07:54.100435Z","steps":["trace[158689518] 'agreement among raft nodes before linearized reading' (duration: 255.802687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:07:54.794485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.33628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T23:07:54.794579Z","caller":"traceutil/trace.go:171","msg":"trace[1589882548] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8655; }","duration":"118.524424ms","start":"2026-09-08T23:07:54.67603Z","end":"2026-09-08T23:07:54.794555Z","steps":["trace[1589882548] 'count revisions from in-memory index tree' (duration: 118.206757ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:23.08584Z","caller":"traceutil/trace.go:171","msg":"trace[846084719] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"492.578858ms","start":"2026-09-08T23:08:22.593238Z","end":"2026-09-08T23:08:23.085817Z","steps":["trace[846084719] 'process raft request' (duration: 492.189939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:08:23.086047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:08:22.593218Z","time spent":"492.711861ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T23:08:23.086404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.017105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-09-08T23:08:23.086599Z","caller":"traceutil/trace.go:171","msg":"trace[545099153] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8858; }","duration":"485.25493ms","start":"2026-09-08T23:08:22.601316Z","end":"2026-09-08T23:08:23.086571Z","steps":["trace[545099153] 'agreement among raft nodes before linearized reading' (duration: 484.80789ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:23.085731Z","caller":"traceutil/trace.go:171","msg":"trace[401823524] linearizableReadLoop","detail":"{readStateIndex:9172; appliedIndex:9171; }","duration":"484.357169ms","start":"2026-09-08T23:08:22.601341Z","end":"2026-09-08T23:08:23.085698Z","steps":["trace[401823524] 'read index received' (duration: 484.068703ms)","trace[401823524] 'applied index is now lower than readState.Index' (duration: 286.876µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:08:23.086704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:08:22.601305Z","time spent":"485.384723ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12232,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-08T23:08:23.087999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.166436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-08T23:08:23.088056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.127145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T23:08:23.088088Z","caller":"traceutil/trace.go:171","msg":"trace[1396532464] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"391.666378ms","start":"2026-09-08T23:08:22.696403Z","end":"2026-09-08T23:08:23.088069Z","steps":["trace[1396532464] 'process raft request' (duration: 391.336389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:08:23.088212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.023181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T23:08:23.088359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.004802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T23:08:23.088418Z","caller":"traceutil/trace.go:171","msg":"trace[1714301334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8859; }","duration":"394.216617ms","start":"2026-09-08T23:08:22.694069Z","end":"2026-09-08T23:08:23.088285Z","steps":["trace[1714301334] 'agreement among raft nodes before linearized reading' (duration: 394.032993ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:23.088529Z","caller":"traceutil/trace.go:171","msg":"trace[614051636] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8859; }","duration":"211.144135ms","start":"2026-09-08T23:08:22.877323Z","end":"2026-09-08T23:08:23.088467Z","steps":["trace[614051636] 'agreement among raft nodes before linearized reading' (duration: 210.699855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:08:23.088426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:08:22.696371Z","time spent":"391.892522ms","remote":"127.0.0.1:48258","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-09-08T23:08:23.088533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:08:22.694048Z","time spent":"394.440161ms","remote":"127.0.0.1:47996","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-09-08T23:08:23.088094Z","caller":"traceutil/trace.go:171","msg":"trace[1573285612] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8859; }","duration":"450.341521ms","start":"2026-09-08T23:08:22.637731Z","end":"2026-09-08T23:08:23.088073Z","steps":["trace[1573285612] 'agreement among raft nodes before linearized reading' (duration: 450.147296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:08:23.088832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:08:22.637699Z","time spent":"451.112808ms","remote":"127.0.0.1:48466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":11,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-09-08T23:08:23.088145Z","caller":"traceutil/trace.go:171","msg":"trace[631275127] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8859; }","duration":"111.282789ms","start":"2026-09-08T23:08:22.976833Z","end":"2026-09-08T23:08:23.088116Z","steps":["trace[631275127] 'agreement among raft nodes before linearized reading' (duration: 111.095224ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:29.556968Z","caller":"traceutil/trace.go:171","msg":"trace[1973454282] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"365.874838ms","start":"2026-09-08T23:08:29.191057Z","end":"2026-09-08T23:08:29.556931Z","steps":["trace[1973454282] 'process raft request' (duration: 299.203681ms)","trace[1973454282] 'compare' (duration: 66.478193ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:08:29.557143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:08:29.191035Z","time spent":"366.017321ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T23:08:36.989287Z","caller":"traceutil/trace.go:171","msg":"trace[243226190] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8974; }","duration":"211.608375ms","start":"2026-09-08T23:08:36.777651Z","end":"2026-09-08T23:08:36.98926Z","steps":["trace[243226190] 'process raft request' (duration: 194.510863ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:36.989301Z","caller":"traceutil/trace.go:171","msg":"trace[1944424869] linearizableReadLoop","detail":"{readStateIndex:9294; appliedIndex:9293; }","duration":"117.531661ms","start":"2026-09-08T23:08:36.871743Z","end":"2026-09-08T23:08:36.989275Z","steps":["trace[1944424869] 'read index received' (duration: 100.47027ms)","trace[1944424869] 'applied index is now lower than readState.Index' (duration: 17.060311ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:08:36.989598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.833128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-fnv25\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-09-08T23:08:36.989668Z","caller":"traceutil/trace.go:171","msg":"trace[540877195] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-fnv25; range_end:; response_count:1; response_revision:8974; }","duration":"117.969031ms","start":"2026-09-08T23:08:36.871682Z","end":"2026-09-08T23:08:36.989651Z","steps":["trace[540877195] 'agreement among raft nodes before linearized reading' (duration: 117.694815ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:37.304294Z","caller":"traceutil/trace.go:171","msg":"trace[2047601980] transaction","detail":"{read_only:false; response_revision:8976; number_of_response:1; }","duration":"138.140893ms","start":"2026-09-08T23:08:37.166117Z","end":"2026-09-08T23:08:37.304258Z","steps":["trace[2047601980] 'process raft request' (duration: 137.908888ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:37.477761Z","caller":"traceutil/trace.go:171","msg":"trace[292697694] transaction","detail":"{read_only:false; response_revision:8977; number_of_response:1; }","duration":"258.208212ms","start":"2026-09-08T23:08:37.219518Z","end":"2026-09-08T23:08:37.477726Z","steps":["trace[292697694] 'process raft request' (duration: 224.890269ms)","trace[292697694] 'compare' (duration: 33.18951ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:08:37.755709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.050013ms","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-09-08T23:08:37.755759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.442189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T23:08:37.755802Z","caller":"traceutil/trace.go:171","msg":"trace[1591252847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8977; }","duration":"132.210146ms","start":"2026-09-08T23:08:37.623568Z","end":"2026-09-08T23:08:37.755779Z","steps":["trace[1591252847] 'range keys from in-memory index tree' (duration: 131.803338ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:37.755839Z","caller":"traceutil/trace.go:171","msg":"trace[1469806383] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8977; }","duration":"107.612703ms","start":"2026-09-08T23:08:37.648208Z","end":"2026-09-08T23:08:37.75582Z","steps":["trace[1469806383] 'count revisions from in-memory index tree' (duration: 107.359028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:08:37.756159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.719835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-08T23:08:37.756219Z","caller":"traceutil/trace.go:171","msg":"trace[2147341531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8977; }","duration":"158.835847ms","start":"2026-09-08T23:08:37.597364Z","end":"2026-09-08T23:08:37.7562Z","steps":["trace[2147341531] 'range keys from in-memory index tree' (duration: 158.569151ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:37.962318Z","caller":"traceutil/trace.go:171","msg":"trace[1946212629] transaction","detail":"{read_only:false; response_revision:8979; number_of_response:1; }","duration":"201.771199ms","start":"2026-09-08T23:08:37.760517Z","end":"2026-09-08T23:08:37.962288Z","steps":["trace[1946212629] 'process raft request' (duration: 201.587635ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:37.962586Z","caller":"traceutil/trace.go:171","msg":"trace[40153614] transaction","detail":"{read_only:false; response_revision:8980; number_of_response:1; }","duration":"201.998225ms","start":"2026-09-08T23:08:37.760578Z","end":"2026-09-08T23:08:37.962576Z","steps":["trace[40153614] 'process raft request' (duration: 201.673997ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:37.962774Z","caller":"traceutil/trace.go:171","msg":"trace[242475728] transaction","detail":"{read_only:false; response_revision:8981; number_of_response:1; }","duration":"201.911373ms","start":"2026-09-08T23:08:37.760851Z","end":"2026-09-08T23:08:37.962762Z","steps":["trace[242475728] 'process raft request' (duration: 201.688798ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:37.963187Z","caller":"traceutil/trace.go:171","msg":"trace[1831914396] linearizableReadLoop","detail":"{readStateIndex:9303; appliedIndex:9298; }","duration":"119.154699ms","start":"2026-09-08T23:08:37.843991Z","end":"2026-09-08T23:08:37.963146Z","steps":["trace[1831914396] 'read index received' (duration: 118.053244ms)","trace[1831914396] 'applied index is now lower than readState.Index' (duration: 1.099715ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:08:37.963394Z","caller":"traceutil/trace.go:171","msg":"trace[1533898876] transaction","detail":"{read_only:false; response_revision:8983; number_of_response:1; }","duration":"200.642524ms","start":"2026-09-08T23:08:37.762725Z","end":"2026-09-08T23:08:37.963368Z","steps":["trace[1533898876] 'process raft request' (duration: 200.277695ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:37.963217Z","caller":"traceutil/trace.go:171","msg":"trace[1928929492] transaction","detail":"{read_only:false; response_revision:8982; number_of_response:1; }","duration":"201.478193ms","start":"2026-09-08T23:08:37.761714Z","end":"2026-09-08T23:08:37.963192Z","steps":["trace[1928929492] 'process raft request' (duration: 200.974921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:08:37.963869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.111677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228446"} {"level":"info","ts":"2026-09-08T23:08:37.963932Z","caller":"traceutil/trace.go:171","msg":"trace[1393483749] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8983; }","duration":"202.289631ms","start":"2026-09-08T23:08:37.761623Z","end":"2026-09-08T23:08:37.963913Z","steps":["trace[1393483749] 'agreement among raft nodes before linearized reading' (duration: 201.686918ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:08:58.145245Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002512.wal"} {"level":"info","ts":"2026-09-08T23:09:54.354319Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f5132dc9ac390b66","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T23:09:54.360964Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-08T23:09:54.361104Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-08T23:09:57.069643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.891843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-08T23:09:57.069781Z","caller":"traceutil/trace.go:171","msg":"trace[1445627768] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:9681; }","duration":"133.068946ms","start":"2026-09-08T23:09:56.936662Z","end":"2026-09-08T23:09:57.069731Z","steps":["trace[1445627768] 'range keys from in-memory index tree' (duration: 132.660656ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:11:31.732477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7987} {"level":"info","ts":"2026-09-08T23:11:31.801142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7987,"took":"66.557243ms","hash":2809426436,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":29048832,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T23:11:31.801193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2809426436,"revision":7987,"compact-revision":5687} {"level":"info","ts":"2026-09-08T23:13:43.957713Z","caller":"traceutil/trace.go:171","msg":"trace[178074341] transaction","detail":"{read_only:false; response_revision:11637; number_of_response:1; }","duration":"148.2084ms","start":"2026-09-08T23:13:43.809473Z","end":"2026-09-08T23:13:43.957682Z","steps":["trace[178074341] 'process raft request' (duration: 69.975756ms)","trace[178074341] 'compare' (duration: 78.092781ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:13:43.963159Z","caller":"traceutil/trace.go:171","msg":"trace[887302594] transaction","detail":"{read_only:false; response_revision:11638; number_of_response:1; }","duration":"151.999027ms","start":"2026-09-08T23:13:43.811132Z","end":"2026-09-08T23:13:43.963131Z","steps":["trace[887302594] 'process raft request' (duration: 151.813893ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:13:43.963362Z","caller":"traceutil/trace.go:171","msg":"trace[1780263294] linearizableReadLoop","detail":"{readStateIndex:12037; appliedIndex:12034; }","duration":"143.452531ms","start":"2026-09-08T23:13:43.819841Z","end":"2026-09-08T23:13:43.963293Z","steps":["trace[1780263294] 'read index received' (duration: 59.747812ms)","trace[1780263294] 'applied index is now lower than readState.Index' (duration: 83.702879ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:13:43.963558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.694738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-08T23:13:43.963622Z","caller":"traceutil/trace.go:171","msg":"trace[1995214012] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11639; }","duration":"143.79304ms","start":"2026-09-08T23:13:43.819808Z","end":"2026-09-08T23:13:43.963602Z","steps":["trace[1995214012] 'agreement among raft nodes before linearized reading' (duration: 143.606466ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:13:43.963965Z","caller":"traceutil/trace.go:171","msg":"trace[444173820] transaction","detail":"{read_only:false; response_revision:11639; number_of_response:1; }","duration":"151.118257ms","start":"2026-09-08T23:13:43.812666Z","end":"2026-09-08T23:13:43.963784Z","steps":["trace[444173820] 'process raft request' (duration: 150.424491ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:13:44.208384Z","caller":"traceutil/trace.go:171","msg":"trace[2092339747] transaction","detail":"{read_only:false; response_revision:11640; number_of_response:1; }","duration":"240.430996ms","start":"2026-09-08T23:13:43.967923Z","end":"2026-09-08T23:13:44.208354Z","steps":["trace[2092339747] 'process raft request' (duration: 207.090042ms)","trace[2092339747] 'compare' (duration: 32.907344ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:13:44.208512Z","caller":"traceutil/trace.go:171","msg":"trace[532782460] transaction","detail":"{read_only:false; response_revision:11641; number_of_response:1; }","duration":"211.445102ms","start":"2026-09-08T23:13:43.997032Z","end":"2026-09-08T23:13:44.208477Z","steps":["trace[532782460] 'process raft request' (duration: 211.022101ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:16:31.745565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10447} {"level":"info","ts":"2026-09-08T23:16:31.83866Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10447,"took":"89.267458ms","hash":2417636380,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":27435008,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-08T23:16:31.838736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2417636380,"revision":10447,"compact-revision":7987} {"level":"info","ts":"2026-09-08T23:20:00.37211Z","caller":"traceutil/trace.go:171","msg":"trace[1774996104] transaction","detail":"{read_only:false; response_revision:14172; number_of_response:1; }","duration":"104.945015ms","start":"2026-09-08T23:20:00.267136Z","end":"2026-09-08T23:20:00.372081Z","steps":["trace[1774996104] 'process raft request' (duration: 103.341997ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:20:16.593253Z","caller":"traceutil/trace.go:171","msg":"trace[912422501] transaction","detail":"{read_only:false; response_revision:14265; number_of_response:1; }","duration":"339.104977ms","start":"2026-09-08T23:20:16.254111Z","end":"2026-09-08T23:20:16.593216Z","steps":["trace[912422501] 'process raft request' (duration: 338.962713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:16.593381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:16.254093Z","time spent":"339.22797ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T23:20:16.752637Z","caller":"traceutil/trace.go:171","msg":"trace[75963577] transaction","detail":"{read_only:false; response_revision:14266; number_of_response:1; }","duration":"494.716996ms","start":"2026-09-08T23:20:16.257877Z","end":"2026-09-08T23:20:16.752594Z","steps":["trace[75963577] 'process raft request' (duration: 491.800348ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:20:16.752765Z","caller":"traceutil/trace.go:171","msg":"trace[1138022826] transaction","detail":"{read_only:false; response_revision:14267; number_of_response:1; }","duration":"312.96893ms","start":"2026-09-08T23:20:16.439755Z","end":"2026-09-08T23:20:16.752724Z","steps":["trace[1138022826] 'process raft request' (duration: 312.743845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:16.752877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:16.257841Z","time spent":"494.919241ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T23:20:16.752994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:16.439727Z","time spent":"313.142994ms","remote":"127.0.0.1:48258","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-09-08T23:20:16.75269Z","caller":"traceutil/trace.go:171","msg":"trace[2146825810] linearizableReadLoop","detail":"{readStateIndex:14757; appliedIndex:14755; }","duration":"493.796885ms","start":"2026-09-08T23:20:16.258863Z","end":"2026-09-08T23:20:16.75266Z","steps":["trace[2146825810] 'read index received' (duration: 334.286475ms)","trace[2146825810] 'applied index is now lower than readState.Index' (duration: 159.50904ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:20:16.753062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.153113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-08T23:20:16.75317Z","caller":"traceutil/trace.go:171","msg":"trace[56041018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14267; }","duration":"494.344997ms","start":"2026-09-08T23:20:16.258802Z","end":"2026-09-08T23:20:16.753147Z","steps":["trace[56041018] 'agreement among raft nodes before linearized reading' (duration: 493.937477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:16.753434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.946738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T23:20:16.753482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.21623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T23:20:16.753527Z","caller":"traceutil/trace.go:171","msg":"trace[1105013780] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:14267; }","duration":"131.071301ms","start":"2026-09-08T23:20:16.622425Z","end":"2026-09-08T23:20:16.753497Z","steps":["trace[1105013780] 'agreement among raft nodes before linearized reading' (duration: 130.903998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:16.753553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.679532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-09-08T23:20:16.753607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.841516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T23:20:16.75361Z","caller":"traceutil/trace.go:171","msg":"trace[1229946217] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:14267; }","duration":"127.746943ms","start":"2026-09-08T23:20:16.625846Z","end":"2026-09-08T23:20:16.753593Z","steps":["trace[1229946217] 'agreement among raft nodes before linearized reading' (duration: 127.58386ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:20:16.753543Z","caller":"traceutil/trace.go:171","msg":"trace[227375581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14267; }","duration":"439.353832ms","start":"2026-09-08T23:20:16.314174Z","end":"2026-09-08T23:20:16.753528Z","steps":["trace[227375581] 'agreement among raft nodes before linearized reading' (duration: 439.153468ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:20:16.753644Z","caller":"traceutil/trace.go:171","msg":"trace[1727404777] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14267; }","duration":"174.918938ms","start":"2026-09-08T23:20:16.578715Z","end":"2026-09-08T23:20:16.753634Z","steps":["trace[1727404777] 'agreement among raft nodes before linearized reading' (duration: 174.849807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:16.753743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:16.31415Z","time spent":"439.531666ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-08T23:20:16.753289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:16.25878Z","time spent":"494.490371ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-08T23:20:16.753975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.32908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-08T23:20:16.754099Z","caller":"traceutil/trace.go:171","msg":"trace[5788843] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14267; }","duration":"298.524075ms","start":"2026-09-08T23:20:16.455544Z","end":"2026-09-08T23:20:16.754069Z","steps":["trace[5788843] 'agreement among raft nodes before linearized reading' (duration: 298.157577ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:20:24.924086Z","caller":"traceutil/trace.go:171","msg":"trace[235698484] linearizableReadLoop","detail":"{readStateIndex:14813; appliedIndex:14812; }","duration":"134.233254ms","start":"2026-09-08T23:20:24.78982Z","end":"2026-09-08T23:20:24.924053Z","steps":["trace[235698484] 'read index received' (duration: 123.847332ms)","trace[235698484] 'applied index is now lower than readState.Index' (duration: 10.384042ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:20:24.92419Z","caller":"traceutil/trace.go:171","msg":"trace[1161063608] transaction","detail":"{read_only:false; response_revision:14321; number_of_response:1; }","duration":"143.05574ms","start":"2026-09-08T23:20:24.781086Z","end":"2026-09-08T23:20:24.924142Z","steps":["trace[1161063608] 'process raft request' (duration: 132.709549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:24.924388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.552081ms","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-09-08T23:20:24.924447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.786195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T23:20:24.92447Z","caller":"traceutil/trace.go:171","msg":"trace[1878597064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14321; }","duration":"134.673294ms","start":"2026-09-08T23:20:24.789768Z","end":"2026-09-08T23:20:24.924442Z","steps":["trace[1878597064] 'agreement among raft nodes before linearized reading' (duration: 134.390948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:24.924496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.680067ms","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-09-08T23:20:24.924514Z","caller":"traceutil/trace.go:171","msg":"trace[506838924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14321; }","duration":"124.914307ms","start":"2026-09-08T23:20:24.799576Z","end":"2026-09-08T23:20:24.92449Z","steps":["trace[506838924] 'agreement among raft nodes before linearized reading' (duration: 124.713583ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:20:24.924541Z","caller":"traceutil/trace.go:171","msg":"trace[424709427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14321; }","duration":"110.738969ms","start":"2026-09-08T23:20:24.813789Z","end":"2026-09-08T23:20:24.924527Z","steps":["trace[424709427] 'agreement among raft nodes before linearized reading' (duration: 110.602276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:24.924548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.110577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-08T23:20:24.924606Z","caller":"traceutil/trace.go:171","msg":"trace[1352439317] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14321; }","duration":"111.21729ms","start":"2026-09-08T23:20:24.813368Z","end":"2026-09-08T23:20:24.924585Z","steps":["trace[1352439317] 'agreement among raft nodes before linearized reading' (duration: 111.053046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:25.323704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.077818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T23:20:25.323964Z","caller":"traceutil/trace.go:171","msg":"trace[940257363] transaction","detail":"{read_only:false; response_revision:14323; number_of_response:1; }","duration":"394.582024ms","start":"2026-09-08T23:20:24.929337Z","end":"2026-09-08T23:20:25.323919Z","steps":["trace[940257363] 'process raft request' (duration: 264.196159ms)","trace[940257363] 'compare' (duration: 129.933705ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:20:25.324137Z","caller":"traceutil/trace.go:171","msg":"trace[1191842396] transaction","detail":"{read_only:false; response_revision:14324; number_of_response:1; }","duration":"394.318947ms","start":"2026-09-08T23:20:24.929783Z","end":"2026-09-08T23:20:25.324102Z","steps":["trace[1191842396] 'process raft request' (duration: 394.056521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:25.32417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:24.929301Z","time spent":"394.753948ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T23:20:25.32428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.385081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-p4fwp\" ","response":"range_response_count:1 size:12924"} {"level":"warn","ts":"2026-09-08T23:20:25.324357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:24.92975Z","time spent":"394.510532ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T23:20:25.324Z","caller":"traceutil/trace.go:171","msg":"trace[684242928] linearizableReadLoop","detail":"{readStateIndex:14817; appliedIndex:14813; }","duration":"373.144035ms","start":"2026-09-08T23:20:24.95083Z","end":"2026-09-08T23:20:25.323974Z","steps":["trace[684242928] 'read index received' (duration: 25.228065ms)","trace[684242928] 'applied index is now lower than readState.Index' (duration: 347.91459ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:20:25.324425Z","caller":"traceutil/trace.go:171","msg":"trace[179702400] transaction","detail":"{read_only:false; response_revision:14325; number_of_response:1; }","duration":"394.251566ms","start":"2026-09-08T23:20:24.93015Z","end":"2026-09-08T23:20:25.324401Z","steps":["trace[179702400] 'process raft request' (duration: 393.767785ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:20:25.324446Z","caller":"traceutil/trace.go:171","msg":"trace[316675087] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-p4fwp; range_end:; response_count:1; response_revision:14325; }","duration":"373.611586ms","start":"2026-09-08T23:20:24.950773Z","end":"2026-09-08T23:20:25.324384Z","steps":["trace[316675087] 'agreement among raft nodes before linearized reading' (duration: 373.268359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:25.324528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:24.930134Z","time spent":"394.343939ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T23:20:25.32455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:20:24.950746Z","time spent":"373.783361ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12947,"request content":"key:\"/registry/pods/openstack/openvswitch-p4fwp\" "} {"level":"info","ts":"2026-09-08T23:20:25.329541Z","caller":"traceutil/trace.go:171","msg":"trace[1661725918] transaction","detail":"{read_only:false; response_revision:14326; number_of_response:1; }","duration":"211.362313ms","start":"2026-09-08T23:20:25.118145Z","end":"2026-09-08T23:20:25.329508Z","steps":["trace[1661725918] 'process raft request' (duration: 211.151799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:20:25.329811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.106383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.59\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-08T23:20:25.329864Z","caller":"traceutil/trace.go:171","msg":"trace[1351444970] range","detail":"{range_begin:/registry/masterleases/162.253.55.59; range_end:; response_count:1; response_revision:14327; }","duration":"166.214045ms","start":"2026-09-08T23:20:25.163637Z","end":"2026-09-08T23:20:25.329851Z","steps":["trace[1351444970] 'agreement among raft nodes before linearized reading' (duration: 166.050271ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:20:25.330017Z","caller":"traceutil/trace.go:171","msg":"trace[1565888689] transaction","detail":"{read_only:false; response_revision:14327; number_of_response:1; }","duration":"207.679367ms","start":"2026-09-08T23:20:25.122252Z","end":"2026-09-08T23:20:25.329932Z","steps":["trace[1565888689] 'process raft request' (duration: 207.200556ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:21:31.754688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12769} {"level":"info","ts":"2026-09-08T23:21:31.83356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12769,"took":"76.30376ms","hash":963611226,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":30441472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T23:21:31.833644Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":963611226,"revision":12769,"compact-revision":10447} {"level":"info","ts":"2026-09-08T23:22:06.631553Z","caller":"traceutil/trace.go:171","msg":"trace[1666119530] transaction","detail":"{read_only:false; response_revision:15398; number_of_response:1; }","duration":"121.358866ms","start":"2026-09-08T23:22:06.510141Z","end":"2026-09-08T23:22:06.6315Z","steps":["trace[1666119530] 'process raft request' (duration: 121.102369ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:26:31.762008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15216} {"level":"info","ts":"2026-09-08T23:26:31.861351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15216,"took":"96.690163ms","hash":2971272869,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-08T23:26:31.861445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2971272869,"revision":15216,"compact-revision":12769} {"level":"info","ts":"2026-09-08T23:27:45.052269Z","caller":"traceutil/trace.go:171","msg":"trace[626381120] transaction","detail":"{read_only:false; response_revision:17933; number_of_response:1; }","duration":"138.982375ms","start":"2026-09-08T23:27:44.913235Z","end":"2026-09-08T23:27:45.052217Z","steps":["trace[626381120] 'process raft request' (duration: 138.81792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:27:50.969831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.190807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-09-08T23:27:50.969914Z","caller":"traceutil/trace.go:171","msg":"trace[757028570] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17967; }","duration":"175.33484ms","start":"2026-09-08T23:27:50.794563Z","end":"2026-09-08T23:27:50.969898Z","steps":["trace[757028570] 'range keys from in-memory index tree' (duration: 175.017613ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:27:51.317899Z","caller":"traceutil/trace.go:171","msg":"trace[1097466634] linearizableReadLoop","detail":"{readStateIndex:18581; appliedIndex:18580; }","duration":"262.592406ms","start":"2026-09-08T23:27:51.055277Z","end":"2026-09-08T23:27:51.317869Z","steps":["trace[1097466634] 'read index received' (duration: 262.454543ms)","trace[1097466634] 'applied index is now lower than readState.Index' (duration: 136.623µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:27:51.318098Z","caller":"traceutil/trace.go:171","msg":"trace[11969486] transaction","detail":"{read_only:false; response_revision:17968; number_of_response:1; }","duration":"332.481872ms","start":"2026-09-08T23:27:50.985603Z","end":"2026-09-08T23:27:51.318085Z","steps":["trace[11969486] 'process raft request' (duration: 332.085022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:27:51.318263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:27:50.98558Z","time spent":"332.572795ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T23:27:51.318982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.64671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T23:27:51.319096Z","caller":"traceutil/trace.go:171","msg":"trace[789667508] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17968; }","duration":"263.839334ms","start":"2026-09-08T23:27:51.055229Z","end":"2026-09-08T23:27:51.319068Z","steps":["trace[789667508] 'agreement among raft nodes before linearized reading' (duration: 263.064117ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:27:51.573086Z","caller":"traceutil/trace.go:171","msg":"trace[334392967] linearizableReadLoop","detail":"{readStateIndex:18583; appliedIndex:18581; }","duration":"255.017552ms","start":"2026-09-08T23:27:51.318046Z","end":"2026-09-08T23:27:51.573064Z","steps":["trace[334392967] 'read index received' (duration: 238.608474ms)","trace[334392967] 'applied index is now lower than readState.Index' (duration: 16.407508ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:27:51.573325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.524213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-08T23:27:51.57336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.238448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-08T23:27:51.573343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.81826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-09-08T23:27:51.573398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.986348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-08T23:27:51.57343Z","caller":"traceutil/trace.go:171","msg":"trace[581779756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17970; }","duration":"285.672796ms","start":"2026-09-08T23:27:51.287732Z","end":"2026-09-08T23:27:51.573405Z","steps":["trace[581779756] 'agreement among raft nodes before linearized reading' (duration: 285.336219ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:27:51.573457Z","caller":"traceutil/trace.go:171","msg":"trace[2041352882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17970; }","duration":"482.08545ms","start":"2026-09-08T23:27:51.091349Z","end":"2026-09-08T23:27:51.573434Z","steps":["trace[2041352882] 'agreement among raft nodes before linearized reading' (duration: 481.823464ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:27:51.573451Z","caller":"traceutil/trace.go:171","msg":"trace[1397888066] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:17970; }","duration":"426.972894ms","start":"2026-09-08T23:27:51.14645Z","end":"2026-09-08T23:27:51.573423Z","steps":["trace[1397888066] 'agreement among raft nodes before linearized reading' (duration: 426.628806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:27:51.573499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:27:51.091335Z","time spent":"482.155852ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-08T23:27:51.573529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:27:51.14642Z","time spent":"427.094297ms","remote":"127.0.0.1:48150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-09-08T23:27:51.573653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.674705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T23:27:51.573741Z","caller":"traceutil/trace.go:171","msg":"trace[1205413582] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17970; }","duration":"248.812018ms","start":"2026-09-08T23:27:51.324907Z","end":"2026-09-08T23:27:51.573719Z","steps":["trace[1205413582] 'agreement among raft nodes before linearized reading' (duration: 248.362598ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:27:51.57316Z","caller":"traceutil/trace.go:171","msg":"trace[1194321635] transaction","detail":"{read_only:false; response_revision:17970; number_of_response:1; }","duration":"487.30832ms","start":"2026-09-08T23:27:51.085786Z","end":"2026-09-08T23:27:51.573094Z","steps":["trace[1194321635] 'process raft request' (duration: 486.988913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:27:51.575176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:27:51.085764Z","time spent":"489.210644ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T23:27:51.576404Z","caller":"traceutil/trace.go:171","msg":"trace[888465087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17970; }","duration":"205.35501ms","start":"2026-09-08T23:27:51.368052Z","end":"2026-09-08T23:27:51.573407Z","steps":["trace[888465087] 'agreement among raft nodes before linearized reading' (duration: 205.226808ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:27:51.576765Z","caller":"traceutil/trace.go:171","msg":"trace[146247845] transaction","detail":"{read_only:false; response_revision:17969; number_of_response:1; }","duration":"489.156314ms","start":"2026-09-08T23:27:51.083699Z","end":"2026-09-08T23:27:51.572855Z","steps":["trace[146247845] 'process raft request' (duration: 473.000412ms)","trace[146247845] 'compare' (duration: 15.905806ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:27:51.576897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:27:51.083674Z","time spent":"493.137025ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T23:28:01.869436Z","caller":"traceutil/trace.go:171","msg":"trace[1450048091] linearizableReadLoop","detail":"{readStateIndex:18770; appliedIndex:18769; }","duration":"119.476617ms","start":"2026-09-08T23:28:01.749933Z","end":"2026-09-08T23:28:01.86941Z","steps":["trace[1450048091] 'read index received' (duration: 93.94682ms)","trace[1450048091] 'applied index is now lower than readState.Index' (duration: 25.528897ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:28:01.869453Z","caller":"traceutil/trace.go:171","msg":"trace[702202291] transaction","detail":"{read_only:false; response_revision:18149; number_of_response:1; }","duration":"135.075355ms","start":"2026-09-08T23:28:01.734342Z","end":"2026-09-08T23:28:01.869418Z","steps":["trace[702202291] 'process raft request' (duration: 109.611589ms)","trace[702202291] 'compare' (duration: 25.354564ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:28:01.869767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.806134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-09-08T23:28:01.869849Z","caller":"traceutil/trace.go:171","msg":"trace[1888002057] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18149; }","duration":"119.948888ms","start":"2026-09-08T23:28:01.749883Z","end":"2026-09-08T23:28:01.869832Z","steps":["trace[1888002057] 'agreement among raft nodes before linearized reading' (duration: 119.59335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:30:06.771864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.419162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T23:30:06.771932Z","caller":"traceutil/trace.go:171","msg":"trace[1595988436] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19208; }","duration":"138.543005ms","start":"2026-09-08T23:30:06.633376Z","end":"2026-09-08T23:30:06.771919Z","steps":["trace[1595988436] 'range keys from in-memory index tree' (duration: 138.327619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:30:06.772254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.771291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T23:30:06.772287Z","caller":"traceutil/trace.go:171","msg":"trace[1575108933] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19208; }","duration":"142.859344ms","start":"2026-09-08T23:30:06.62942Z","end":"2026-09-08T23:30:06.772279Z","steps":["trace[1575108933] 'range keys from in-memory index tree' (duration: 142.692879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:30:06.772299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.735254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:1 size:11072"} {"level":"info","ts":"2026-09-08T23:30:06.772416Z","caller":"traceutil/trace.go:171","msg":"trace[735580292] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:1; response_revision:19208; }","duration":"120.899838ms","start":"2026-09-08T23:30:06.651494Z","end":"2026-09-08T23:30:06.772394Z","steps":["trace[735580292] 'range keys from in-memory index tree' (duration: 120.50742ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:30:11.947451Z","caller":"traceutil/trace.go:171","msg":"trace[1406997496] transaction","detail":"{read_only:false; response_revision:19231; number_of_response:1; }","duration":"104.723126ms","start":"2026-09-08T23:30:11.842698Z","end":"2026-09-08T23:30:11.947421Z","steps":["trace[1406997496] 'process raft request' (duration: 104.42197ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:30:26.476422Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f5132dc9ac390b66","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T23:30:26.482116Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-08T23:30:26.482278Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-08T23:31:31.773652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17419} {"level":"info","ts":"2026-09-08T23:31:32.015807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17419,"took":"236.524366ms","hash":2849340644,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":34775040,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-08T23:31:32.015916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2849340644,"revision":17419,"compact-revision":15216} {"level":"info","ts":"2026-09-08T23:33:05.383076Z","caller":"traceutil/trace.go:171","msg":"trace[1307212604] linearizableReadLoop","detail":"{readStateIndex:21167; appliedIndex:21166; }","duration":"103.51908ms","start":"2026-09-08T23:33:05.279524Z","end":"2026-09-08T23:33:05.383043Z","steps":["trace[1307212604] 'read index received' (duration: 103.321744ms)","trace[1307212604] 'applied index is now lower than readState.Index' (duration: 195.156µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:33:05.383392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.838557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-08T23:33:05.383423Z","caller":"traceutil/trace.go:171","msg":"trace[1033449611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20473; }","duration":"103.924048ms","start":"2026-09-08T23:33:05.27949Z","end":"2026-09-08T23:33:05.383414Z","steps":["trace[1033449611] 'agreement among raft nodes before linearized reading' (duration: 103.722274ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:33:21.184138Z","caller":"traceutil/trace.go:171","msg":"trace[1619964873] transaction","detail":"{read_only:false; response_revision:20556; number_of_response:1; }","duration":"102.844574ms","start":"2026-09-08T23:33:21.081239Z","end":"2026-09-08T23:33:21.184083Z","steps":["trace[1619964873] 'process raft request' (duration: 87.615934ms)","trace[1619964873] 'compare' (duration: 14.78174ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:33:21.682361Z","caller":"traceutil/trace.go:171","msg":"trace[1937697653] linearizableReadLoop","detail":"{readStateIndex:21254; appliedIndex:21253; }","duration":"127.949821ms","start":"2026-09-08T23:33:21.554389Z","end":"2026-09-08T23:33:21.682339Z","steps":["trace[1937697653] 'read index received' (duration: 127.784258ms)","trace[1937697653] 'applied index is now lower than readState.Index' (duration: 164.433µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:33:21.682504Z","caller":"traceutil/trace.go:171","msg":"trace[1333789975] transaction","detail":"{read_only:false; response_revision:20557; number_of_response:1; }","duration":"128.066194ms","start":"2026-09-08T23:33:21.55438Z","end":"2026-09-08T23:33:21.682446Z","steps":["trace[1333789975] 'process raft request' (duration: 127.805538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:33:21.682585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.186807ms","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-09-08T23:33:21.682652Z","caller":"traceutil/trace.go:171","msg":"trace[318755186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20557; }","duration":"128.266869ms","start":"2026-09-08T23:33:21.554367Z","end":"2026-09-08T23:33:21.682634Z","steps":["trace[318755186] 'agreement among raft nodes before linearized reading' (duration: 128.084685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:33:21.682769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.468483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-08T23:33:21.682845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.870959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T23:33:21.68295Z","caller":"traceutil/trace.go:171","msg":"trace[700387713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20557; }","duration":"117.999412ms","start":"2026-09-08T23:33:21.564928Z","end":"2026-09-08T23:33:21.682927Z","steps":["trace[700387713] 'agreement among raft nodes before linearized reading' (duration: 117.749677ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:33:21.682906Z","caller":"traceutil/trace.go:171","msg":"trace[442008034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20557; }","duration":"118.629957ms","start":"2026-09-08T23:33:21.564256Z","end":"2026-09-08T23:33:21.682886Z","steps":["trace[442008034] 'agreement among raft nodes before linearized reading' (duration: 118.29257ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:33:21.84908Z","caller":"traceutil/trace.go:171","msg":"trace[2136156336] transaction","detail":"{read_only:false; response_revision:20558; number_of_response:1; }","duration":"161.110583ms","start":"2026-09-08T23:33:21.687941Z","end":"2026-09-08T23:33:21.849052Z","steps":["trace[2136156336] 'process raft request' (duration: 158.303439ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:33:21.853372Z","caller":"traceutil/trace.go:171","msg":"trace[691649460] transaction","detail":"{read_only:false; response_revision:20559; number_of_response:1; }","duration":"165.364981ms","start":"2026-09-08T23:33:21.687943Z","end":"2026-09-08T23:33:21.853308Z","steps":["trace[691649460] 'process raft request' (duration: 165.155836ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:33:21.853593Z","caller":"traceutil/trace.go:171","msg":"trace[1543961032] linearizableReadLoop","detail":"{readStateIndex:21257; appliedIndex:21254; }","duration":"161.474321ms","start":"2026-09-08T23:33:21.692057Z","end":"2026-09-08T23:33:21.853531Z","steps":["trace[1543961032] 'read index received' (duration: 154.501611ms)","trace[1543961032] 'applied index is now lower than readState.Index' (duration: 6.970179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:33:21.853719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.656105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T23:33:21.853789Z","caller":"traceutil/trace.go:171","msg":"trace[2065003786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20560; }","duration":"161.783588ms","start":"2026-09-08T23:33:21.691989Z","end":"2026-09-08T23:33:21.853773Z","steps":["trace[2065003786] 'agreement among raft nodes before linearized reading' (duration: 161.660615ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:33:21.853619Z","caller":"traceutil/trace.go:171","msg":"trace[137900199] transaction","detail":"{read_only:false; response_revision:20560; number_of_response:1; }","duration":"165.614556ms","start":"2026-09-08T23:33:21.687978Z","end":"2026-09-08T23:33:21.853592Z","steps":["trace[137900199] 'process raft request' (duration: 165.284899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:33:22.171252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.839058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T23:33:22.17137Z","caller":"traceutil/trace.go:171","msg":"trace[463955937] transaction","detail":"{read_only:false; response_revision:20561; number_of_response:1; }","duration":"290.371083ms","start":"2026-09-08T23:33:21.880976Z","end":"2026-09-08T23:33:22.171347Z","steps":["trace[463955937] 'process raft request' (duration: 159.345702ms)","trace[463955937] 'compare' (duration: 130.691054ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:33:22.240504Z","caller":"traceutil/trace.go:171","msg":"trace[1390394639] transaction","detail":"{read_only:false; response_revision:20562; number_of_response:1; }","duration":"230.398045ms","start":"2026-09-08T23:33:22.010082Z","end":"2026-09-08T23:33:22.24048Z","steps":["trace[1390394639] 'process raft request' (duration: 230.045387ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:33:22.529936Z","caller":"traceutil/trace.go:171","msg":"trace[131088634] transaction","detail":"{read_only:false; response_revision:20563; number_of_response:1; }","duration":"283.927046ms","start":"2026-09-08T23:33:22.24597Z","end":"2026-09-08T23:33:22.529897Z","steps":["trace[131088634] 'process raft request' (duration: 204.0369ms)","trace[131088634] 'compare' (duration: 79.719902ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:36:31.781474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19799} {"level":"info","ts":"2026-09-08T23:36:31.908339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19799,"took":"123.289936ms","hash":2707333374,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":30388224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T23:36:31.908417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2707333374,"revision":19799,"compact-revision":17419} {"level":"info","ts":"2026-09-08T23:37:10.343009Z","caller":"traceutil/trace.go:171","msg":"trace[1552958064] transaction","detail":"{read_only:false; response_revision:22967; number_of_response:1; }","duration":"123.579713ms","start":"2026-09-08T23:37:10.219406Z","end":"2026-09-08T23:37:10.342986Z","steps":["trace[1552958064] 'process raft request' (duration: 123.422679ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:38:13.717678Z","caller":"traceutil/trace.go:171","msg":"trace[167893651] transaction","detail":"{read_only:false; response_revision:23679; number_of_response:1; }","duration":"157.681586ms","start":"2026-09-08T23:38:13.559976Z","end":"2026-09-08T23:38:13.717658Z","steps":["trace[167893651] 'process raft request' (duration: 157.551124ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:38:13.717694Z","caller":"traceutil/trace.go:171","msg":"trace[1889336770] linearizableReadLoop","detail":"{readStateIndex:24463; appliedIndex:24462; }","duration":"157.340549ms","start":"2026-09-08T23:38:13.560312Z","end":"2026-09-08T23:38:13.717653Z","steps":["trace[1889336770] 'read index received' (duration: 157.322239ms)","trace[1889336770] 'applied index is now lower than readState.Index' (duration: 15.76µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:38:13.717905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.561964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T23:38:13.717951Z","caller":"traceutil/trace.go:171","msg":"trace[1714742157] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:23679; }","duration":"157.635206ms","start":"2026-09-08T23:38:13.560302Z","end":"2026-09-08T23:38:13.717937Z","steps":["trace[1714742157] 'agreement among raft nodes before linearized reading' (duration: 157.498553ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:38:13.7238Z","caller":"traceutil/trace.go:171","msg":"trace[1912767800] transaction","detail":"{read_only:false; response_revision:23681; number_of_response:1; }","duration":"144.403091ms","start":"2026-09-08T23:38:13.579372Z","end":"2026-09-08T23:38:13.723775Z","steps":["trace[1912767800] 'process raft request' (duration: 144.108864ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:38:13.723847Z","caller":"traceutil/trace.go:171","msg":"trace[1080133150] transaction","detail":"{read_only:false; response_revision:23682; number_of_response:1; }","duration":"102.809483ms","start":"2026-09-08T23:38:13.620997Z","end":"2026-09-08T23:38:13.723807Z","steps":["trace[1080133150] 'process raft request' (duration: 102.65186ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:38:13.724137Z","caller":"traceutil/trace.go:171","msg":"trace[646370892] transaction","detail":"{read_only:false; response_revision:23680; number_of_response:1; }","duration":"146.770115ms","start":"2026-09-08T23:38:13.577358Z","end":"2026-09-08T23:38:13.724129Z","steps":["trace[646370892] 'process raft request' (duration: 145.925226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:38:13.724936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.972027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-08T23:38:13.725041Z","caller":"traceutil/trace.go:171","msg":"trace[608992381] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23682; }","duration":"163.10994ms","start":"2026-09-08T23:38:13.561893Z","end":"2026-09-08T23:38:13.725003Z","steps":["trace[608992381] 'agreement among raft nodes before linearized reading' (duration: 162.846984ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:41:31.787843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22144} {"level":"info","ts":"2026-09-08T23:41:31.911842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22144,"took":"119.398116ms","hash":1911252726,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":42110976,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-08T23:41:31.912006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1911252726,"revision":22144,"compact-revision":19799} {"level":"info","ts":"2026-09-08T23:43:17.43513Z","caller":"traceutil/trace.go:171","msg":"trace[285307540] linearizableReadLoop","detail":"{readStateIndex:26853; appliedIndex:26851; }","duration":"110.658635ms","start":"2026-09-08T23:43:17.324446Z","end":"2026-09-08T23:43:17.435104Z","steps":["trace[285307540] 'read index received' (duration: 99.712593ms)","trace[285307540] 'applied index is now lower than readState.Index' (duration: 10.944402ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:43:17.435392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.935061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T23:43:17.435488Z","caller":"traceutil/trace.go:171","msg":"trace[105152408] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26005; }","duration":"111.089455ms","start":"2026-09-08T23:43:17.324383Z","end":"2026-09-08T23:43:17.435473Z","steps":["trace[105152408] 'agreement among raft nodes before linearized reading' (duration: 110.92312ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:43:17.435756Z","caller":"traceutil/trace.go:171","msg":"trace[1934832916] transaction","detail":"{read_only:false; response_revision:26004; number_of_response:1; }","duration":"134.693378ms","start":"2026-09-08T23:43:17.301047Z","end":"2026-09-08T23:43:17.435741Z","steps":["trace[1934832916] 'process raft request' (duration: 123.07544ms)","trace[1934832916] 'compare' (duration: 10.739937ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:43:17.435936Z","caller":"traceutil/trace.go:171","msg":"trace[1687244154] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"133.963891ms","start":"2026-09-08T23:43:17.301958Z","end":"2026-09-08T23:43:17.435922Z","steps":["trace[1687244154] 'process raft request' (duration: 133.0853ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:45:23.276503Z","caller":"traceutil/trace.go:171","msg":"trace[98088420] transaction","detail":"{read_only:false; response_revision:26963; number_of_response:1; }","duration":"108.55059ms","start":"2026-09-08T23:45:23.167927Z","end":"2026-09-08T23:45:23.276477Z","steps":["trace[98088420] 'process raft request' (duration: 108.378596ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:45:23.281464Z","caller":"traceutil/trace.go:171","msg":"trace[1660930953] transaction","detail":"{read_only:false; response_revision:26964; number_of_response:1; }","duration":"107.977699ms","start":"2026-09-08T23:45:23.173455Z","end":"2026-09-08T23:45:23.281433Z","steps":["trace[1660930953] 'process raft request' (duration: 107.774813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:45:33.73576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.860612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T23:45:33.735958Z","caller":"traceutil/trace.go:171","msg":"trace[281569237] transaction","detail":"{read_only:false; response_revision:27041; number_of_response:1; }","duration":"116.571526ms","start":"2026-09-08T23:45:33.619348Z","end":"2026-09-08T23:45:33.73592Z","steps":["trace[281569237] 'compare' (duration: 106.731559ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:46:31.794403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25238} {"level":"info","ts":"2026-09-08T23:46:31.949807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25238,"took":"151.634025ms","hash":582626002,"current-db-size-bytes":56217600,"current-db-size":"56 MB","current-db-size-in-use-bytes":29687808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T23:46:31.949863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":582626002,"revision":25238,"compact-revision":22144}