{"level":"warn","ts":"2026-09-19T10:20:35.712335Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T10:20:35.712441Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T10:20:35.712517Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T10:20:35.712523Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-09-19T10:20:35.712541Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T10:20:35.713237Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-09-19T10:20:35.713313Z","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":8,"max-cpu-available":8,"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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T10:20:35.720547Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.976339ms"} {"level":"info","ts":"2026-09-19T10:20:35.735125Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-09-19T10:20:35.7352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T10:20:35.735223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-09-19T10:20:35.735235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T10:20:35.735248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-09-19T10:20:35.73531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-09-19T10:20:35.746201Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T10:20:35.748729Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T10:20:35.751602Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T10:20:35.755368Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T10:20:35.75848Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T10:20:35.758637Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:20:35.758694Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:20:35.758703Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:20:35.758996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-09-19T10:20:35.759235Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-09-19T10:20:35.762298Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T10:20:35.762444Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-09-19T10:20:35.7625Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-09-19T10:20:35.762578Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T10:20:35.762607Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T10:20:36.136179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T10:20:36.136316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T10:20:36.136413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-09-19T10:20:36.136447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-09-19T10:20:36.136459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-09-19T10:20:36.136519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-09-19T10:20:36.136534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-09-19T10:20:36.137574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:20:36.138162Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T10:20:36.138164Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:20:36.138193Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:20:36.138661Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:20:36.138908Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:20:36.138953Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:20:36.142156Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-09-19T10:20:36.142351Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T10:20:36.14238Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T10:20:36.142495Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-09-19T10:20:41.691904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.007757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:20:41.692073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.171563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:20:41.692158Z","caller":"traceutil/trace.go:171","msg":"trace[1149820335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:281; }","duration":"318.281873ms","start":"2026-09-19T10:20:41.373849Z","end":"2026-09-19T10:20:41.692131Z","steps":["trace[1149820335] 'range keys from in-memory index tree' (duration: 317.908764ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:20:41.692161Z","caller":"traceutil/trace.go:171","msg":"trace[1265888210] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:281; }","duration":"319.298376ms","start":"2026-09-19T10:20:41.372841Z","end":"2026-09-19T10:20:41.692139Z","steps":["trace[1265888210] 'range keys from in-memory index tree' (duration: 319.05603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:41.692231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.372824Z","time spent":"319.397148ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-09-19T10:20:41.692222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.373792Z","time spent":"318.416677ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T10:20:41.69262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.757182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-09-19T10:20:41.692708Z","caller":"traceutil/trace.go:171","msg":"trace[743373635] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:281; }","duration":"326.927445ms","start":"2026-09-19T10:20:41.365761Z","end":"2026-09-19T10:20:41.692689Z","steps":["trace[743373635] 'range keys from in-memory index tree' (duration: 326.009605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:41.692792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.365735Z","time spent":"327.045748ms","remote":"127.0.0.1:37018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-09-19T10:20:41.693102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.136965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:20:41.693194Z","caller":"traceutil/trace.go:171","msg":"trace[1959422632] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:287; }","duration":"213.4626ms","start":"2026-09-19T10:20:41.479714Z","end":"2026-09-19T10:20:41.693177Z","steps":["trace[1959422632] 'read index received' (duration: 38.454436ms)","trace[1959422632] 'applied index is now lower than readState.Index' (duration: 175.006514ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:20:41.693217Z","caller":"traceutil/trace.go:171","msg":"trace[1598359662] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"213.642034ms","start":"2026-09-19T10:20:41.479552Z","end":"2026-09-19T10:20:41.693194Z","steps":["trace[1598359662] 'process raft request' (duration: 38.570239ms)","trace[1598359662] 'compare' (duration: 173.789517ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:20:41.69331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.590393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-19T10:20:41.69337Z","caller":"traceutil/trace.go:171","msg":"trace[2066731091] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:282; }","duration":"213.700346ms","start":"2026-09-19T10:20:41.479654Z","end":"2026-09-19T10:20:41.693354Z","steps":["trace[2066731091] 'agreement among raft nodes before linearized reading' (duration: 213.565333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.231201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.07353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-09-19T10:20:42.231551Z","caller":"traceutil/trace.go:171","msg":"trace[110445979] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"649.353319ms","start":"2026-09-19T10:20:41.582177Z","end":"2026-09-19T10:20:42.231531Z","steps":["trace[110445979] 'process raft request' (duration: 455.026301ms)","trace[110445979] 'compare' (duration: 192.560259ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:20:42.231752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.582151Z","time spent":"649.567384ms","remote":"127.0.0.1:36996","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:20:42.232053Z","caller":"traceutil/trace.go:171","msg":"trace[2131530573] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:288; }","duration":"538.822549ms","start":"2026-09-19T10:20:41.69322Z","end":"2026-09-19T10:20:42.232042Z","steps":["trace[2131530573] 'read index received' (duration: 343.99405ms)","trace[2131530573] 'applied index is now lower than readState.Index' (duration: 194.827399ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:20:42.232128Z","caller":"traceutil/trace.go:171","msg":"trace[448209450] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"630.822597ms","start":"2026-09-19T10:20:41.601261Z","end":"2026-09-19T10:20:42.232084Z","steps":["trace[448209450] 'process raft request' (duration: 630.453889ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:20:42.232172Z","caller":"traceutil/trace.go:171","msg":"trace[407803318] transaction","detail":"{read_only:false; number_of_response:0; response_revision:284; }","duration":"630.423798ms","start":"2026-09-19T10:20:41.601722Z","end":"2026-09-19T10:20:42.232146Z","steps":["trace[407803318] 'process raft request' (duration: 630.138011ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:20:42.232247Z","caller":"traceutil/trace.go:171","msg":"trace[789901897] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"533.982422ms","start":"2026-09-19T10:20:41.698258Z","end":"2026-09-19T10:20:42.232241Z","steps":["trace[789901897] 'process raft request' (duration: 533.733096ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:20:42.232257Z","caller":"traceutil/trace.go:171","msg":"trace[1910519149] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"629.116719ms","start":"2026-09-19T10:20:41.603128Z","end":"2026-09-19T10:20:42.232245Z","steps":["trace[1910519149] 'process raft request' (duration: 628.783812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.232262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.601238Z","time spent":"630.95338ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:20:42.232297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.601711Z","time spent":"630.54465ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:20:42.23205Z","caller":"traceutil/trace.go:171","msg":"trace[1985036400] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"630.686163ms","start":"2026-09-19T10:20:41.601309Z","end":"2026-09-19T10:20:42.231995Z","steps":["trace[1985036400] 'process raft request' (duration: 630.503039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.232344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.603104Z","time spent":"629.18442ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:20:42.2322Z","caller":"traceutil/trace.go:171","msg":"trace[2093394858] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"535.019305ms","start":"2026-09-19T10:20:41.697173Z","end":"2026-09-19T10:20:42.232192Z","steps":["trace[2093394858] 'process raft request' (duration: 534.775689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.232417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.697147Z","time spent":"535.245989ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:20:42.23234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.698242Z","time spent":"534.034283ms","remote":"127.0.0.1:36894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:20:42.232479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.059988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-19T10:20:42.232508Z","caller":"traceutil/trace.go:171","msg":"trace[1104978788] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:287; }","duration":"536.15807ms","start":"2026-09-19T10:20:41.696343Z","end":"2026-09-19T10:20:42.232501Z","steps":["trace[1104978788] 'agreement among raft nodes before linearized reading' (duration: 536.036067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.232592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.696324Z","time spent":"536.251392ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-19T10:20:42.232546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.601237Z","time spent":"631.109413ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:20:42.232688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.792106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:20:42.232717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.880715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:20:42.23273Z","caller":"traceutil/trace.go:171","msg":"trace[907399275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:287; }","duration":"235.893028ms","start":"2026-09-19T10:20:41.996824Z","end":"2026-09-19T10:20:42.232717Z","steps":["trace[907399275] 'agreement among raft nodes before linearized reading' (duration: 235.827956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.232736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.982236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:20:42.232757Z","caller":"traceutil/trace.go:171","msg":"trace[2104512370] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:287; }","duration":"531.947136ms","start":"2026-09-19T10:20:41.700796Z","end":"2026-09-19T10:20:42.232744Z","steps":["trace[2104512370] 'agreement among raft nodes before linearized reading' (duration: 531.883385ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:20:42.232772Z","caller":"traceutil/trace.go:171","msg":"trace[1431718442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:287; }","duration":"116.051417ms","start":"2026-09-19T10:20:42.116708Z","end":"2026-09-19T10:20:42.232759Z","steps":["trace[1431718442] 'agreement among raft nodes before linearized reading' (duration: 115.998075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.232755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.720869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:20:42.232777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.577507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"warn","ts":"2026-09-19T10:20:42.232794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.700787Z","time spent":"532.000507ms","remote":"127.0.0.1:36922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"info","ts":"2026-09-19T10:20:42.232824Z","caller":"traceutil/trace.go:171","msg":"trace[657202412] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:287; }","duration":"535.642759ms","start":"2026-09-19T10:20:41.697174Z","end":"2026-09-19T10:20:42.232817Z","steps":["trace[657202412] 'agreement among raft nodes before linearized reading' (duration: 535.555507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.232843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.697163Z","time spent":"535.674819ms","remote":"127.0.0.1:37018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-09-19T10:20:42.232937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.09136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T10:20:42.231992Z","caller":"traceutil/trace.go:171","msg":"trace[785255629] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"630.741385ms","start":"2026-09-19T10:20:41.601219Z","end":"2026-09-19T10:20:42.23196Z","steps":["trace[785255629] 'process raft request' (duration: 630.366646ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:20:42.232863Z","caller":"traceutil/trace.go:171","msg":"trace[38184894] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:287; }","duration":"752.969465ms","start":"2026-09-19T10:20:41.47983Z","end":"2026-09-19T10:20:42.2328Z","steps":["trace[38184894] 'agreement among raft nodes before linearized reading' (duration: 752.615898ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:20:42.233058Z","caller":"traceutil/trace.go:171","msg":"trace[2010474633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:287; }","duration":"408.244354ms","start":"2026-09-19T10:20:41.8248Z","end":"2026-09-19T10:20:42.233044Z","steps":["trace[2010474633] 'agreement among raft nodes before linearized reading' (duration: 407.974068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:20:42.233268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.824787Z","time spent":"408.466769ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T10:20:42.233167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.601189Z","time spent":"631.949312ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:20:42.233262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:20:41.47981Z","time spent":"753.323563ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-09-19T10:21:08.567851Z","caller":"traceutil/trace.go:171","msg":"trace[416015783] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"112.357996ms","start":"2026-09-19T10:21:08.455465Z","end":"2026-09-19T10:21:08.567823Z","steps":["trace[416015783] 'process raft request' (duration: 110.754926ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:08.568823Z","caller":"traceutil/trace.go:171","msg":"trace[823844884] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:499; }","duration":"107.229429ms","start":"2026-09-19T10:21:08.461579Z","end":"2026-09-19T10:21:08.568809Z","steps":["trace[823844884] 'read index received' (duration: 104.625444ms)","trace[823844884] 'applied index is now lower than readState.Index' (duration: 2.602945ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:21:08.569089Z","caller":"traceutil/trace.go:171","msg":"trace[1181579795] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"112.340276ms","start":"2026-09-19T10:21:08.456701Z","end":"2026-09-19T10:21:08.569041Z","steps":["trace[1181579795] 'process raft request' (duration: 112.050848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:08.569083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.459015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-19T10:21:08.569114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.49733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T10:21:08.569164Z","caller":"traceutil/trace.go:171","msg":"trace[2016508371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"102.566932ms","start":"2026-09-19T10:21:08.466583Z","end":"2026-09-19T10:21:08.56915Z","steps":["trace[2016508371] 'agreement among raft nodes before linearized reading' (duration: 102.45714ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:08.56917Z","caller":"traceutil/trace.go:171","msg":"trace[1641894923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"107.579567ms","start":"2026-09-19T10:21:08.461566Z","end":"2026-09-19T10:21:08.569146Z","steps":["trace[1641894923] 'agreement among raft nodes before linearized reading' (duration: 107.411003ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.358972Z","caller":"traceutil/trace.go:171","msg":"trace[2084148709] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"211.066138ms","start":"2026-09-19T10:21:20.147878Z","end":"2026-09-19T10:21:20.358944Z","steps":["trace[2084148709] 'process raft request' (duration: 210.888944ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.365403Z","caller":"traceutil/trace.go:171","msg":"trace[1411021708] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"209.023145ms","start":"2026-09-19T10:21:20.156352Z","end":"2026-09-19T10:21:20.365375Z","steps":["trace[1411021708] 'process raft request' (duration: 208.814559ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.365519Z","caller":"traceutil/trace.go:171","msg":"trace[1934844995] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"208.82592ms","start":"2026-09-19T10:21:20.156664Z","end":"2026-09-19T10:21:20.365489Z","steps":["trace[1934844995] 'process raft request' (duration: 208.605134ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.365759Z","caller":"traceutil/trace.go:171","msg":"trace[1109082140] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"205.701579ms","start":"2026-09-19T10:21:20.160012Z","end":"2026-09-19T10:21:20.365714Z","steps":["trace[1109082140] 'process raft request' (duration: 205.32743ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.365807Z","caller":"traceutil/trace.go:171","msg":"trace[1109979757] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"200.068713ms","start":"2026-09-19T10:21:20.165715Z","end":"2026-09-19T10:21:20.365784Z","steps":["trace[1109979757] 'process raft request' (duration: 199.891999ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.36586Z","caller":"traceutil/trace.go:171","msg":"trace[2008476129] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"196.809939ms","start":"2026-09-19T10:21:20.169039Z","end":"2026-09-19T10:21:20.365849Z","steps":["trace[2008476129] 'process raft request' (duration: 196.649245ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.365967Z","caller":"traceutil/trace.go:171","msg":"trace[1781683517] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"195.949687ms","start":"2026-09-19T10:21:20.170002Z","end":"2026-09-19T10:21:20.365952Z","steps":["trace[1781683517] 'process raft request' (duration: 195.767072ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.36615Z","caller":"traceutil/trace.go:171","msg":"trace[1905464597] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"195.712791ms","start":"2026-09-19T10:21:20.170406Z","end":"2026-09-19T10:21:20.366119Z","steps":["trace[1905464597] 'process raft request' (duration: 195.431774ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.366291Z","caller":"traceutil/trace.go:171","msg":"trace[1783519719] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"188.755341ms","start":"2026-09-19T10:21:20.177508Z","end":"2026-09-19T10:21:20.366263Z","steps":["trace[1783519719] 'process raft request' (duration: 188.400851ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.366385Z","caller":"traceutil/trace.go:171","msg":"trace[1698522861] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"176.861003ms","start":"2026-09-19T10:21:20.189509Z","end":"2026-09-19T10:21:20.36637Z","steps":["trace[1698522861] 'process raft request' (duration: 176.676429ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:20.366481Z","caller":"traceutil/trace.go:171","msg":"trace[198053731] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"125.933276ms","start":"2026-09-19T10:21:20.240537Z","end":"2026-09-19T10:21:20.366471Z","steps":["trace[198053731] 'process raft request' (duration: 125.754981ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:26.518109Z","caller":"traceutil/trace.go:171","msg":"trace[136345631] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"190.243163ms","start":"2026-09-19T10:21:26.327841Z","end":"2026-09-19T10:21:26.518084Z","steps":["trace[136345631] 'process raft request' (duration: 189.72411ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:26.822886Z","caller":"traceutil/trace.go:171","msg":"trace[1294487820] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"134.285765ms","start":"2026-09-19T10:21:26.688576Z","end":"2026-09-19T10:21:26.822862Z","steps":["trace[1294487820] 'process raft request' (duration: 134.131811ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:27.091022Z","caller":"traceutil/trace.go:171","msg":"trace[377055571] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"398.070823ms","start":"2026-09-19T10:21:26.692925Z","end":"2026-09-19T10:21:27.090996Z","steps":["trace[377055571] 'process raft request' (duration: 397.899719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:27.091365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:26.692842Z","time spent":"398.253788ms","remote":"127.0.0.1:37084","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-09-19T10:21:27.091351Z","caller":"traceutil/trace.go:171","msg":"trace[1102677932] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"398.369201ms","start":"2026-09-19T10:21:26.69295Z","end":"2026-09-19T10:21:27.091319Z","steps":["trace[1102677932] 'process raft request' (duration: 398.008431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:27.091534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:26.692926Z","time spent":"398.492855ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:21:27.092064Z","caller":"traceutil/trace.go:171","msg":"trace[844798645] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"388.126752ms","start":"2026-09-19T10:21:26.703923Z","end":"2026-09-19T10:21:27.09205Z","steps":["trace[844798645] 'process raft request' (duration: 388.05033ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:27.09207Z","caller":"traceutil/trace.go:171","msg":"trace[1592580208] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"399.10664ms","start":"2026-09-19T10:21:26.69295Z","end":"2026-09-19T10:21:27.092057Z","steps":["trace[1592580208] 'process raft request' (duration: 398.312199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:27.092169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:26.692933Z","time spent":"399.176412ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:21:27.092169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:26.703899Z","time spent":"388.205995ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:21:27.77911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490026160575749212,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T10:21:27.959879Z","caller":"traceutil/trace.go:171","msg":"trace[1494180660] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"681.098157ms","start":"2026-09-19T10:21:27.278756Z","end":"2026-09-19T10:21:27.959855Z","steps":["trace[1494180660] 'read index received' (duration: 680.255065ms)","trace[1494180660] 'applied index is now lower than readState.Index' (duration: 842.032µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:21:27.960108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.341383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:21:27.960116Z","caller":"traceutil/trace.go:171","msg":"trace[1882768782] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"787.697081ms","start":"2026-09-19T10:21:27.172353Z","end":"2026-09-19T10:21:27.96005Z","steps":["trace[1882768782] 'process raft request' (duration: 786.941711ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:27.960214Z","caller":"traceutil/trace.go:171","msg":"trace[1426260837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"681.487607ms","start":"2026-09-19T10:21:27.278713Z","end":"2026-09-19T10:21:27.9602Z","steps":["trace[1426260837] 'agreement among raft nodes before linearized reading' (duration: 681.345124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:27.960323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:27.278695Z","time spent":"681.61655ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T10:21:27.960419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:27.172325Z","time spent":"787.917597ms","remote":"127.0.0.1:36916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:21:29.920413Z","caller":"traceutil/trace.go:171","msg":"trace[1899022793] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"105.671473ms","start":"2026-09-19T10:21:29.81472Z","end":"2026-09-19T10:21:29.920391Z","steps":["trace[1899022793] 'process raft request' (duration: 105.55536ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:21:33.815264Z","caller":"traceutil/trace.go:171","msg":"trace[1166324425] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"122.416042ms","start":"2026-09-19T10:21:33.69282Z","end":"2026-09-19T10:21:33.815236Z","steps":["trace[1166324425] 'process raft request' (duration: 103.024936ms)","trace[1166324425] 'compare' (duration: 19.181181ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:21:33.81752Z","caller":"traceutil/trace.go:171","msg":"trace[892683233] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"101.244719ms","start":"2026-09-19T10:21:33.71625Z","end":"2026-09-19T10:21:33.817495Z","steps":["trace[892683233] 'process raft request' (duration: 100.821427ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:09.052451Z","caller":"traceutil/trace.go:171","msg":"trace[1714525653] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"105.172269ms","start":"2026-09-19T10:22:08.947255Z","end":"2026-09-19T10:22:09.052427Z","steps":["trace[1714525653] 'process raft request' (duration: 93.764338ms)","trace[1714525653] 'compare' (duration: 11.252167ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:22:09.05258Z","caller":"traceutil/trace.go:171","msg":"trace[1097990914] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"101.64768ms","start":"2026-09-19T10:22:08.950914Z","end":"2026-09-19T10:22:09.052562Z","steps":["trace[1097990914] 'process raft request' (duration: 101.466695ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:00.677512Z","caller":"traceutil/trace.go:171","msg":"trace[158799338] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"118.33618ms","start":"2026-09-19T10:23:00.559147Z","end":"2026-09-19T10:23:00.677483Z","steps":["trace[158799338] 'process raft request' (duration: 117.233988ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:00.681955Z","caller":"traceutil/trace.go:171","msg":"trace[1909055297] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"122.720278ms","start":"2026-09-19T10:23:00.559205Z","end":"2026-09-19T10:23:00.681925Z","steps":["trace[1909055297] 'process raft request' (duration: 122.628745ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:00.681979Z","caller":"traceutil/trace.go:171","msg":"trace[1777276513] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"121.873093ms","start":"2026-09-19T10:23:00.560089Z","end":"2026-09-19T10:23:00.681962Z","steps":["trace[1777276513] 'process raft request' (duration: 121.844412ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:00.682212Z","caller":"traceutil/trace.go:171","msg":"trace[1587889816] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"122.992556ms","start":"2026-09-19T10:23:00.559212Z","end":"2026-09-19T10:23:00.682204Z","steps":["trace[1587889816] 'process raft request' (duration: 122.649366ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:00.682216Z","caller":"traceutil/trace.go:171","msg":"trace[301514107] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"123.053028ms","start":"2026-09-19T10:23:00.559151Z","end":"2026-09-19T10:23:00.682204Z","steps":["trace[301514107] 'process raft request' (duration: 122.601795ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:00.68239Z","caller":"traceutil/trace.go:171","msg":"trace[1468938693] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"123.039777ms","start":"2026-09-19T10:23:00.559345Z","end":"2026-09-19T10:23:00.682384Z","steps":["trace[1468938693] 'process raft request' (duration: 122.568873ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:00.682372Z","caller":"traceutil/trace.go:171","msg":"trace[234239075] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"123.046097ms","start":"2026-09-19T10:23:00.559301Z","end":"2026-09-19T10:23:00.682347Z","steps":["trace[234239075] 'process raft request' (duration: 122.593464ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:08.783623Z","caller":"traceutil/trace.go:171","msg":"trace[824656316] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"272.707402ms","start":"2026-09-19T10:23:08.510891Z","end":"2026-09-19T10:23:08.783599Z","steps":["trace[824656316] 'read index received' (duration: 272.397343ms)","trace[824656316] 'applied index is now lower than readState.Index' (duration: 309.179µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:23:08.783665Z","caller":"traceutil/trace.go:171","msg":"trace[1662244399] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"345.669342ms","start":"2026-09-19T10:23:08.437973Z","end":"2026-09-19T10:23:08.783643Z","steps":["trace[1662244399] 'process raft request' (duration: 345.410515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:08.783826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:08.437957Z","time spent":"345.783706ms","remote":"127.0.0.1:37084","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-09-19T10:23:08.783987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.98397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-19T10:23:08.784069Z","caller":"traceutil/trace.go:171","msg":"trace[233122546] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1897; }","duration":"273.112673ms","start":"2026-09-19T10:23:08.510935Z","end":"2026-09-19T10:23:08.784048Z","steps":["trace[233122546] 'agreement among raft nodes before linearized reading' (duration: 272.915427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:08.78405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.150095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-19T10:23:08.78413Z","caller":"traceutil/trace.go:171","msg":"trace[943821306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1897; }","duration":"273.265738ms","start":"2026-09-19T10:23:08.510844Z","end":"2026-09-19T10:23:08.78411Z","steps":["trace[943821306] 'agreement among raft nodes before linearized reading' (duration: 272.950269ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:43.14025Z","caller":"traceutil/trace.go:171","msg":"trace[1581809731] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"128.42615ms","start":"2026-09-19T10:23:43.011768Z","end":"2026-09-19T10:23:43.140195Z","steps":["trace[1581809731] 'process raft request' (duration: 128.223164ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:43.525345Z","caller":"traceutil/trace.go:171","msg":"trace[675651847] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"378.774687ms","start":"2026-09-19T10:23:43.146547Z","end":"2026-09-19T10:23:43.525322Z","steps":["trace[675651847] 'process raft request' (duration: 378.594052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:43.52559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:43.14652Z","time spent":"378.971233ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:23:43.526196Z","caller":"traceutil/trace.go:171","msg":"trace[552165170] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"379.045496ms","start":"2026-09-19T10:23:43.147124Z","end":"2026-09-19T10:23:43.52617Z","steps":["trace[552165170] 'process raft request' (duration: 378.790498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:43.526336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:43.147094Z","time spent":"379.170459ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:23:43.526466Z","caller":"traceutil/trace.go:171","msg":"trace[546188354] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2227; }","duration":"248.388169ms","start":"2026-09-19T10:23:43.278053Z","end":"2026-09-19T10:23:43.526441Z","steps":["trace[546188354] 'read index received' (duration: 247.208974ms)","trace[546188354] 'applied index is now lower than readState.Index' (duration: 1.178205ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:23:43.526616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.568504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-19T10:23:43.526666Z","caller":"traceutil/trace.go:171","msg":"trace[263253677] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2145; }","duration":"248.874473ms","start":"2026-09-19T10:23:43.277777Z","end":"2026-09-19T10:23:43.526651Z","steps":["trace[263253677] 'agreement among raft nodes before linearized reading' (duration: 248.743489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:43.526642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.604445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:23:43.526755Z","caller":"traceutil/trace.go:171","msg":"trace[1767643788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2145; }","duration":"247.798301ms","start":"2026-09-19T10:23:43.278942Z","end":"2026-09-19T10:23:43.526741Z","steps":["trace[1767643788] 'agreement among raft nodes before linearized reading' (duration: 247.651347ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:43.806556Z","caller":"traceutil/trace.go:171","msg":"trace[1199651195] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"182.708151ms","start":"2026-09-19T10:23:43.623827Z","end":"2026-09-19T10:23:43.806535Z","steps":["trace[1199651195] 'read index received' (duration: 95.37309ms)","trace[1199651195] 'applied index is now lower than readState.Index' (duration: 87.334061ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:23:43.806623Z","caller":"traceutil/trace.go:171","msg":"trace[66289748] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"204.58461ms","start":"2026-09-19T10:23:43.602011Z","end":"2026-09-19T10:23:43.806596Z","steps":["trace[66289748] 'process raft request' (duration: 117.228708ms)","trace[66289748] 'compare' (duration: 86.701702ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:23:43.807056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.189296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T10:23:43.807127Z","caller":"traceutil/trace.go:171","msg":"trace[1076017140] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2147; }","duration":"183.321499ms","start":"2026-09-19T10:23:43.623787Z","end":"2026-09-19T10:23:43.807109Z","steps":["trace[1076017140] 'agreement among raft nodes before linearized reading' (duration: 182.850985ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:50.516367Z","caller":"traceutil/trace.go:171","msg":"trace[2056375163] linearizableReadLoop","detail":"{readStateIndex:2714; appliedIndex:2713; }","duration":"154.635268ms","start":"2026-09-19T10:24:50.361708Z","end":"2026-09-19T10:24:50.516343Z","steps":["trace[2056375163] 'read index received' (duration: 154.539987ms)","trace[2056375163] 'applied index is now lower than readState.Index' (duration: 94.291µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:24:50.516612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.883692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-19T10:24:50.516653Z","caller":"traceutil/trace.go:171","msg":"trace[513030356] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2613; }","duration":"154.965954ms","start":"2026-09-19T10:24:50.361674Z","end":"2026-09-19T10:24:50.51664Z","steps":["trace[513030356] 'agreement among raft nodes before linearized reading' (duration: 154.764111ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:50.516928Z","caller":"traceutil/trace.go:171","msg":"trace[1666616958] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"170.016374ms","start":"2026-09-19T10:24:50.3469Z","end":"2026-09-19T10:24:50.516916Z","steps":["trace[1666616958] 'process raft request' (duration: 169.307023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:26.527172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.688952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7341"} {"level":"info","ts":"2026-09-19T10:25:26.527246Z","caller":"traceutil/trace.go:171","msg":"trace[989113911] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2809; }","duration":"146.795455ms","start":"2026-09-19T10:25:26.380434Z","end":"2026-09-19T10:25:26.52723Z","steps":["trace[989113911] 'range keys from in-memory index tree' (duration: 146.5445ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:50.364043Z","caller":"traceutil/trace.go:171","msg":"trace[796752685] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"187.470834ms","start":"2026-09-19T10:29:50.176543Z","end":"2026-09-19T10:29:50.364014Z","steps":["trace[796752685] 'process raft request' (duration: 124.871673ms)","trace[796752685] 'compare' (duration: 62.484578ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:29:53.308993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.903992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-19T10:29:53.30914Z","caller":"traceutil/trace.go:171","msg":"trace[320422902] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4883; }","duration":"120.076777ms","start":"2026-09-19T10:29:53.189044Z","end":"2026-09-19T10:29:53.30912Z","steps":["trace[320422902] 'range keys from in-memory index tree' (duration: 119.770618ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.359858Z","caller":"traceutil/trace.go:171","msg":"trace[2019203741] linearizableReadLoop","detail":"{readStateIndex:5074; appliedIndex:5073; }","duration":"129.943474ms","start":"2026-09-19T10:29:53.229876Z","end":"2026-09-19T10:29:53.359819Z","steps":["trace[2019203741] 'read index received' (duration: 129.653776ms)","trace[2019203741] 'applied index is now lower than readState.Index' (duration: 288.968µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:53.360049Z","caller":"traceutil/trace.go:171","msg":"trace[385492753] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"170.345726ms","start":"2026-09-19T10:29:53.189694Z","end":"2026-09-19T10:29:53.36004Z","steps":["trace[385492753] 'process raft request' (duration: 169.961276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:53.360372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.474738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-fbw7l\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-09-19T10:29:53.360452Z","caller":"traceutil/trace.go:171","msg":"trace[401037182] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-fbw7l; range_end:; response_count:1; response_revision:4884; }","duration":"130.596681ms","start":"2026-09-19T10:29:53.229823Z","end":"2026-09-19T10:29:53.36042Z","steps":["trace[401037182] 'agreement among raft nodes before linearized reading' (duration: 130.409146ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.546965Z","caller":"traceutil/trace.go:171","msg":"trace[241580225] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"175.266209ms","start":"2026-09-19T10:29:53.371666Z","end":"2026-09-19T10:29:53.546932Z","steps":["trace[241580225] 'process raft request' (duration: 175.134656ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.547005Z","caller":"traceutil/trace.go:171","msg":"trace[357595493] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"175.020003ms","start":"2026-09-19T10:29:53.371972Z","end":"2026-09-19T10:29:53.546992Z","steps":["trace[357595493] 'process raft request' (duration: 174.92302ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.547159Z","caller":"traceutil/trace.go:171","msg":"trace[1267253965] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"166.624075ms","start":"2026-09-19T10:29:53.380499Z","end":"2026-09-19T10:29:53.547123Z","steps":["trace[1267253965] 'process raft request' (duration: 166.42221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:53.547822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.460106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-19T10:29:53.547857Z","caller":"traceutil/trace.go:171","msg":"trace[193978461] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4888; }","duration":"151.535728ms","start":"2026-09-19T10:29:53.396312Z","end":"2026-09-19T10:29:53.547847Z","steps":["trace[193978461] 'agreement among raft nodes before linearized reading' (duration: 150.799528ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:59.850943Z","caller":"traceutil/trace.go:171","msg":"trace[606943411] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"201.580403ms","start":"2026-09-19T10:29:59.64933Z","end":"2026-09-19T10:29:59.850911Z","steps":["trace[606943411] 'process raft request' (duration: 174.998243ms)","trace[606943411] 'compare' (duration: 26.300322ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:59.851033Z","caller":"traceutil/trace.go:171","msg":"trace[310918680] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5201; }","duration":"137.713851ms","start":"2026-09-19T10:29:59.713297Z","end":"2026-09-19T10:29:59.851011Z","steps":["trace[310918680] 'read index received' (duration: 111.031689ms)","trace[310918680] 'applied index is now lower than readState.Index' (duration: 26.680032ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:29:59.851279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.412201ms","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-09-19T10:29:59.851336Z","caller":"traceutil/trace.go:171","msg":"trace[1783253513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5009; }","duration":"141.500914ms","start":"2026-09-19T10:29:59.70982Z","end":"2026-09-19T10:29:59.851321Z","steps":["trace[1783253513] 'agreement among raft nodes before linearized reading' (duration: 141.322869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:59.851387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.281311ms","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-09-19T10:29:59.851279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.154252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-19T10:29:59.851428Z","caller":"traceutil/trace.go:171","msg":"trace[703554158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5009; }","duration":"178.343992ms","start":"2026-09-19T10:29:59.673072Z","end":"2026-09-19T10:29:59.851416Z","steps":["trace[703554158] 'agreement among raft nodes before linearized reading' (duration: 178.23893ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:59.85146Z","caller":"traceutil/trace.go:171","msg":"trace[1701139456] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5009; }","duration":"204.395618ms","start":"2026-09-19T10:29:59.647051Z","end":"2026-09-19T10:29:59.851447Z","steps":["trace[1701139456] 'agreement among raft nodes before linearized reading' (duration: 204.07791ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:59.851748Z","caller":"traceutil/trace.go:171","msg":"trace[542517658] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"137.521987ms","start":"2026-09-19T10:29:59.714195Z","end":"2026-09-19T10:29:59.851717Z","steps":["trace[542517658] 'process raft request' (duration: 136.647293ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:59.851999Z","caller":"traceutil/trace.go:171","msg":"trace[844814574] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"134.222187ms","start":"2026-09-19T10:29:59.717716Z","end":"2026-09-19T10:29:59.851939Z","steps":["trace[844814574] 'process raft request' (duration: 133.23973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:59.852233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.631117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-19T10:29:59.852313Z","caller":"traceutil/trace.go:171","msg":"trace[1424604643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5011; }","duration":"131.711669ms","start":"2026-09-19T10:29:59.720587Z","end":"2026-09-19T10:29:59.852298Z","steps":["trace[1424604643] 'agreement among raft nodes before linearized reading' (duration: 131.522674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:59.85239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.982638ms","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-09-19T10:29:59.8525Z","caller":"traceutil/trace.go:171","msg":"trace[1674340309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5011; }","duration":"128.091481ms","start":"2026-09-19T10:29:59.724393Z","end":"2026-09-19T10:29:59.852485Z","steps":["trace[1674340309] 'agreement among raft nodes before linearized reading' (duration: 127.915817ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:00.351064Z","caller":"traceutil/trace.go:171","msg":"trace[1436057554] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"131.957417ms","start":"2026-09-19T10:30:00.219078Z","end":"2026-09-19T10:30:00.351036Z","steps":["trace[1436057554] 'process raft request' (duration: 126.314234ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:00.362671Z","caller":"traceutil/trace.go:171","msg":"trace[1588638412] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"134.133206ms","start":"2026-09-19T10:30:00.228513Z","end":"2026-09-19T10:30:00.362646Z","steps":["trace[1588638412] 'process raft request' (duration: 133.989512ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:00.477879Z","caller":"traceutil/trace.go:171","msg":"trace[2037885992] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"103.375852ms","start":"2026-09-19T10:30:00.37448Z","end":"2026-09-19T10:30:00.477856Z","steps":["trace[2037885992] 'process raft request' (duration: 103.337391ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:00.478296Z","caller":"traceutil/trace.go:171","msg":"trace[1459813921] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"105.860119ms","start":"2026-09-19T10:30:00.372425Z","end":"2026-09-19T10:30:00.478285Z","steps":["trace[1459813921] 'process raft request' (duration: 97.875112ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:00.478512Z","caller":"traceutil/trace.go:171","msg":"trace[1362752790] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"104.919614ms","start":"2026-09-19T10:30:00.373582Z","end":"2026-09-19T10:30:00.478502Z","steps":["trace[1362752790] 'process raft request' (duration: 104.139593ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:00.478628Z","caller":"traceutil/trace.go:171","msg":"trace[1958323142] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"104.459911ms","start":"2026-09-19T10:30:00.37416Z","end":"2026-09-19T10:30:00.47862Z","steps":["trace[1958323142] 'process raft request' (duration: 103.618788ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:13.275203Z","caller":"traceutil/trace.go:171","msg":"trace[74464788] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"209.79177ms","start":"2026-09-19T10:30:13.065386Z","end":"2026-09-19T10:30:13.275178Z","steps":["trace[74464788] 'process raft request' (duration: 209.587894ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:13.435084Z","caller":"traceutil/trace.go:171","msg":"trace[19270881] linearizableReadLoop","detail":"{readStateIndex:5378; appliedIndex:5377; }","duration":"101.956635ms","start":"2026-09-19T10:30:13.333099Z","end":"2026-09-19T10:30:13.435056Z","steps":["trace[19270881] 'read index received' (duration: 101.853102ms)","trace[19270881] 'applied index is now lower than readState.Index' (duration: 102.103µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:13.435104Z","caller":"traceutil/trace.go:171","msg":"trace[1877110308] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"105.646625ms","start":"2026-09-19T10:30:13.32943Z","end":"2026-09-19T10:30:13.435077Z","steps":["trace[1877110308] 'process raft request' (duration: 105.45106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:13.435345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.225512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5a20b059-48c6-40e7-bd8d-08c7bde1e6b0\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-19T10:30:13.435387Z","caller":"traceutil/trace.go:171","msg":"trace[1666611032] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5a20b059-48c6-40e7-bd8d-08c7bde1e6b0; range_end:; response_count:1; response_revision:5179; }","duration":"102.387156ms","start":"2026-09-19T10:30:13.332987Z","end":"2026-09-19T10:30:13.435374Z","steps":["trace[1666611032] 'agreement among raft nodes before linearized reading' (duration: 102.192891ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:13.463792Z","caller":"traceutil/trace.go:171","msg":"trace[317000847] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"124.621791ms","start":"2026-09-19T10:30:13.339154Z","end":"2026-09-19T10:30:13.463776Z","steps":["trace[317000847] 'process raft request' (duration: 124.443886ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:13.464026Z","caller":"traceutil/trace.go:171","msg":"trace[938906344] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"124.748165ms","start":"2026-09-19T10:30:13.339268Z","end":"2026-09-19T10:30:13.464016Z","steps":["trace[938906344] 'process raft request' (duration: 124.437866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:13.464575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.390609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-09-19T10:30:13.464641Z","caller":"traceutil/trace.go:171","msg":"trace[744826259] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5182; }","duration":"126.501032ms","start":"2026-09-19T10:30:13.338123Z","end":"2026-09-19T10:30:13.464624Z","steps":["trace[744826259] 'agreement among raft nodes before linearized reading' (duration: 126.305647ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:13.464833Z","caller":"traceutil/trace.go:171","msg":"trace[675960451] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"118.746442ms","start":"2026-09-19T10:30:13.346076Z","end":"2026-09-19T10:30:13.464823Z","steps":["trace[675960451] 'process raft request' (duration: 117.839917ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:36.177818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2859} {"level":"info","ts":"2026-09-19T10:30:36.322109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2859,"took":"143.096339ms","hash":3724204470,"current-db-size-bytes":51068928,"current-db-size":"51 MB","current-db-size-in-use-bytes":27717632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T10:30:36.322187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3724204470,"revision":2859,"compact-revision":-1} {"level":"info","ts":"2026-09-19T10:30:50.233468Z","caller":"traceutil/trace.go:171","msg":"trace[1015572024] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"121.787746ms","start":"2026-09-19T10:30:50.111659Z","end":"2026-09-19T10:30:50.233447Z","steps":["trace[1015572024] 'process raft request' (duration: 92.296696ms)","trace[1015572024] 'compare' (duration: 29.285375ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:35:36.19056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5347} {"level":"info","ts":"2026-09-19T10:35:36.278221Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5347,"took":"86.159641ms","hash":618206227,"current-db-size-bytes":51068928,"current-db-size":"51 MB","current-db-size-in-use-bytes":18690048,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T10:35:36.278283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":618206227,"revision":5347,"compact-revision":2859}