{"level":"warn","ts":"2026-10-26T23:51:40.019879Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T23:51:40.020041Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.210: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.210:2380","--initial-cluster=instance=https://162.253.55.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-26T23:51:40.020162Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T23:51:40.02019Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.210:2380"]} {"level":"info","ts":"2026-10-26T23:51:40.020224Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T23:51:40.021699Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.210:2379"]} {"level":"info","ts":"2026-10-26T23:51:40.021876Z","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.210:2380"],"listen-peer-urls":["https://162.253.55.210:2380"],"advertise-client-urls":["https://162.253.55.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-26T23:51:40.025328Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.037285ms"} {"level":"info","ts":"2026-10-26T23:51:40.032756Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a6d0019d52d78795","cluster-id":"c90109516379646"} {"level":"info","ts":"2026-10-26T23:51:40.032875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T23:51:40.032902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 became follower at term 0"} {"level":"info","ts":"2026-10-26T23:51:40.032912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a6d0019d52d78795 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T23:51:40.03292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 became follower at term 1"} {"level":"info","ts":"2026-10-26T23:51:40.032963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 switched to configuration voters=(12020109180663203733)"} {"level":"warn","ts":"2026-10-26T23:51:40.039171Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T23:51:40.040982Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T23:51:40.042546Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-26T23:51:40.046083Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a6d0019d52d78795","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T23:51:40.04642Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a6d0019d52d78795","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T23:51:40.046566Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T23:51:40.046686Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T23:51:40.046698Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T23:51:40.047903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 switched to configuration voters=(12020109180663203733)"} {"level":"info","ts":"2026-10-26T23:51:40.048291Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c90109516379646","local-member-id":"a6d0019d52d78795","added-peer-id":"a6d0019d52d78795","added-peer-peer-urls":["https://162.253.55.210:2380"]} {"level":"info","ts":"2026-10-26T23:51:40.051484Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T23:51:40.051731Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a6d0019d52d78795","initial-advertise-peer-urls":["https://162.253.55.210:2380"],"listen-peer-urls":["https://162.253.55.210:2380"],"advertise-client-urls":["https://162.253.55.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T23:51:40.051762Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T23:51:40.051836Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.210:2380"} {"level":"info","ts":"2026-10-26T23:51:40.051867Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.210:2380"} {"level":"info","ts":"2026-10-26T23:51:40.733161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T23:51:40.733222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T23:51:40.733257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 received MsgPreVoteResp from a6d0019d52d78795 at term 1"} {"level":"info","ts":"2026-10-26T23:51:40.733303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 became candidate at term 2"} {"level":"info","ts":"2026-10-26T23:51:40.733321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 received MsgVoteResp from a6d0019d52d78795 at term 2"} {"level":"info","ts":"2026-10-26T23:51:40.733342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6d0019d52d78795 became leader at term 2"} {"level":"info","ts":"2026-10-26T23:51:40.733362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a6d0019d52d78795 elected leader a6d0019d52d78795 at term 2"} {"level":"info","ts":"2026-10-26T23:51:40.734586Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T23:51:40.735346Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a6d0019d52d78795","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.210:2379]}","request-path":"/0/members/a6d0019d52d78795/attributes","cluster-id":"c90109516379646","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T23:51:40.735508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T23:51:40.735732Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T23:51:40.736185Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c90109516379646","local-member-id":"a6d0019d52d78795","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T23:51:40.736377Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T23:51:40.736418Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T23:51:40.738578Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.210:2379"} {"level":"info","ts":"2026-10-26T23:51:40.738741Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T23:51:40.738865Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T23:51:40.738975Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-26T23:51:59.823659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.962189ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-26T23:51:59.823862Z","caller":"traceutil/trace.go:171","msg":"trace[1329287572] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"258.070567ms","start":"2026-10-26T23:51:59.565771Z","end":"2026-10-26T23:51:59.823841Z","steps":["trace[1329287572] 'read index received' (duration: 119.904853ms)","trace[1329287572] 'applied index is now lower than readState.Index' (duration: 138.163314ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:51:59.824027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.247222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T23:51:59.824062Z","caller":"traceutil/trace.go:171","msg":"trace[682958104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"258.338355ms","start":"2026-10-26T23:51:59.565714Z","end":"2026-10-26T23:51:59.824052Z","steps":["trace[682958104] 'agreement among raft nodes before linearized reading' (duration: 258.251392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:51:59.824201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.856663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T23:51:59.82432Z","caller":"traceutil/trace.go:171","msg":"trace[456268269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"139.057309ms","start":"2026-10-26T23:51:59.68523Z","end":"2026-10-26T23:51:59.824287Z","steps":["trace[456268269] 'agreement among raft nodes before linearized reading' (duration: 138.794202ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:52:34.1722Z","caller":"traceutil/trace.go:171","msg":"trace[2080745663] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"117.078825ms","start":"2026-10-26T23:52:34.055059Z","end":"2026-10-26T23:52:34.172138Z","steps":["trace[2080745663] 'process raft request' (duration: 113.049165ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:52:34.179326Z","caller":"traceutil/trace.go:171","msg":"trace[1548805376] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"119.27461ms","start":"2026-10-26T23:52:34.059993Z","end":"2026-10-26T23:52:34.179267Z","steps":["trace[1548805376] 'read index received' (duration: 107.253942ms)","trace[1548805376] 'applied index is now lower than readState.Index' (duration: 12.019498ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:52:34.179539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.533637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-26T23:52:34.17958Z","caller":"traceutil/trace.go:171","msg":"trace[1151329083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"119.632499ms","start":"2026-10-26T23:52:34.059936Z","end":"2026-10-26T23:52:34.179568Z","steps":["trace[1151329083] 'agreement among raft nodes before linearized reading' (duration: 119.466665ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:52:34.179688Z","caller":"traceutil/trace.go:171","msg":"trace[1136953505] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"123.641509ms","start":"2026-10-26T23:52:34.056005Z","end":"2026-10-26T23:52:34.179647Z","steps":["trace[1136953505] 'process raft request' (duration: 123.102036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:52:34.645456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.931727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T23:52:34.645637Z","caller":"traceutil/trace.go:171","msg":"trace[1334819995] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"379.219771ms","start":"2026-10-26T23:52:34.266401Z","end":"2026-10-26T23:52:34.645621Z","steps":["trace[1334819995] 'read index received' (duration: 154.698649ms)","trace[1334819995] 'applied index is now lower than readState.Index' (duration: 224.520122ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T23:52:34.645731Z","caller":"traceutil/trace.go:171","msg":"trace[2045268960] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"396.506811ms","start":"2026-10-26T23:52:34.249213Z","end":"2026-10-26T23:52:34.64572Z","steps":["trace[2045268960] 'process raft request' (duration: 396.351247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:52:34.645804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T23:52:34.249192Z","time spent":"396.583922ms","remote":"127.0.0.1:38262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T23:52:34.6458Z","caller":"traceutil/trace.go:171","msg":"trace[627243445] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"460.92237ms","start":"2026-10-26T23:52:34.184793Z","end":"2026-10-26T23:52:34.645716Z","steps":["trace[627243445] 'process raft request' (duration: 236.216683ms)","trace[627243445] 'compare' (duration: 223.730462ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:52:34.645974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.210\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-26T23:52:34.64602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.902231ms","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-10-26T23:52:34.64609Z","caller":"traceutil/trace.go:171","msg":"trace[1876348315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"147.997153ms","start":"2026-10-26T23:52:34.498073Z","end":"2026-10-26T23:52:34.64607Z","steps":["trace[1876348315] 'agreement among raft nodes before linearized reading' (duration: 147.842869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:52:34.646094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T23:52:34.18476Z","time spent":"461.184115ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T23:52:34.64609Z","caller":"traceutil/trace.go:171","msg":"trace[488825895] range","detail":"{range_begin:/registry/masterleases/162.253.55.210; range_end:; response_count:1; response_revision:613; }","duration":"379.715373ms","start":"2026-10-26T23:52:34.266347Z","end":"2026-10-26T23:52:34.646063Z","steps":["trace[488825895] 'agreement among raft nodes before linearized reading' (duration: 379.536259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:52:34.646239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T23:52:34.26633Z","time spent":"379.868647ms","remote":"127.0.0.1:37926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.210\" "} {"level":"warn","ts":"2026-10-26T23:52:34.646006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.452662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zpvb5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-26T23:52:34.6464Z","caller":"traceutil/trace.go:171","msg":"trace[971106069] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zpvb5; range_end:; response_count:1; response_revision:613; }","duration":"299.921123ms","start":"2026-10-26T23:52:34.346467Z","end":"2026-10-26T23:52:34.646388Z","steps":["trace[971106069] 'agreement among raft nodes before linearized reading' (duration: 299.294778ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:52:50.136837Z","caller":"traceutil/trace.go:171","msg":"trace[69691] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"173.664836ms","start":"2026-10-26T23:52:49.963135Z","end":"2026-10-26T23:52:50.136799Z","steps":["trace[69691] 'process raft request' (duration: 173.45513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:54:04.861062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.973953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T23:54:04.861152Z","caller":"traceutil/trace.go:171","msg":"trace[667127758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1845; }","duration":"150.127398ms","start":"2026-10-26T23:54:04.711007Z","end":"2026-10-26T23:54:04.861134Z","steps":["trace[667127758] 'range keys from in-memory index tree' (duration: 149.783599ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:54:04.999005Z","caller":"traceutil/trace.go:171","msg":"trace[418421535] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"130.705606ms","start":"2026-10-26T23:54:04.868276Z","end":"2026-10-26T23:54:04.998982Z","steps":["trace[418421535] 'process raft request' (duration: 117.758595ms)","trace[418421535] 'compare' (duration: 12.828768ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:54:05.292264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.793479ms","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-10-26T23:54:05.292283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.094843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-26T23:54:05.292308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.184977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-233e6756-aabd-4e6a-a89e-5b334e9f7bd4\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-26T23:54:05.29236Z","caller":"traceutil/trace.go:171","msg":"trace[1586012166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1849; }","duration":"166.224687ms","start":"2026-10-26T23:54:05.126119Z","end":"2026-10-26T23:54:05.292344Z","steps":["trace[1586012166] 'range keys from in-memory index tree' (duration: 165.931119ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:54:05.292349Z","caller":"traceutil/trace.go:171","msg":"trace[1516913137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1849; }","duration":"187.912253ms","start":"2026-10-26T23:54:05.104418Z","end":"2026-10-26T23:54:05.292331Z","steps":["trace[1516913137] 'range keys from in-memory index tree' (duration: 187.598573ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:54:05.292395Z","caller":"traceutil/trace.go:171","msg":"trace[1788462717] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-233e6756-aabd-4e6a-a89e-5b334e9f7bd4; range_end:; response_count:1; response_revision:1849; }","duration":"132.288721ms","start":"2026-10-26T23:54:05.160075Z","end":"2026-10-26T23:54:05.292363Z","steps":["trace[1788462717] 'range keys from in-memory index tree' (duration: 132.086555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:54:05.292442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.160011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18e23984512d1d9a\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-10-26T23:54:05.292536Z","caller":"traceutil/trace.go:171","msg":"trace[120501638] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18e23984512d1d9a; range_end:; response_count:1; response_revision:1849; }","duration":"121.315235ms","start":"2026-10-26T23:54:05.171199Z","end":"2026-10-26T23:54:05.292514Z","steps":["trace[120501638] 'range keys from in-memory index tree' (duration: 120.949384ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:54:17.553352Z","caller":"traceutil/trace.go:171","msg":"trace[1891573118] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"175.606488ms","start":"2026-10-26T23:54:17.377722Z","end":"2026-10-26T23:54:17.553328Z","steps":["trace[1891573118] 'process raft request' (duration: 175.473205ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:54:17.554481Z","caller":"traceutil/trace.go:171","msg":"trace[426133766] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"174.328803ms","start":"2026-10-26T23:54:17.380139Z","end":"2026-10-26T23:54:17.554468Z","steps":["trace[426133766] 'process raft request' (duration: 174.22796ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:54:17.554657Z","caller":"traceutil/trace.go:171","msg":"trace[1271293487] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2022; }","duration":"163.382318ms","start":"2026-10-26T23:54:17.391248Z","end":"2026-10-26T23:54:17.55463Z","steps":["trace[1271293487] 'read index received' (duration: 161.939597ms)","trace[1271293487] 'applied index is now lower than readState.Index' (duration: 1.441101ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:54:17.554871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.612215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T23:54:17.554904Z","caller":"traceutil/trace.go:171","msg":"trace[305739805] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1952; }","duration":"163.696307ms","start":"2026-10-26T23:54:17.391195Z","end":"2026-10-26T23:54:17.554892Z","steps":["trace[305739805] 'agreement among raft nodes before linearized reading' (duration: 163.551053ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:54:49.37737Z","caller":"traceutil/trace.go:171","msg":"trace[1460373709] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"199.345281ms","start":"2026-10-26T23:54:49.177981Z","end":"2026-10-26T23:54:49.377326Z","steps":["trace[1460373709] 'process raft request' (duration: 194.005069ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:55:47.844719Z","caller":"traceutil/trace.go:171","msg":"trace[316442665] transaction","detail":"{read_only:false; response_revision:2625; number_of_response:1; }","duration":"184.334873ms","start":"2026-10-26T23:55:47.660335Z","end":"2026-10-26T23:55:47.84467Z","steps":["trace[316442665] 'process raft request' (duration: 183.14207ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:56:21.153025Z","caller":"traceutil/trace.go:171","msg":"trace[1033654311] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"105.769873ms","start":"2026-10-26T23:56:21.04723Z","end":"2026-10-26T23:56:21.153Z","steps":["trace[1033654311] 'process raft request' (duration: 105.615049ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:11.111209Z","caller":"traceutil/trace.go:171","msg":"trace[662049251] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"105.296374ms","start":"2026-10-27T00:01:11.00589Z","end":"2026-10-27T00:01:11.111186Z","steps":["trace[662049251] 'process raft request' (duration: 105.14545ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:11.111353Z","caller":"traceutil/trace.go:171","msg":"trace[250924995] linearizableReadLoop","detail":"{readStateIndex:5483; appliedIndex:5480; }","duration":"101.218322ms","start":"2026-10-27T00:01:11.010107Z","end":"2026-10-27T00:01:11.111325Z","steps":["trace[250924995] 'read index received' (duration: 92.380631ms)","trace[250924995] 'applied index is now lower than readState.Index' (duration: 8.835621ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:01:11.111435Z","caller":"traceutil/trace.go:171","msg":"trace[1048484028] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"105.453708ms","start":"2026-10-27T00:01:11.005969Z","end":"2026-10-27T00:01:11.111423Z","steps":["trace[1048484028] 'process raft request' (duration: 105.18048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:01:11.111601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-27T00:01:11.111633Z","caller":"traceutil/trace.go:171","msg":"trace[1533437966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5280; }","duration":"101.566302ms","start":"2026-10-27T00:01:11.010058Z","end":"2026-10-27T00:01:11.111624Z","steps":["trace[1533437966] 'agreement among raft nodes before linearized reading' (duration: 101.384327ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:26.052133Z","caller":"traceutil/trace.go:171","msg":"trace[233762601] transaction","detail":"{read_only:false; response_revision:5546; number_of_response:1; }","duration":"142.046167ms","start":"2026-10-27T00:01:25.910051Z","end":"2026-10-27T00:01:26.052097Z","steps":["trace[233762601] 'process raft request' (duration: 44.6986ms)","trace[233762601] 'compare' (duration: 96.981397ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:01:26.250838Z","caller":"traceutil/trace.go:171","msg":"trace[15815943] transaction","detail":"{read_only:false; response_revision:5547; number_of_response:1; }","duration":"100.782471ms","start":"2026-10-27T00:01:26.150028Z","end":"2026-10-27T00:01:26.25081Z","steps":["trace[15815943] 'process raft request' (duration: 52.41557ms)","trace[15815943] 'compare' (duration: 48.233837ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:01:26.581785Z","caller":"traceutil/trace.go:171","msg":"trace[1746366805] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"229.319109ms","start":"2026-10-27T00:01:26.352439Z","end":"2026-10-27T00:01:26.581758Z","steps":["trace[1746366805] 'process raft request' (duration: 229.164094ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:26.586223Z","caller":"traceutil/trace.go:171","msg":"trace[1071220521] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"233.637527ms","start":"2026-10-27T00:01:26.352561Z","end":"2026-10-27T00:01:26.586198Z","steps":["trace[1071220521] 'process raft request' (duration: 233.445322ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:26.586772Z","caller":"traceutil/trace.go:171","msg":"trace[1199627269] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"233.602056ms","start":"2026-10-27T00:01:26.353142Z","end":"2026-10-27T00:01:26.586744Z","steps":["trace[1199627269] 'process raft request' (duration: 233.000019ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:35.303127Z","caller":"traceutil/trace.go:171","msg":"trace[370713916] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"110.149967ms","start":"2026-10-27T00:01:35.192954Z","end":"2026-10-27T00:01:35.303104Z","steps":["trace[370713916] 'process raft request' (duration: 109.955631ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:35.331475Z","caller":"traceutil/trace.go:171","msg":"trace[899478436] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"119.173573ms","start":"2026-10-27T00:01:35.212281Z","end":"2026-10-27T00:01:35.331455Z","steps":["trace[899478436] 'process raft request' (duration: 119.032489ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:35.331748Z","caller":"traceutil/trace.go:171","msg":"trace[168128502] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"118.528805ms","start":"2026-10-27T00:01:35.213209Z","end":"2026-10-27T00:01:35.331737Z","steps":["trace[168128502] 'process raft request' (duration: 118.212367ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:35.33184Z","caller":"traceutil/trace.go:171","msg":"trace[2056530132] transaction","detail":"{read_only:false; response_revision:5716; number_of_response:1; }","duration":"116.535761ms","start":"2026-10-27T00:01:35.215281Z","end":"2026-10-27T00:01:35.331816Z","steps":["trace[2056530132] 'process raft request' (duration: 116.368636ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:35.499159Z","caller":"traceutil/trace.go:171","msg":"trace[675946164] transaction","detail":"{read_only:false; response_revision:5718; number_of_response:1; }","duration":"160.11134ms","start":"2026-10-27T00:01:35.339025Z","end":"2026-10-27T00:01:35.499136Z","steps":["trace[675946164] 'process raft request' (duration: 159.957556ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:40.759779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"info","ts":"2026-10-27T00:01:40.846917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"85.770851ms","hash":1323383421,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":32227328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T00:01:40.846967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1323383421,"revision":2939,"compact-revision":-1} {"level":"info","ts":"2026-10-27T00:06:40.769019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5822} {"level":"info","ts":"2026-10-27T00:06:40.864751Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5822,"took":"91.916019ms","hash":1341640649,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":26075136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-27T00:06:40.864831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1341640649,"revision":5822,"compact-revision":2939} {"level":"info","ts":"2026-10-27T00:07:55.953Z","caller":"traceutil/trace.go:171","msg":"trace[1165073108] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"401.499703ms","start":"2026-10-27T00:07:55.551471Z","end":"2026-10-27T00:07:55.952971Z","steps":["trace[1165073108] 'process raft request' (duration: 401.305837ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:07:55.953127Z","caller":"traceutil/trace.go:171","msg":"trace[301019350] linearizableReadLoop","detail":"{readStateIndex:8941; appliedIndex:8940; }","duration":"386.850416ms","start":"2026-10-27T00:07:55.566244Z","end":"2026-10-27T00:07:55.953095Z","steps":["trace[301019350] 'read index received' (duration: 386.431695ms)","trace[301019350] 'applied index is now lower than readState.Index' (duration: 416.011µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:07:55.953188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.55145Z","time spent":"401.655807ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:07:55.953261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.00582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:07:55.953345Z","caller":"traceutil/trace.go:171","msg":"trace[614107406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8631; }","duration":"387.134233ms","start":"2026-10-27T00:07:55.56619Z","end":"2026-10-27T00:07:55.953324Z","steps":["trace[614107406] 'agreement among raft nodes before linearized reading' (duration: 387.00587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:55.953385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.56617Z","time spent":"387.207896ms","remote":"127.0.0.1:37866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-27T00:07:55.953468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.26646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T00:07:55.953514Z","caller":"traceutil/trace.go:171","msg":"trace[220889209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8631; }","duration":"379.357662ms","start":"2026-10-27T00:07:55.574146Z","end":"2026-10-27T00:07:55.953503Z","steps":["trace[220889209] 'agreement among raft nodes before linearized reading' (duration: 379.109425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:55.953548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.574135Z","time spent":"379.404534ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-27T00:07:55.953553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.273369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-27T00:07:55.953625Z","caller":"traceutil/trace.go:171","msg":"trace[961653130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8631; }","duration":"205.352281ms","start":"2026-10-27T00:07:55.748254Z","end":"2026-10-27T00:07:55.953606Z","steps":["trace[961653130] 'agreement among raft nodes before linearized reading' (duration: 205.248458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:55.953454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.933863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-27T00:07:55.953743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.756239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:07:55.953773Z","caller":"traceutil/trace.go:171","msg":"trace[411920240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8631; }","duration":"218.272312ms","start":"2026-10-27T00:07:55.735474Z","end":"2026-10-27T00:07:55.953746Z","steps":["trace[411920240] 'agreement among raft nodes before linearized reading' (duration: 217.84606ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:07:55.953781Z","caller":"traceutil/trace.go:171","msg":"trace[1644811666] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8631; }","duration":"248.836591ms","start":"2026-10-27T00:07:55.70493Z","end":"2026-10-27T00:07:55.953767Z","steps":["trace[1644811666] 'agreement among raft nodes before linearized reading' (duration: 248.77482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:55.95381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.364152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-27T00:07:55.953882Z","caller":"traceutil/trace.go:171","msg":"trace[1877829061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8631; }","duration":"250.456735ms","start":"2026-10-27T00:07:55.703408Z","end":"2026-10-27T00:07:55.953865Z","steps":["trace[1877829061] 'agreement among raft nodes before linearized reading' (duration: 250.251449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:56.309529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.000922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:07:56.309631Z","caller":"traceutil/trace.go:171","msg":"trace[162020036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8631; }","duration":"355.141606ms","start":"2026-10-27T00:07:55.954471Z","end":"2026-10-27T00:07:56.309613Z","steps":["trace[162020036] 'range keys from in-memory index tree' (duration: 354.9307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:56.309671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.954457Z","time spent":"355.205457ms","remote":"127.0.0.1:37876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-27T00:07:56.310013Z","caller":"traceutil/trace.go:171","msg":"trace[488379980] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"352.601886ms","start":"2026-10-27T00:07:55.957382Z","end":"2026-10-27T00:07:56.309984Z","steps":["trace[488379980] 'process raft request' (duration: 283.7988ms)","trace[488379980] 'compare' (duration: 68.21983ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:07:56.310167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.957364Z","time spent":"352.7326ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:07:56.310213Z","caller":"traceutil/trace.go:171","msg":"trace[1533795873] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"352.547425ms","start":"2026-10-27T00:07:55.957654Z","end":"2026-10-27T00:07:56.310201Z","steps":["trace[1533795873] 'process raft request' (duration: 352.274618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:56.31031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.957645Z","time spent":"352.607297ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:07:56.310534Z","caller":"traceutil/trace.go:171","msg":"trace[1610897912] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"352.825072ms","start":"2026-10-27T00:07:55.957674Z","end":"2026-10-27T00:07:56.310499Z","steps":["trace[1610897912] 'process raft request' (duration: 352.453972ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:07:56.310624Z","caller":"traceutil/trace.go:171","msg":"trace[500978711] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"352.098613ms","start":"2026-10-27T00:07:55.958485Z","end":"2026-10-27T00:07:56.310584Z","steps":["trace[500978711] 'process raft request' (duration: 351.960449ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:07:56.310621Z","caller":"traceutil/trace.go:171","msg":"trace[514252634] linearizableReadLoop","detail":"{readStateIndex:8945; appliedIndex:8941; }","duration":"216.987128ms","start":"2026-10-27T00:07:56.093599Z","end":"2026-10-27T00:07:56.310586Z","steps":["trace[514252634] 'read index received' (duration: 147.599715ms)","trace[514252634] 'applied index is now lower than readState.Index' (duration: 69.386203ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:07:56.31073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.957654Z","time spent":"352.971927ms","remote":"127.0.0.1:38174","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-10-27T00:07:56.31076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.958471Z","time spent":"352.253847ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:07:56.310861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.220354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-27T00:07:56.310917Z","caller":"traceutil/trace.go:171","msg":"trace[1653443675] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8635; }","duration":"217.373107ms","start":"2026-10-27T00:07:56.093525Z","end":"2026-10-27T00:07:56.310899Z","steps":["trace[1653443675] 'agreement among raft nodes before linearized reading' (duration: 217.150131ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.625612Z","caller":"traceutil/trace.go:171","msg":"trace[1924495800] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"253.412676ms","start":"2026-10-27T00:08:08.372176Z","end":"2026-10-27T00:08:08.625588Z","steps":["trace[1924495800] 'process raft request' (duration: 252.500321ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.625821Z","caller":"traceutil/trace.go:171","msg":"trace[660589711] linearizableReadLoop","detail":"{readStateIndex:9009; appliedIndex:9005; }","duration":"219.022512ms","start":"2026-10-27T00:08:08.406782Z","end":"2026-10-27T00:08:08.625805Z","steps":["trace[660589711] 'read index received' (duration: 31.406262ms)","trace[660589711] 'applied index is now lower than readState.Index' (duration: 187.6154ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:08.625854Z","caller":"traceutil/trace.go:171","msg":"trace[1066763770] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"253.635761ms","start":"2026-10-27T00:08:08.372189Z","end":"2026-10-27T00:08:08.625825Z","steps":["trace[1066763770] 'process raft request' (duration: 253.334953ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.625895Z","caller":"traceutil/trace.go:171","msg":"trace[1978447102] transaction","detail":"{read_only:false; response_revision:8697; number_of_response:1; }","duration":"253.098197ms","start":"2026-10-27T00:08:08.37277Z","end":"2026-10-27T00:08:08.625868Z","steps":["trace[1978447102] 'process raft request' (duration: 252.967404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:08.625941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.137995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:08.626655Z","caller":"traceutil/trace.go:171","msg":"trace[1090787057] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:8697; }","duration":"219.880006ms","start":"2026-10-27T00:08:08.406746Z","end":"2026-10-27T00:08:08.626626Z","steps":["trace[1090787057] 'agreement among raft nodes before linearized reading' (duration: 219.108855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:09.226155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.955934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T00:08:09.22653Z","caller":"traceutil/trace.go:171","msg":"trace[2052100694] linearizableReadLoop","detail":"{readStateIndex:9012; appliedIndex:9009; }","duration":"372.25294ms","start":"2026-10-27T00:08:08.854259Z","end":"2026-10-27T00:08:09.226512Z","steps":["trace[2052100694] 'read index received' (duration: 621.337µs)","trace[2052100694] 'applied index is now lower than readState.Index' (duration: 371.630673ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:09.226615Z","caller":"traceutil/trace.go:171","msg":"trace[1944147372] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"387.960566ms","start":"2026-10-27T00:08:08.838624Z","end":"2026-10-27T00:08:09.226585Z","steps":["trace[1944147372] 'process raft request' (duration: 387.620626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:09.226748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.838607Z","time spent":"388.067219ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:08:09.226652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.401024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:09.22697Z","caller":"traceutil/trace.go:171","msg":"trace[1966252230] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"392.803408ms","start":"2026-10-27T00:08:08.834109Z","end":"2026-10-27T00:08:09.226912Z","steps":["trace[1966252230] 'process raft request' (duration: 150.00561ms)","trace[1966252230] 'compare' (duration: 241.548324ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:09.226994Z","caller":"traceutil/trace.go:171","msg":"trace[1272518624] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8700; }","duration":"372.780574ms","start":"2026-10-27T00:08:08.854199Z","end":"2026-10-27T00:08:09.22698Z","steps":["trace[1272518624] 'agreement among raft nodes before linearized reading' (duration: 372.417914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:09.227172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.834082Z","time spent":"392.985693ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:08:09.22721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.854179Z","time spent":"373.01698ms","remote":"127.0.0.1:38334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-27T00:08:09.300048Z","caller":"traceutil/trace.go:171","msg":"trace[1439763628] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"225.305183ms","start":"2026-10-27T00:08:09.074714Z","end":"2026-10-27T00:08:09.30002Z","steps":["trace[1439763628] 'process raft request' (duration: 225.139638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:09.30015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.840946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T00:08:09.300226Z","caller":"traceutil/trace.go:171","msg":"trace[554592808] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8701; }","duration":"431.96272ms","start":"2026-10-27T00:08:08.868245Z","end":"2026-10-27T00:08:09.300208Z","steps":["trace[554592808] 'agreement among raft nodes before linearized reading' (duration: 431.766835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:09.300276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.868234Z","time spent":"432.031431ms","remote":"127.0.0.1:38098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":112,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-10-27T00:08:10.01971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.053675ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-27T00:08:10.019827Z","caller":"traceutil/trace.go:171","msg":"trace[938630390] linearizableReadLoop","detail":"{readStateIndex:9014; appliedIndex:9013; }","duration":"175.020809ms","start":"2026-10-27T00:08:09.844784Z","end":"2026-10-27T00:08:10.019805Z","steps":["trace[938630390] 'read index received' (duration: 1.734817ms)","trace[938630390] 'applied index is now lower than readState.Index' (duration: 173.284292ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:08:10.019925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.130251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:10.019969Z","caller":"traceutil/trace.go:171","msg":"trace[1282628985] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8701; }","duration":"175.246965ms","start":"2026-10-27T00:08:09.844709Z","end":"2026-10-27T00:08:10.019956Z","steps":["trace[1282628985] 'agreement among raft nodes before linearized reading' (duration: 175.151172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:10.41505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.303033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T00:08:10.415168Z","caller":"traceutil/trace.go:171","msg":"trace[2060553149] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8702; }","duration":"141.473768ms","start":"2026-10-27T00:08:10.273671Z","end":"2026-10-27T00:08:10.415145Z","steps":["trace[2060553149] 'count revisions from in-memory index tree' (duration: 141.221712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:10.415268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.394029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-27T00:08:10.415346Z","caller":"traceutil/trace.go:171","msg":"trace[1860927407] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8702; }","duration":"161.507892ms","start":"2026-10-27T00:08:10.253822Z","end":"2026-10-27T00:08:10.41533Z","steps":["trace[1860927407] 'range keys from in-memory index tree' (duration: 161.182893ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:11.003132Z","caller":"traceutil/trace.go:171","msg":"trace[1053180369] transaction","detail":"{read_only:false; response_revision:8708; number_of_response:1; }","duration":"143.128063ms","start":"2026-10-27T00:08:10.859981Z","end":"2026-10-27T00:08:11.003109Z","steps":["trace[1053180369] 'process raft request' (duration: 142.982149ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:31.309982Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b5.wal"} {"level":"info","ts":"2026-10-27T00:08:41.005722Z","caller":"traceutil/trace.go:171","msg":"trace[471339472] linearizableReadLoop","detail":"{readStateIndex:9241; appliedIndex:9240; }","duration":"439.878784ms","start":"2026-10-27T00:08:40.565824Z","end":"2026-10-27T00:08:41.005702Z","steps":["trace[471339472] 'read index received' (duration: 439.662248ms)","trace[471339472] 'applied index is now lower than readState.Index' (duration: 215.656µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:41.005844Z","caller":"traceutil/trace.go:171","msg":"trace[139871385] transaction","detail":"{read_only:false; response_revision:8918; number_of_response:1; }","duration":"519.653273ms","start":"2026-10-27T00:08:40.48618Z","end":"2026-10-27T00:08:41.005833Z","steps":["trace[139871385] 'process raft request' (duration: 519.355714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:41.005936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:40.486164Z","time spent":"519.702154ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:08:41.006031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.995173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-27T00:08:41.006085Z","caller":"traceutil/trace.go:171","msg":"trace[255677973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8918; }","duration":"372.090195ms","start":"2026-10-27T00:08:40.633981Z","end":"2026-10-27T00:08:41.006071Z","steps":["trace[255677973] 'agreement among raft nodes before linearized reading' (duration: 371.959292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:41.006121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:40.633963Z","time spent":"372.151616ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-27T00:08:41.006272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.185704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-27T00:08:41.006304Z","caller":"traceutil/trace.go:171","msg":"trace[1672923967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8918; }","duration":"173.237574ms","start":"2026-10-27T00:08:40.833058Z","end":"2026-10-27T00:08:41.006295Z","steps":["trace[1672923967] 'agreement among raft nodes before linearized reading' (duration: 173.157022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:41.006311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.485201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:41.006344Z","caller":"traceutil/trace.go:171","msg":"trace[336904898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8918; }","duration":"440.530781ms","start":"2026-10-27T00:08:40.565801Z","end":"2026-10-27T00:08:41.006332Z","steps":["trace[336904898] 'agreement among raft nodes before linearized reading' (duration: 440.49104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:41.006378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:40.565792Z","time spent":"440.580732ms","remote":"127.0.0.1:37866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-27T00:08:41.006412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.532795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T00:08:41.006434Z","caller":"traceutil/trace.go:171","msg":"trace[787038142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8918; }","duration":"209.583466ms","start":"2026-10-27T00:08:40.796844Z","end":"2026-10-27T00:08:41.006427Z","steps":["trace[787038142] 'agreement among raft nodes before linearized reading' (duration: 209.516454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:41.006271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.502696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-27T00:08:41.006501Z","caller":"traceutil/trace.go:171","msg":"trace[943007437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8918; }","duration":"163.755263ms","start":"2026-10-27T00:08:40.842739Z","end":"2026-10-27T00:08:41.006495Z","steps":["trace[943007437] 'agreement among raft nodes before linearized reading' (duration: 163.461185ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:41.215734Z","caller":"traceutil/trace.go:171","msg":"trace[1372102277] transaction","detail":"{read_only:false; response_revision:8919; number_of_response:1; }","duration":"204.620519ms","start":"2026-10-27T00:08:41.011091Z","end":"2026-10-27T00:08:41.215711Z","steps":["trace[1372102277] 'process raft request' (duration: 172.490373ms)","trace[1372102277] 'compare' (duration: 32.056254ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:41.224881Z","caller":"traceutil/trace.go:171","msg":"trace[142360434] transaction","detail":"{read_only:false; response_revision:8920; number_of_response:1; }","duration":"213.600577ms","start":"2026-10-27T00:08:41.011265Z","end":"2026-10-27T00:08:41.224866Z","steps":["trace[142360434] 'process raft request' (duration: 213.488094ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:41.225049Z","caller":"traceutil/trace.go:171","msg":"trace[574373708] transaction","detail":"{read_only:false; response_revision:8921; number_of_response:1; }","duration":"213.043602ms","start":"2026-10-27T00:08:41.011996Z","end":"2026-10-27T00:08:41.22504Z","steps":["trace[574373708] 'process raft request' (duration: 212.836466ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:41.225223Z","caller":"traceutil/trace.go:171","msg":"trace[1745860089] linearizableReadLoop","detail":"{readStateIndex:9245; appliedIndex:9241; }","duration":"192.199027ms","start":"2026-10-27T00:08:41.033006Z","end":"2026-10-27T00:08:41.225205Z","steps":["trace[1745860089] 'read index received' (duration: 150.58501ms)","trace[1745860089] 'applied index is now lower than readState.Index' (duration: 41.612807ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:41.225286Z","caller":"traceutil/trace.go:171","msg":"trace[596205541] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"105.635452ms","start":"2026-10-27T00:08:41.119641Z","end":"2026-10-27T00:08:41.225276Z","steps":["trace[596205541] 'process raft request' (duration: 105.485858ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:41.225285Z","caller":"traceutil/trace.go:171","msg":"trace[941799943] transaction","detail":"{read_only:false; response_revision:8922; number_of_response:1; }","duration":"212.781025ms","start":"2026-10-27T00:08:41.012485Z","end":"2026-10-27T00:08:41.225266Z","steps":["trace[941799943] 'process raft request' (duration: 212.512187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:41.225383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.357231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T00:08:41.225426Z","caller":"traceutil/trace.go:171","msg":"trace[373333756] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8923; }","duration":"192.441744ms","start":"2026-10-27T00:08:41.032971Z","end":"2026-10-27T00:08:41.225413Z","steps":["trace[373333756] 'agreement among raft nodes before linearized reading' (duration: 192.345811ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:42.653402Z","caller":"traceutil/trace.go:171","msg":"trace[461354594] linearizableReadLoop","detail":"{readStateIndex:9252; appliedIndex:9251; }","duration":"109.392655ms","start":"2026-10-27T00:08:42.543985Z","end":"2026-10-27T00:08:42.653378Z","steps":["trace[461354594] 'read index received' (duration: 17.680118ms)","trace[461354594] 'applied index is now lower than readState.Index' (duration: 91.711257ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:42.653531Z","caller":"traceutil/trace.go:171","msg":"trace[1329407603] transaction","detail":"{read_only:false; response_revision:8929; number_of_response:1; }","duration":"118.879367ms","start":"2026-10-27T00:08:42.534639Z","end":"2026-10-27T00:08:42.653518Z","steps":["trace[1329407603] 'process raft request' (duration: 27.076727ms)","trace[1329407603] 'compare' (duration: 91.45607ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:08:42.653771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.771695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6558cb5bdc-zhczw.18e23a4356da4e3c\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-27T00:08:42.653828Z","caller":"traceutil/trace.go:171","msg":"trace[2073020119] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6558cb5bdc-zhczw.18e23a4356da4e3c; range_end:; response_count:1; response_revision:8929; }","duration":"109.870698ms","start":"2026-10-27T00:08:42.543942Z","end":"2026-10-27T00:08:42.653812Z","steps":["trace[2073020119] 'agreement among raft nodes before linearized reading' (duration: 109.745245ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:50.571257Z","caller":"traceutil/trace.go:171","msg":"trace[1175596406] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"202.796359ms","start":"2026-10-27T00:08:50.368435Z","end":"2026-10-27T00:08:50.571231Z","steps":["trace[1175596406] 'process raft request' (duration: 202.635935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.113654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.778115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-sbgw8\" ","response":"range_response_count:1 size:4790"} {"level":"info","ts":"2026-10-27T00:08:54.113771Z","caller":"traceutil/trace.go:171","msg":"trace[839668334] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-sbgw8; range_end:; response_count:1; response_revision:9002; }","duration":"101.978301ms","start":"2026-10-27T00:08:54.011766Z","end":"2026-10-27T00:08:54.113744Z","steps":["trace[839668334] 'range keys from in-memory index tree' (duration: 101.611621ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:54.120818Z","caller":"traceutil/trace.go:171","msg":"trace[1447764844] transaction","detail":"{read_only:false; response_revision:9003; number_of_response:1; }","duration":"107.796691ms","start":"2026-10-27T00:08:54.012994Z","end":"2026-10-27T00:08:54.120791Z","steps":["trace[1447764844] 'process raft request' (duration: 107.594385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.499955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.257019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T00:08:54.500076Z","caller":"traceutil/trace.go:171","msg":"trace[57996467] linearizableReadLoop","detail":"{readStateIndex:9329; appliedIndex:9328; }","duration":"179.172438ms","start":"2026-10-27T00:08:54.320882Z","end":"2026-10-27T00:08:54.500055Z","steps":["trace[57996467] 'read index received' (duration: 19.6228ms)","trace[57996467] 'applied index is now lower than readState.Index' (duration: 159.548078ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:54.500232Z","caller":"traceutil/trace.go:171","msg":"trace[1663850471] transaction","detail":"{read_only:false; response_revision:9004; number_of_response:1; }","duration":"377.394381ms","start":"2026-10-27T00:08:54.122804Z","end":"2026-10-27T00:08:54.500199Z","steps":["trace[1663850471] 'process raft request' (duration: 217.781962ms)","trace[1663850471] 'compare' (duration: 159.137406ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:08:54.50036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.470597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-27T00:08:54.500425Z","caller":"traceutil/trace.go:171","msg":"trace[1680487647] range","detail":"{range_begin:/registry/masterleases/162.253.55.210; range_end:; response_count:1; response_revision:9004; }","duration":"179.569869ms","start":"2026-10-27T00:08:54.320838Z","end":"2026-10-27T00:08:54.500408Z","steps":["trace[1680487647] 'agreement among raft nodes before linearized reading' (duration: 179.327833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.500525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.09033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:54.500645Z","caller":"traceutil/trace.go:171","msg":"trace[1802071929] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:9004; }","duration":"120.232874ms","start":"2026-10-27T00:08:54.380364Z","end":"2026-10-27T00:08:54.500597Z","steps":["trace[1802071929] 'agreement among raft nodes before linearized reading' (duration: 120.10149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.500756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:54.122776Z","time spent":"377.919775ms","remote":"127.0.0.1:38072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:09:55.979115Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6d0019d52d78795","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T00:09:55.984369Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-27T00:09:55.984435Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-27T00:10:24.81331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.003446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:10:24.813408Z","caller":"traceutil/trace.go:171","msg":"trace[1052137509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9854; }","duration":"247.167021ms","start":"2026-10-27T00:10:24.566221Z","end":"2026-10-27T00:10:24.813388Z","steps":["trace[1052137509] 'range keys from in-memory index tree' (duration: 246.889614ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:10:25.043185Z","caller":"traceutil/trace.go:171","msg":"trace[1865378335] transaction","detail":"{read_only:false; response_revision:9855; number_of_response:1; }","duration":"160.956072ms","start":"2026-10-27T00:10:24.882204Z","end":"2026-10-27T00:10:25.04316Z","steps":["trace[1865378335] 'process raft request' (duration: 160.805398ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:11:40.779094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8002} {"level":"info","ts":"2026-10-27T00:11:40.853218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8002,"took":"71.783822ms","hash":3100541527,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":27996160,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T00:11:40.853283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3100541527,"revision":8002,"compact-revision":5822} {"level":"info","ts":"2026-10-27T00:16:05.593464Z","caller":"traceutil/trace.go:171","msg":"trace[1243153328] transaction","detail":"{read_only:false; response_revision:12299; number_of_response:1; }","duration":"179.456098ms","start":"2026-10-27T00:16:05.413978Z","end":"2026-10-27T00:16:05.593434Z","steps":["trace[1243153328] 'process raft request' (duration: 87.199478ms)","trace[1243153328] 'compare' (duration: 91.969832ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:16:05.60496Z","caller":"traceutil/trace.go:171","msg":"trace[815628583] linearizableReadLoop","detail":"{readStateIndex:12729; appliedIndex:12727; }","duration":"190.017867ms","start":"2026-10-27T00:16:05.414921Z","end":"2026-10-27T00:16:05.604939Z","steps":["trace[815628583] 'read index received' (duration: 86.376065ms)","trace[815628583] 'applied index is now lower than readState.Index' (duration: 103.640732ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:16:05.605026Z","caller":"traceutil/trace.go:171","msg":"trace[108766524] transaction","detail":"{read_only:false; response_revision:12300; number_of_response:1; }","duration":"190.087679ms","start":"2026-10-27T00:16:05.414914Z","end":"2026-10-27T00:16:05.605001Z","steps":["trace[108766524] 'process raft request' (duration: 189.889903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.605276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.162822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-27T00:16:05.605335Z","caller":"traceutil/trace.go:171","msg":"trace[1165430568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12300; }","duration":"162.271275ms","start":"2026-10-27T00:16:05.443054Z","end":"2026-10-27T00:16:05.605325Z","steps":["trace[1165430568] 'agreement among raft nodes before linearized reading' (duration: 162.10656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.605414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.027248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T00:16:05.605501Z","caller":"traceutil/trace.go:171","msg":"trace[479574315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12300; }","duration":"156.154552ms","start":"2026-10-27T00:16:05.449333Z","end":"2026-10-27T00:16:05.605487Z","steps":["trace[479574315] 'agreement among raft nodes before linearized reading' (duration: 155.873014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.605698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.223822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:16:05.605808Z","caller":"traceutil/trace.go:171","msg":"trace[1561396678] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:12300; }","duration":"190.902712ms","start":"2026-10-27T00:16:05.414891Z","end":"2026-10-27T00:16:05.605793Z","steps":["trace[1561396678] 'agreement among raft nodes before linearized reading' (duration: 190.221093ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:16:05.911707Z","caller":"traceutil/trace.go:171","msg":"trace[1153836135] transaction","detail":"{read_only:false; response_revision:12301; number_of_response:1; }","duration":"301.847348ms","start":"2026-10-27T00:16:05.609834Z","end":"2026-10-27T00:16:05.911681Z","steps":["trace[1153836135] 'process raft request' (duration: 271.651027ms)","trace[1153836135] 'compare' (duration: 30.022466ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:16:05.911848Z","caller":"traceutil/trace.go:171","msg":"trace[1069629438] transaction","detail":"{read_only:false; response_revision:12302; number_of_response:1; }","duration":"301.54135ms","start":"2026-10-27T00:16:05.610277Z","end":"2026-10-27T00:16:05.911818Z","steps":["trace[1069629438] 'process raft request' (duration: 301.367095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.911891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:16:05.609796Z","time spent":"301.996653ms","remote":"127.0.0.1:38174","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-10-27T00:16:05.911988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:16:05.610258Z","time spent":"301.658843ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:16:40.785414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10368} {"level":"info","ts":"2026-10-27T00:16:40.873084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10368,"took":"85.613713ms","hash":876684011,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":25628672,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-27T00:16:40.873167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":876684011,"revision":10368,"compact-revision":8002} {"level":"info","ts":"2026-10-27T00:21:40.794703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12532} {"level":"info","ts":"2026-10-27T00:21:40.884084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12532,"took":"86.675208ms","hash":433578003,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":27799552,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T00:21:40.884153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":433578003,"revision":12532,"compact-revision":10368} {"level":"info","ts":"2026-10-27T00:22:12.391965Z","caller":"traceutil/trace.go:171","msg":"trace[1847013814] transaction","detail":"{read_only:false; response_revision:14779; number_of_response:1; }","duration":"122.019646ms","start":"2026-10-27T00:22:12.269908Z","end":"2026-10-27T00:22:12.391927Z","steps":["trace[1847013814] 'process raft request' (duration: 121.884742ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:32.635152Z","caller":"traceutil/trace.go:171","msg":"trace[1112751200] transaction","detail":"{read_only:false; response_revision:14884; number_of_response:1; }","duration":"161.405103ms","start":"2026-10-27T00:22:32.473718Z","end":"2026-10-27T00:22:32.635123Z","steps":["trace[1112751200] 'process raft request' (duration: 161.362692ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:32.635174Z","caller":"traceutil/trace.go:171","msg":"trace[1074420694] transaction","detail":"{read_only:false; response_revision:14882; number_of_response:1; }","duration":"164.221966ms","start":"2026-10-27T00:22:32.470909Z","end":"2026-10-27T00:22:32.635131Z","steps":["trace[1074420694] 'process raft request' (duration: 163.966788ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:32.635288Z","caller":"traceutil/trace.go:171","msg":"trace[1018136109] transaction","detail":"{read_only:false; response_revision:14883; number_of_response:1; }","duration":"161.905468ms","start":"2026-10-27T00:22:32.473272Z","end":"2026-10-27T00:22:32.635178Z","steps":["trace[1018136109] 'process raft request' (duration: 161.768384ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:32.960455Z","caller":"traceutil/trace.go:171","msg":"trace[1122635002] transaction","detail":"{read_only:false; response_revision:14885; number_of_response:1; }","duration":"140.629711ms","start":"2026-10-27T00:22:32.819803Z","end":"2026-10-27T00:22:32.960433Z","steps":["trace[1122635002] 'process raft request' (duration: 140.492727ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:49.878849Z","caller":"traceutil/trace.go:171","msg":"trace[1063810807] transaction","detail":"{read_only:false; response_revision:14987; number_of_response:1; }","duration":"141.871578ms","start":"2026-10-27T00:22:49.736944Z","end":"2026-10-27T00:22:49.878815Z","steps":["trace[1063810807] 'process raft request' (duration: 141.675252ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:50.179457Z","caller":"traceutil/trace.go:171","msg":"trace[58656394] linearizableReadLoop","detail":"{readStateIndex:15512; appliedIndex:15511; }","duration":"166.272277ms","start":"2026-10-27T00:22:50.013159Z","end":"2026-10-27T00:22:50.179431Z","steps":["trace[58656394] 'read index received' (duration: 162.530806ms)","trace[58656394] 'applied index is now lower than readState.Index' (duration: 3.739981ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:22:50.179749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.061904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hf2vl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-27T00:22:50.179788Z","caller":"traceutil/trace.go:171","msg":"trace[401743869] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hf2vl; range_end:; response_count:1; response_revision:14987; }","duration":"158.152947ms","start":"2026-10-27T00:22:50.021624Z","end":"2026-10-27T00:22:50.179777Z","steps":["trace[401743869] 'agreement among raft nodes before linearized reading' (duration: 157.974342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:22:50.179841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.726502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-27T00:22:50.179953Z","caller":"traceutil/trace.go:171","msg":"trace[249933683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14987; }","duration":"125.895237ms","start":"2026-10-27T00:22:50.054038Z","end":"2026-10-27T00:22:50.179934Z","steps":["trace[249933683] 'agreement among raft nodes before linearized reading' (duration: 125.608629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:22:50.18025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.056129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:22:50.180345Z","caller":"traceutil/trace.go:171","msg":"trace[391676866] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:14987; }","duration":"167.203824ms","start":"2026-10-27T00:22:50.013121Z","end":"2026-10-27T00:22:50.180324Z","steps":["trace[391676866] 'agreement among raft nodes before linearized reading' (duration: 166.452732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:22:50.180601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.473305ms","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-10-27T00:22:50.180721Z","caller":"traceutil/trace.go:171","msg":"trace[23131617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14987; }","duration":"126.606568ms","start":"2026-10-27T00:22:50.054073Z","end":"2026-10-27T00:22:50.18068Z","steps":["trace[23131617] 'agreement among raft nodes before linearized reading' (duration: 125.609229ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:23:13.859833Z","caller":"traceutil/trace.go:171","msg":"trace[1173500471] linearizableReadLoop","detail":"{readStateIndex:15842; appliedIndex:15841; }","duration":"132.689607ms","start":"2026-10-27T00:23:13.727115Z","end":"2026-10-27T00:23:13.859805Z","steps":["trace[1173500471] 'read index received' (duration: 132.676677ms)","trace[1173500471] 'applied index is now lower than readState.Index' (duration: 10.77µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:23:13.859848Z","caller":"traceutil/trace.go:171","msg":"trace[1478059030] transaction","detail":"{read_only:false; response_revision:15304; number_of_response:1; }","duration":"136.034055ms","start":"2026-10-27T00:23:13.723782Z","end":"2026-10-27T00:23:13.859816Z","steps":["trace[1478059030] 'process raft request' (duration: 135.695665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:23:13.860128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.974385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-27T00:23:13.860161Z","caller":"traceutil/trace.go:171","msg":"trace[406843741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15304; }","duration":"133.059498ms","start":"2026-10-27T00:23:13.727091Z","end":"2026-10-27T00:23:13.86015Z","steps":["trace[406843741] 'agreement among raft nodes before linearized reading' (duration: 132.854332ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:23:13.891492Z","caller":"traceutil/trace.go:171","msg":"trace[1459094866] transaction","detail":"{read_only:false; response_revision:15305; number_of_response:1; }","duration":"140.100236ms","start":"2026-10-27T00:23:13.751317Z","end":"2026-10-27T00:23:13.891417Z","steps":["trace[1459094866] 'process raft request' (duration: 139.947611ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:23:13.980087Z","caller":"traceutil/trace.go:171","msg":"trace[1798106224] transaction","detail":"{read_only:false; response_revision:15306; number_of_response:1; }","duration":"115.236823ms","start":"2026-10-27T00:23:13.864818Z","end":"2026-10-27T00:23:13.980054Z","steps":["trace[1798106224] 'process raft request' (duration: 114.78932ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:25.777994Z","caller":"traceutil/trace.go:171","msg":"trace[1818773970] transaction","detail":"{read_only:false; response_revision:16046; number_of_response:1; }","duration":"110.689751ms","start":"2026-10-27T00:24:25.667273Z","end":"2026-10-27T00:24:25.777963Z","steps":["trace[1818773970] 'process raft request' (duration: 110.154606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:26.956519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.752125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T00:24:26.956673Z","caller":"traceutil/trace.go:171","msg":"trace[2031886739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16049; }","duration":"232.980922ms","start":"2026-10-27T00:24:26.723667Z","end":"2026-10-27T00:24:26.956648Z","steps":["trace[2031886739] 'range keys from in-memory index tree' (duration: 232.56296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:26.956721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.713116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-27T00:24:26.956823Z","caller":"traceutil/trace.go:171","msg":"trace[1674819540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16049; }","duration":"223.85088ms","start":"2026-10-27T00:24:26.732949Z","end":"2026-10-27T00:24:26.9568Z","steps":["trace[1674819540] 'range keys from in-memory index tree' (duration: 223.49601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:26.957071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.177284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-27T00:24:26.957123Z","caller":"traceutil/trace.go:171","msg":"trace[1960900086] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16049; }","duration":"230.270066ms","start":"2026-10-27T00:24:26.72684Z","end":"2026-10-27T00:24:26.95711Z","steps":["trace[1960900086] 'range keys from in-memory index tree' (duration: 230.04124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:26.957113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.713149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:24:26.95718Z","caller":"traceutil/trace.go:171","msg":"trace[1170609192] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16049; }","duration":"230.800691ms","start":"2026-10-27T00:24:26.72636Z","end":"2026-10-27T00:24:26.957161Z","steps":["trace[1170609192] 'range keys from in-memory index tree' (duration: 230.653167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:26.957167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.326471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-27T00:24:26.957256Z","caller":"traceutil/trace.go:171","msg":"trace[989861870] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16049; }","duration":"229.455824ms","start":"2026-10-27T00:24:26.727777Z","end":"2026-10-27T00:24:26.957233Z","steps":["trace[989861870] 'range keys from in-memory index tree' (duration: 229.163326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:27.176976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.367161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T00:24:27.177208Z","caller":"traceutil/trace.go:171","msg":"trace[1770969465] transaction","detail":"{read_only:false; response_revision:16053; number_of_response:1; }","duration":"139.289059ms","start":"2026-10-27T00:24:27.037866Z","end":"2026-10-27T00:24:27.177155Z","steps":["trace[1770969465] 'process raft request' (duration: 36.65303ms)","trace[1770969465] 'compare' (duration: 102.024812ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:24:27.177253Z","caller":"traceutil/trace.go:171","msg":"trace[1472189468] linearizableReadLoop","detail":"{readStateIndex:16610; appliedIndex:16609; }","duration":"126.163807ms","start":"2026-10-27T00:24:27.051069Z","end":"2026-10-27T00:24:27.177233Z","steps":["trace[1472189468] 'read index received' (duration: 23.433776ms)","trace[1472189468] 'applied index is now lower than readState.Index' (duration: 102.728521ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:24:27.177457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.37915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-27T00:24:27.177491Z","caller":"traceutil/trace.go:171","msg":"trace[1118273047] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16054; }","duration":"160.413851ms","start":"2026-10-27T00:24:27.017066Z","end":"2026-10-27T00:24:27.17748Z","steps":["trace[1118273047] 'agreement among raft nodes before linearized reading' (duration: 160.217025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:27.177478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.213303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-27T00:24:27.177546Z","caller":"traceutil/trace.go:171","msg":"trace[1326099360] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16054; }","duration":"157.320536ms","start":"2026-10-27T00:24:27.020207Z","end":"2026-10-27T00:24:27.177527Z","steps":["trace[1326099360] 'agreement among raft nodes before linearized reading' (duration: 157.10089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:27.17759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.769223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:24:27.177624Z","caller":"traceutil/trace.go:171","msg":"trace[486707984] transaction","detail":"{read_only:false; response_revision:16054; number_of_response:1; }","duration":"122.417894ms","start":"2026-10-27T00:24:27.055186Z","end":"2026-10-27T00:24:27.177604Z","steps":["trace[486707984] 'process raft request' (duration: 121.931101ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:27.17766Z","caller":"traceutil/trace.go:171","msg":"trace[1986132743] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16054; }","duration":"159.903957ms","start":"2026-10-27T00:24:27.01774Z","end":"2026-10-27T00:24:27.177644Z","steps":["trace[1986132743] 'agreement among raft nodes before linearized reading' (duration: 159.735753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:27.177681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.204885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-27T00:24:27.177779Z","caller":"traceutil/trace.go:171","msg":"trace[2019920734] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16054; }","duration":"127.345489ms","start":"2026-10-27T00:24:27.050407Z","end":"2026-10-27T00:24:27.177753Z","steps":["trace[2019920734] 'agreement among raft nodes before linearized reading' (duration: 127.071342ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:27.177976Z","caller":"traceutil/trace.go:171","msg":"trace[1579035661] transaction","detail":"{read_only:false; response_revision:16055; number_of_response:1; }","duration":"109.313822ms","start":"2026-10-27T00:24:27.068647Z","end":"2026-10-27T00:24:27.177961Z","steps":["trace[1579035661] 'process raft request' (duration: 108.553011ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:27.189262Z","caller":"traceutil/trace.go:171","msg":"trace[956009868] transaction","detail":"{read_only:false; response_revision:16056; number_of_response:1; }","duration":"114.338002ms","start":"2026-10-27T00:24:27.07489Z","end":"2026-10-27T00:24:27.189228Z","steps":["trace[956009868] 'process raft request' (duration: 114.058154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:27.189298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.858455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-hgpgt\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-27T00:24:27.189469Z","caller":"traceutil/trace.go:171","msg":"trace[1585756338] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-hgpgt; range_end:; response_count:1; response_revision:16056; }","duration":"110.957618ms","start":"2026-10-27T00:24:27.078381Z","end":"2026-10-27T00:24:27.189339Z","steps":["trace[1585756338] 'agreement among raft nodes before linearized reading' (duration: 110.798053ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:26:40.803777Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14568} {"level":"info","ts":"2026-10-27T00:26:40.906295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14568,"took":"99.039285ms","hash":3246420941,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":31768576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T00:26:40.906375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3246420941,"revision":14568,"compact-revision":12532} {"level":"info","ts":"2026-10-27T00:30:37.852295Z","caller":"traceutil/trace.go:171","msg":"trace[1558975385] transaction","detail":"{read_only:false; response_revision:18738; number_of_response:1; }","duration":"161.714815ms","start":"2026-10-27T00:30:37.690552Z","end":"2026-10-27T00:30:37.852266Z","steps":["trace[1558975385] 'process raft request' (duration: 161.569541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:44.456256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.267211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:30:44.456373Z","caller":"traceutil/trace.go:171","msg":"trace[1792116573] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18781; }","duration":"102.424715ms","start":"2026-10-27T00:30:44.353917Z","end":"2026-10-27T00:30:44.456342Z","steps":["trace[1792116573] 'range keys from in-memory index tree' (duration: 102.210668ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:44.523027Z","caller":"traceutil/trace.go:171","msg":"trace[785139481] transaction","detail":"{read_only:false; response_revision:18782; number_of_response:1; }","duration":"115.730172ms","start":"2026-10-27T00:30:44.407269Z","end":"2026-10-27T00:30:44.522999Z","steps":["trace[785139481] 'process raft request' (duration: 115.558337ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:44.923311Z","caller":"traceutil/trace.go:171","msg":"trace[692806738] transaction","detail":"{read_only:false; response_revision:18785; number_of_response:1; }","duration":"309.839584ms","start":"2026-10-27T00:30:44.613436Z","end":"2026-10-27T00:30:44.923276Z","steps":["trace[692806738] 'process raft request' (duration: 298.086709ms)","trace[692806738] 'compare' (duration: 11.593481ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:30:44.923497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:30:44.613406Z","time spent":"309.980588ms","remote":"127.0.0.1:38174","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-10-27T00:30:44.93714Z","caller":"traceutil/trace.go:171","msg":"trace[1051910262] transaction","detail":"{read_only:false; response_revision:18786; number_of_response:1; }","duration":"323.650416ms","start":"2026-10-27T00:30:44.613471Z","end":"2026-10-27T00:30:44.937121Z","steps":["trace[1051910262] 'process raft request' (duration: 323.503682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:44.937244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:30:44.613443Z","time spent":"323.750719ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:30:44.937457Z","caller":"traceutil/trace.go:171","msg":"trace[2124317409] linearizableReadLoop","detail":"{readStateIndex:19434; appliedIndex:19431; }","duration":"206.947167ms","start":"2026-10-27T00:30:44.730486Z","end":"2026-10-27T00:30:44.937434Z","steps":["trace[2124317409] 'read index received' (duration: 181.325098ms)","trace[2124317409] 'applied index is now lower than readState.Index' (duration: 25.619569ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:30:44.937495Z","caller":"traceutil/trace.go:171","msg":"trace[418169174] transaction","detail":"{read_only:false; response_revision:18787; number_of_response:1; }","duration":"315.956303ms","start":"2026-10-27T00:30:44.621523Z","end":"2026-10-27T00:30:44.937479Z","steps":["trace[418169174] 'process raft request' (duration: 315.551602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:44.937583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:30:44.621508Z","time spent":"316.028816ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:30:44.937742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.194313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-27T00:30:44.937796Z","caller":"traceutil/trace.go:171","msg":"trace[143232176] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:18787; }","duration":"207.348277ms","start":"2026-10-27T00:30:44.730433Z","end":"2026-10-27T00:30:44.937781Z","steps":["trace[143232176] 'agreement among raft nodes before linearized reading' (duration: 207.09529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:45.200441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.141042ms","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-10-27T00:30:45.20054Z","caller":"traceutil/trace.go:171","msg":"trace[769590771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18787; }","duration":"104.308746ms","start":"2026-10-27T00:30:45.096207Z","end":"2026-10-27T00:30:45.200516Z","steps":["trace[769590771] 'range keys from in-memory index tree' (duration: 103.919426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:45.200568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.638589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-27T00:30:45.200691Z","caller":"traceutil/trace.go:171","msg":"trace[24799179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18787; }","duration":"107.781513ms","start":"2026-10-27T00:30:45.09289Z","end":"2026-10-27T00:30:45.200672Z","steps":["trace[24799179] 'range keys from in-memory index tree' (duration: 107.403303ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:45.406635Z","caller":"traceutil/trace.go:171","msg":"trace[1187008324] transaction","detail":"{read_only:false; response_revision:18789; number_of_response:1; }","duration":"199.512531ms","start":"2026-10-27T00:30:45.207086Z","end":"2026-10-27T00:30:45.406599Z","steps":["trace[1187008324] 'process raft request' (duration: 171.403474ms)","trace[1187008324] 'compare' (duration: 27.944613ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:31:27.59947Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6d0019d52d78795","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T00:31:27.606911Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-27T00:31:27.607024Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-27T00:31:40.810119Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16947} {"level":"info","ts":"2026-10-27T00:31:40.907668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16947,"took":"93.601254ms","hash":2215866545,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":33087488,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-27T00:31:40.90774Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2215866545,"revision":16947,"compact-revision":14568} {"level":"info","ts":"2026-10-27T00:36:17.780967Z","caller":"traceutil/trace.go:171","msg":"trace[610202316] transaction","detail":"{read_only:false; response_revision:21402; number_of_response:1; }","duration":"115.884542ms","start":"2026-10-27T00:36:17.66506Z","end":"2026-10-27T00:36:17.780944Z","steps":["trace[610202316] 'process raft request' (duration: 115.748779ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:27.98819Z","caller":"traceutil/trace.go:171","msg":"trace[388320731] linearizableReadLoop","detail":"{readStateIndex:22190; appliedIndex:22189; }","duration":"143.393001ms","start":"2026-10-27T00:36:27.844772Z","end":"2026-10-27T00:36:27.988165Z","steps":["trace[388320731] 'read index received' (duration: 143.081932ms)","trace[388320731] 'applied index is now lower than readState.Index' (duration: 310.119µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:36:27.988324Z","caller":"traceutil/trace.go:171","msg":"trace[610768887] transaction","detail":"{read_only:false; response_revision:21454; number_of_response:1; }","duration":"152.030739ms","start":"2026-10-27T00:36:27.83625Z","end":"2026-10-27T00:36:27.988281Z","steps":["trace[610768887] 'process raft request' (duration: 151.674149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:27.988441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.210438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-27T00:36:27.988434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.634847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T00:36:27.988489Z","caller":"traceutil/trace.go:171","msg":"trace[1277585907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21454; }","duration":"142.345181ms","start":"2026-10-27T00:36:27.846131Z","end":"2026-10-27T00:36:27.988477Z","steps":["trace[1277585907] 'agreement among raft nodes before linearized reading' (duration: 142.193077ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:27.988509Z","caller":"traceutil/trace.go:171","msg":"trace[450922114] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:21454; }","duration":"143.761131ms","start":"2026-10-27T00:36:27.844731Z","end":"2026-10-27T00:36:27.988492Z","steps":["trace[450922114] 'agreement among raft nodes before linearized reading' (duration: 143.589756ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:34.122047Z","caller":"traceutil/trace.go:171","msg":"trace[2115864176] transaction","detail":"{read_only:false; response_revision:21484; number_of_response:1; }","duration":"102.315417ms","start":"2026-10-27T00:36:34.019708Z","end":"2026-10-27T00:36:34.122024Z","steps":["trace[2115864176] 'process raft request' (duration: 101.965607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:34.122387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.097015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-27T00:36:34.122443Z","caller":"traceutil/trace.go:171","msg":"trace[956226860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21484; }","duration":"100.176148ms","start":"2026-10-27T00:36:34.022241Z","end":"2026-10-27T00:36:34.122417Z","steps":["trace[956226860] 'agreement among raft nodes before linearized reading' (duration: 100.020293ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:34.294134Z","caller":"traceutil/trace.go:171","msg":"trace[1588995635] transaction","detail":"{read_only:false; response_revision:21485; number_of_response:1; }","duration":"165.021579ms","start":"2026-10-27T00:36:34.129084Z","end":"2026-10-27T00:36:34.294106Z","steps":["trace[1588995635] 'process raft request' (duration: 150.95251ms)","trace[1588995635] 'compare' (duration: 13.937665ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:36:34.494607Z","caller":"traceutil/trace.go:171","msg":"trace[932384416] linearizableReadLoop","detail":"{readStateIndex:22225; appliedIndex:22222; }","duration":"317.311167ms","start":"2026-10-27T00:36:34.177269Z","end":"2026-10-27T00:36:34.49458Z","steps":["trace[932384416] 'read index received' (duration: 102.78195ms)","trace[932384416] 'applied index is now lower than readState.Index' (duration: 214.527697ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:36:34.494742Z","caller":"traceutil/trace.go:171","msg":"trace[980259274] transaction","detail":"{read_only:false; response_revision:21486; number_of_response:1; }","duration":"355.272045ms","start":"2026-10-27T00:36:34.139426Z","end":"2026-10-27T00:36:34.494698Z","steps":["trace[980259274] 'process raft request' (duration: 311.928437ms)","trace[980259274] 'compare' (duration: 43.023309ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:36:34.494825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.537233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-27T00:36:34.494797Z","caller":"traceutil/trace.go:171","msg":"trace[2014100709] transaction","detail":"{read_only:false; response_revision:21487; number_of_response:1; }","duration":"317.870222ms","start":"2026-10-27T00:36:34.176871Z","end":"2026-10-27T00:36:34.494741Z","steps":["trace[2014100709] 'process raft request' (duration: 317.647216ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:34.494859Z","caller":"traceutil/trace.go:171","msg":"trace[340680553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21487; }","duration":"317.586704ms","start":"2026-10-27T00:36:34.177263Z","end":"2026-10-27T00:36:34.49485Z","steps":["trace[340680553] 'agreement among raft nodes before linearized reading' (duration: 317.43929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:34.494903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:36:34.177247Z","time spent":"317.648675ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-27T00:36:34.494927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:36:34.139403Z","time spent":"355.42965ms","remote":"127.0.0.1:37972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":887,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:36:34.495013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:36:34.176851Z","time spent":"318.052557ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:36:34.552223Z","caller":"traceutil/trace.go:171","msg":"trace[1338982283] transaction","detail":"{read_only:false; response_revision:21488; number_of_response:1; }","duration":"262.117131ms","start":"2026-10-27T00:36:34.290081Z","end":"2026-10-27T00:36:34.552198Z","steps":["trace[1338982283] 'process raft request' (duration: 261.667099ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:34.552321Z","caller":"traceutil/trace.go:171","msg":"trace[1312478232] transaction","detail":"{read_only:false; response_revision:21489; number_of_response:1; }","duration":"258.395029ms","start":"2026-10-27T00:36:34.293888Z","end":"2026-10-27T00:36:34.552283Z","steps":["trace[1312478232] 'process raft request' (duration: 257.988467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:34.552477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.248507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.210\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-27T00:36:34.552556Z","caller":"traceutil/trace.go:171","msg":"trace[1913862696] range","detail":"{range_begin:/registry/masterleases/162.253.55.210; range_end:; response_count:1; response_revision:21489; }","duration":"118.379671ms","start":"2026-10-27T00:36:34.434155Z","end":"2026-10-27T00:36:34.552535Z","steps":["trace[1913862696] 'agreement among raft nodes before linearized reading' (duration: 118.092223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:34.552477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.445615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-27T00:36:34.552802Z","caller":"traceutil/trace.go:171","msg":"trace[1791046653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21489; }","duration":"214.729702ms","start":"2026-10-27T00:36:34.338014Z","end":"2026-10-27T00:36:34.552744Z","steps":["trace[1791046653] 'agreement among raft nodes before linearized reading' (duration: 214.337681ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:34.663401Z","caller":"traceutil/trace.go:171","msg":"trace[1686075671] linearizableReadLoop","detail":"{readStateIndex:22228; appliedIndex:22227; }","duration":"111.461309ms","start":"2026-10-27T00:36:34.551917Z","end":"2026-10-27T00:36:34.663378Z","steps":["trace[1686075671] 'read index received' (duration: 111.351096ms)","trace[1686075671] 'applied index is now lower than readState.Index' (duration: 109.023µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:36:34.663453Z","caller":"traceutil/trace.go:171","msg":"trace[597535288] transaction","detail":"{read_only:false; response_revision:21490; number_of_response:1; }","duration":"163.731103ms","start":"2026-10-27T00:36:34.499692Z","end":"2026-10-27T00:36:34.663423Z","steps":["trace[597535288] 'process raft request' (duration: 163.492377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:34.663621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.541646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-cfn-c5ccb8dcd-mxmc2.18e23bb8396d0b55\" ","response":"range_response_count:1 size:903"} {"level":"info","ts":"2026-10-27T00:36:34.663687Z","caller":"traceutil/trace.go:171","msg":"trace[1903075410] range","detail":"{range_begin:/registry/events/openstack/heat-cfn-c5ccb8dcd-mxmc2.18e23bb8396d0b55; range_end:; response_count:1; response_revision:21490; }","duration":"164.647368ms","start":"2026-10-27T00:36:34.499023Z","end":"2026-10-27T00:36:34.663671Z","steps":["trace[1903075410] 'agreement among raft nodes before linearized reading' (duration: 164.433162ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:34.66494Z","caller":"traceutil/trace.go:171","msg":"trace[1869214923] transaction","detail":"{read_only:false; response_revision:21491; number_of_response:1; }","duration":"103.117429ms","start":"2026-10-27T00:36:34.561811Z","end":"2026-10-27T00:36:34.664929Z","steps":["trace[1869214923] 'process raft request' (duration: 102.733938ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:40.816558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19435} {"level":"info","ts":"2026-10-27T00:36:40.96778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19435,"took":"147.101904ms","hash":4157778819,"current-db-size-bytes":56991744,"current-db-size":"57 MB","current-db-size-in-use-bytes":29712384,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-27T00:36:40.967852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4157778819,"revision":19435,"compact-revision":16947} {"level":"info","ts":"2026-10-27T00:40:27.811519Z","caller":"traceutil/trace.go:171","msg":"trace[359634141] transaction","detail":"{read_only:false; response_revision:23850; number_of_response:1; }","duration":"105.827972ms","start":"2026-10-27T00:40:27.705662Z","end":"2026-10-27T00:40:27.81149Z","steps":["trace[359634141] 'process raft request' (duration: 105.573995ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:40:34.133657Z","caller":"traceutil/trace.go:171","msg":"trace[1972418788] transaction","detail":"{read_only:false; response_revision:24011; number_of_response:1; }","duration":"181.774504ms","start":"2026-10-27T00:40:33.951861Z","end":"2026-10-27T00:40:34.133635Z","steps":["trace[1972418788] 'process raft request' (duration: 171.179716ms)","trace[1972418788] 'compare' (duration: 10.474395ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:40:34.133641Z","caller":"traceutil/trace.go:171","msg":"trace[2115848964] linearizableReadLoop","detail":"{readStateIndex:24824; appliedIndex:24823; }","duration":"159.424077ms","start":"2026-10-27T00:40:33.974193Z","end":"2026-10-27T00:40:34.133617Z","steps":["trace[2115848964] 'read index received' (duration: 148.781828ms)","trace[2115848964] 'applied index is now lower than readState.Index' (duration: 10.641039ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:40:34.133803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.607532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-27T00:40:34.133845Z","caller":"traceutil/trace.go:171","msg":"trace[1672939293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24011; }","duration":"159.711474ms","start":"2026-10-27T00:40:33.974125Z","end":"2026-10-27T00:40:34.133836Z","steps":["trace[1672939293] 'agreement among raft nodes before linearized reading' (duration: 159.56684ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:41:40.826128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21524} {"level":"info","ts":"2026-10-27T00:41:40.935008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21524,"took":"105.428751ms","hash":894432369,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":43360256,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-27T00:41:40.935082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":894432369,"revision":21524,"compact-revision":19435} {"level":"info","ts":"2026-10-27T00:43:02.341848Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066d9.wal"} {"level":"info","ts":"2026-10-27T00:43:59.64964Z","caller":"traceutil/trace.go:171","msg":"trace[294809116] linearizableReadLoop","detail":"{readStateIndex:26861; appliedIndex:26860; }","duration":"122.236151ms","start":"2026-10-27T00:43:59.527381Z","end":"2026-10-27T00:43:59.649617Z","steps":["trace[294809116] 'read index received' (duration: 111.785283ms)","trace[294809116] 'applied index is now lower than readState.Index' (duration: 10.450038ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:43:59.649687Z","caller":"traceutil/trace.go:171","msg":"trace[1934339940] transaction","detail":"{read_only:false; response_revision:25996; number_of_response:1; }","duration":"134.222462ms","start":"2026-10-27T00:43:59.515429Z","end":"2026-10-27T00:43:59.649652Z","steps":["trace[1934339940] 'process raft request' (duration: 123.677891ms)","trace[1934339940] 'compare' (duration: 10.311265ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:43:59.649761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.369125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:43:59.649791Z","caller":"traceutil/trace.go:171","msg":"trace[994471219] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25996; }","duration":"122.449957ms","start":"2026-10-27T00:43:59.527331Z","end":"2026-10-27T00:43:59.649781Z","steps":["trace[994471219] 'agreement among raft nodes before linearized reading' (duration: 122.375115ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:44:00.141899Z","caller":"traceutil/trace.go:171","msg":"trace[890089262] transaction","detail":"{read_only:false; response_revision:25998; number_of_response:1; }","duration":"304.072376ms","start":"2026-10-27T00:43:59.837801Z","end":"2026-10-27T00:44:00.141874Z","steps":["trace[890089262] 'process raft request' (duration: 303.903252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:44:00.14206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:43:59.837781Z","time spent":"304.190149ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:45:03.763518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.396948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T00:45:03.76362Z","caller":"traceutil/trace.go:171","msg":"trace[2033692146] linearizableReadLoop","detail":"{readStateIndex:27467; appliedIndex:27465; }","duration":"242.063596ms","start":"2026-10-27T00:45:03.521541Z","end":"2026-10-27T00:45:03.763604Z","steps":["trace[2033692146] 'read index received' (duration: 11.202159ms)","trace[2033692146] 'applied index is now lower than readState.Index' (duration: 230.860277ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:45:03.763641Z","caller":"traceutil/trace.go:171","msg":"trace[1483355151] transaction","detail":"{read_only:false; response_revision:26587; number_of_response:1; }","duration":"251.948519ms","start":"2026-10-27T00:45:03.511665Z","end":"2026-10-27T00:45:03.763614Z","steps":["trace[1483355151] 'process raft request' (duration: 134.356955ms)","trace[1483355151] 'compare' (duration: 116.989957ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:45:03.76378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.239401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-27T00:45:03.763804Z","caller":"traceutil/trace.go:171","msg":"trace[1378013131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26587; }","duration":"242.325654ms","start":"2026-10-27T00:45:03.52147Z","end":"2026-10-27T00:45:03.763796Z","steps":["trace[1378013131] 'agreement among raft nodes before linearized reading' (duration: 242.20909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:45:03.764376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.527329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-27T00:45:03.764354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.9755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:45:03.764591Z","caller":"traceutil/trace.go:171","msg":"trace[744569329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26587; }","duration":"198.296919ms","start":"2026-10-27T00:45:03.566261Z","end":"2026-10-27T00:45:03.764558Z","steps":["trace[744569329] 'agreement among raft nodes before linearized reading' (duration: 197.887747ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:45:03.764459Z","caller":"traceutil/trace.go:171","msg":"trace[1591544087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26587; }","duration":"238.663792ms","start":"2026-10-27T00:45:03.525773Z","end":"2026-10-27T00:45:03.764437Z","steps":["trace[1591544087] 'agreement among raft nodes before linearized reading' (duration: 238.366364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:45:04.051994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.854172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T00:45:04.052194Z","caller":"traceutil/trace.go:171","msg":"trace[495365155] linearizableReadLoop","detail":"{readStateIndex:27469; appliedIndex:27467; }","duration":"247.181047ms","start":"2026-10-27T00:45:03.804994Z","end":"2026-10-27T00:45:04.052175Z","steps":["trace[495365155] 'read index received' (duration: 109.051567ms)","trace[495365155] 'applied index is now lower than readState.Index' (duration: 138.12798ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:45:04.052379Z","caller":"traceutil/trace.go:171","msg":"trace[1957140226] transaction","detail":"{read_only:false; response_revision:26589; number_of_response:1; }","duration":"282.562513ms","start":"2026-10-27T00:45:03.769777Z","end":"2026-10-27T00:45:04.05234Z","steps":["trace[1957140226] 'process raft request' (duration: 282.335656ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:45:04.052542Z","caller":"traceutil/trace.go:171","msg":"trace[1580864596] transaction","detail":"{read_only:false; response_revision:26588; number_of_response:1; }","duration":"284.333772ms","start":"2026-10-27T00:45:03.768197Z","end":"2026-10-27T00:45:04.05253Z","steps":["trace[1580864596] 'process raft request' (duration: 145.844502ms)","trace[1580864596] 'compare' (duration: 137.724369ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:45:04.052565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.551828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:45:04.052682Z","caller":"traceutil/trace.go:171","msg":"trace[1034665152] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:26589; }","duration":"247.688181ms","start":"2026-10-27T00:45:03.804974Z","end":"2026-10-27T00:45:04.052662Z","steps":["trace[1034665152] 'agreement among raft nodes before linearized reading' (duration: 247.476036ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:45:04.194934Z","caller":"traceutil/trace.go:171","msg":"trace[169067838] linearizableReadLoop","detail":"{readStateIndex:27470; appliedIndex:27469; }","duration":"142.686316ms","start":"2026-10-27T00:45:04.052224Z","end":"2026-10-27T00:45:04.19491Z","steps":["trace[169067838] 'read index received' (duration: 142.536941ms)","trace[169067838] 'applied index is now lower than readState.Index' (duration: 148.534µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:45:04.194962Z","caller":"traceutil/trace.go:171","msg":"trace[74615510] transaction","detail":"{read_only:false; response_revision:26590; number_of_response:1; }","duration":"186.642547ms","start":"2026-10-27T00:45:04.008291Z","end":"2026-10-27T00:45:04.194933Z","steps":["trace[74615510] 'process raft request' (duration: 186.482403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:45:04.195138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.775276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-27T00:45:04.195201Z","caller":"traceutil/trace.go:171","msg":"trace[1200245498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26590; }","duration":"316.91859ms","start":"2026-10-27T00:45:03.878273Z","end":"2026-10-27T00:45:04.195191Z","steps":["trace[1200245498] 'agreement among raft nodes before linearized reading' (duration: 316.728315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:45:04.195236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:45:03.87825Z","time spent":"316.977812ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-27T00:45:04.195441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.673822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T00:45:04.195559Z","caller":"traceutil/trace.go:171","msg":"trace[350004305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26590; }","duration":"193.859217ms","start":"2026-10-27T00:45:04.00168Z","end":"2026-10-27T00:45:04.195539Z","steps":["trace[350004305] 'agreement among raft nodes before linearized reading' (duration: 193.364233ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:46:40.834225Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24759} {"level":"info","ts":"2026-10-27T00:46:41.012665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24759,"took":"166.128838ms","hash":364414632,"current-db-size-bytes":59539456,"current-db-size":"60 MB","current-db-size-in-use-bytes":36208640,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-27T00:46:41.012757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":364414632,"revision":24759,"compact-revision":21524} {"level":"info","ts":"2026-10-27T00:49:14.114512Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6d0019d52d78795","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T00:49:14.121958Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-27T00:49:14.122096Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-27T00:51:40.847175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27542} {"level":"info","ts":"2026-10-27T00:51:40.987574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27542,"took":"131.08113ms","hash":2985640443,"current-db-size-bytes":59539456,"current-db-size":"60 MB","current-db-size-in-use-bytes":37171200,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-27T00:51:40.987667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2985640443,"revision":27542,"compact-revision":24759} {"level":"info","ts":"2026-10-27T00:56:40.858604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30199} {"level":"info","ts":"2026-10-27T00:56:41.000137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30199,"took":"138.74055ms","hash":1842446554,"current-db-size-bytes":59539456,"current-db-size":"60 MB","current-db-size-in-use-bytes":31354880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T00:56:41.000187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1842446554,"revision":30199,"compact-revision":27542} {"level":"info","ts":"2026-10-27T01:01:40.865901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32454} {"level":"info","ts":"2026-10-27T01:01:41.023795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32454,"took":"153.13562ms","hash":3051508485,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":31322112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T01:01:41.023862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3051508485,"revision":32454,"compact-revision":30199} {"level":"info","ts":"2026-10-27T01:06:40.885043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34753} {"level":"info","ts":"2026-10-27T01:06:41.031013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34753,"took":"141.834098ms","hash":955657253,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":32514048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T01:06:41.031143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":955657253,"revision":34753,"compact-revision":32454} {"level":"info","ts":"2026-10-27T01:10:23.856526Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6d0019d52d78795","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T01:10:23.862587Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-27T01:10:23.862812Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-27T01:11:40.895644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37079} {"level":"info","ts":"2026-10-27T01:11:41.041806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37079,"took":"142.167008ms","hash":1829868677,"current-db-size-bytes":61059072,"current-db-size":"61 MB","current-db-size-in-use-bytes":33538048,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T01:11:41.041875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1829868677,"revision":37079,"compact-revision":34753} {"level":"info","ts":"2026-10-27T01:16:40.902178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39359} {"level":"info","ts":"2026-10-27T01:16:41.037471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39359,"took":"131.370593ms","hash":384149343,"current-db-size-bytes":62754816,"current-db-size":"63 MB","current-db-size-in-use-bytes":32079872,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T01:16:41.03753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":384149343,"revision":39359,"compact-revision":37079}