{"level":"warn","ts":"2026-07-22T05:10:41.429863Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T05:10:41.429947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.17: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://199.19.213.17:2380","--initial-cluster=instance=https://199.19.213.17:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.17:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.17: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-07-22T05:10:41.430018Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T05:10:41.43003Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.17:2380"]} {"level":"info","ts":"2026-07-22T05:10:41.430064Z","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-07-22T05:10:41.430692Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"]} {"level":"info","ts":"2026-07-22T05:10:41.430764Z","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://199.19.213.17:2380"],"listen-peer-urls":["https://199.19.213.17:2380"],"advertise-client-urls":["https://199.19.213.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.17: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-07-22T05:10:41.43447Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.465823ms"} {"level":"info","ts":"2026-07-22T05:10:41.445583Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"27c4b87f3ea730e3","cluster-id":"cdce983f4a7486fe"} {"level":"info","ts":"2026-07-22T05:10:41.445698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T05:10:41.445737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became follower at term 0"} {"level":"info","ts":"2026-07-22T05:10:41.445762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 27c4b87f3ea730e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T05:10:41.445775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became follower at term 1"} {"level":"info","ts":"2026-07-22T05:10:41.445833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=(2865618119565979875)"} {"level":"warn","ts":"2026-07-22T05:10:41.454524Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T05:10:41.4564Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T05:10:41.457507Z","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-07-22T05:10:41.463521Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"27c4b87f3ea730e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T05:10:41.463707Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"27c4b87f3ea730e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T05:10:41.464523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=(2865618119565979875)"} {"level":"info","ts":"2026-07-22T05:10:41.464648Z","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-07-22T05:10:41.464768Z","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-07-22T05:10:41.464791Z","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-07-22T05:10:41.466843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdce983f4a7486fe","local-member-id":"27c4b87f3ea730e3","added-peer-id":"27c4b87f3ea730e3","added-peer-peer-urls":["https://199.19.213.17:2380"]} {"level":"info","ts":"2026-07-22T05:10:41.475276Z","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-07-22T05:10:41.47552Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"27c4b87f3ea730e3","initial-advertise-peer-urls":["https://199.19.213.17:2380"],"listen-peer-urls":["https://199.19.213.17:2380"],"advertise-client-urls":["https://199.19.213.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T05:10:41.475554Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T05:10:41.475932Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.17:2380"} {"level":"info","ts":"2026-07-22T05:10:41.475961Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.17:2380"} {"level":"info","ts":"2026-07-22T05:10:42.046863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T05:10:42.046935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T05:10:42.04696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 received MsgPreVoteResp from 27c4b87f3ea730e3 at term 1"} {"level":"info","ts":"2026-07-22T05:10:42.046979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became candidate at term 2"} {"level":"info","ts":"2026-07-22T05:10:42.046988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 received MsgVoteResp from 27c4b87f3ea730e3 at term 2"} {"level":"info","ts":"2026-07-22T05:10:42.047002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became leader at term 2"} {"level":"info","ts":"2026-07-22T05:10:42.047014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 27c4b87f3ea730e3 elected leader 27c4b87f3ea730e3 at term 2"} {"level":"info","ts":"2026-07-22T05:10:42.047801Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T05:10:42.048647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T05:10:42.048648Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"27c4b87f3ea730e3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.17:2379]}","request-path":"/0/members/27c4b87f3ea730e3/attributes","cluster-id":"cdce983f4a7486fe","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T05:10:42.048701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T05:10:42.049128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdce983f4a7486fe","local-member-id":"27c4b87f3ea730e3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T05:10:42.049446Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T05:10:42.049453Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T05:10:42.049541Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T05:10:42.049514Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T05:10:42.052001Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.17:2379"} {"level":"info","ts":"2026-07-22T05:10:42.053153Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T05:10:45.452667Z","caller":"traceutil/trace.go:171","msg":"trace[477518277] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"152.58037ms","start":"2026-07-22T05:10:45.300066Z","end":"2026-07-22T05:10:45.452647Z","steps":["trace[477518277] 'process raft request' (duration: 133.759557ms)","trace[477518277] 'compare' (duration: 18.747629ms)"],"step_count":2} 2026/07/22 05:10:46 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-22T05:10:51.037603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.944362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:10:51.037725Z","caller":"traceutil/trace.go:171","msg":"trace[676360641] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"148.020118ms","start":"2026-07-22T05:10:50.889691Z","end":"2026-07-22T05:10:51.037711Z","steps":["trace[676360641] 'read index received' (duration: 54.013µs)","trace[676360641] 'applied index is now lower than readState.Index' (duration: 147.965255ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:10:51.03782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.122725ms","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-07-22T05:10:51.037793Z","caller":"traceutil/trace.go:171","msg":"trace[537661928] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"416.258552ms","start":"2026-07-22T05:10:50.621496Z","end":"2026-07-22T05:10:51.037755Z","steps":["trace[537661928] 'process raft request' (duration: 156.802287ms)","trace[537661928] 'compare' (duration: 258.816625ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:10:51.037853Z","caller":"traceutil/trace.go:171","msg":"trace[1977127734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:335; }","duration":"148.212131ms","start":"2026-07-22T05:10:50.889633Z","end":"2026-07-22T05:10:51.037845Z","steps":["trace[1977127734] 'agreement among raft nodes before linearized reading' (duration: 148.114745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.037932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:50.621467Z","time spent":"416.407492ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T05:10:51.576918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522834740999759882,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T05:10:51.849175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.27646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:10:51.849294Z","caller":"traceutil/trace.go:171","msg":"trace[1516398183] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"772.967221ms","start":"2026-07-22T05:10:51.076307Z","end":"2026-07-22T05:10:51.849274Z","steps":["trace[1516398183] 'read index received' (duration: 561.287605ms)","trace[1516398183] 'applied index is now lower than readState.Index' (duration: 211.675516ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:10:51.849427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.11673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-07-22T05:10:51.8494Z","caller":"traceutil/trace.go:171","msg":"trace[1305806355] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"804.675224ms","start":"2026-07-22T05:10:51.044672Z","end":"2026-07-22T05:10:51.849347Z","steps":["trace[1305806355] 'process raft request' (duration: 592.947145ms)","trace[1305806355] 'compare' (duration: 211.088538ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:10:51.849462Z","caller":"traceutil/trace.go:171","msg":"trace[970387844] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:336; }","duration":"773.191235ms","start":"2026-07-22T05:10:51.076258Z","end":"2026-07-22T05:10:51.84945Z","steps":["trace[970387844] 'agreement among raft nodes before linearized reading' (duration: 773.116391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.8495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.076234Z","time spent":"773.258429ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-07-22T05:10:51.849557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.044607Z","time spent":"804.865477ms","remote":"127.0.0.1:55376","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-07-22T05:10:51.849782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.47031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-22T05:10:51.849824Z","caller":"traceutil/trace.go:171","msg":"trace[262405660] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:336; }","duration":"772.531753ms","start":"2026-07-22T05:10:51.077283Z","end":"2026-07-22T05:10:51.849815Z","steps":["trace[262405660] 'agreement among raft nodes before linearized reading' (duration: 772.463459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.849851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.077274Z","time spent":"772.570156ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-07-22T05:10:51.850147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.629167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-22T05:10:51.850204Z","caller":"traceutil/trace.go:171","msg":"trace[156505124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:336; }","duration":"699.708942ms","start":"2026-07-22T05:10:51.150479Z","end":"2026-07-22T05:10:51.850188Z","steps":["trace[156505124] 'agreement among raft nodes before linearized reading' (duration: 699.573143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.850227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.916842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-07-22T05:10:51.850248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.52235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-22T05:10:51.850279Z","caller":"traceutil/trace.go:171","msg":"trace[982865312] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:336; }","duration":"767.993697ms","start":"2026-07-22T05:10:51.082272Z","end":"2026-07-22T05:10:51.850265Z","steps":["trace[982865312] 'agreement among raft nodes before linearized reading' (duration: 767.87948ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:10:51.850286Z","caller":"traceutil/trace.go:171","msg":"trace[1492453224] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:336; }","duration":"771.568942ms","start":"2026-07-22T05:10:51.078707Z","end":"2026-07-22T05:10:51.850276Z","steps":["trace[1492453224] 'agreement among raft nodes before linearized reading' (duration: 771.494758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.850323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.082262Z","time spent":"768.053381ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-07-22T05:10:51.850336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.607831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-22T05:10:51.850335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.370452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-22T05:10:51.850387Z","caller":"traceutil/trace.go:171","msg":"trace[871508067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:336; }","duration":"547.716098ms","start":"2026-07-22T05:10:51.302657Z","end":"2026-07-22T05:10:51.850373Z","steps":["trace[871508067] 'agreement among raft nodes before linearized reading' (duration: 547.619022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.850426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.922946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-07-22T05:10:51.850438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.302624Z","time spent":"547.795283ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T05:10:51.850457Z","caller":"traceutil/trace.go:171","msg":"trace[1231889902] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:336; }","duration":"718.963848ms","start":"2026-07-22T05:10:51.131482Z","end":"2026-07-22T05:10:51.850446Z","steps":["trace[1231889902] 'agreement among raft nodes before linearized reading' (duration: 718.914635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.850429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.242774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-22T05:10:51.850467Z","caller":"traceutil/trace.go:171","msg":"trace[1644515241] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:336; }","duration":"524.503641ms","start":"2026-07-22T05:10:51.325893Z","end":"2026-07-22T05:10:51.850397Z","steps":["trace[1644515241] 'agreement among raft nodes before linearized reading' (duration: 524.318039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.850486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.131473Z","time spent":"719.007371ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-07-22T05:10:51.850511Z","caller":"traceutil/trace.go:171","msg":"trace[1506205348] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:336; }","duration":"552.366942ms","start":"2026-07-22T05:10:51.298125Z","end":"2026-07-22T05:10:51.850492Z","steps":["trace[1506205348] 'agreement among raft nodes before linearized reading' (duration: 552.1776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.850528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.325883Z","time spent":"524.630339ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-07-22T05:10:51.850555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.298114Z","time spent":"552.433116ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-22T05:10:51.850349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.078653Z","time spent":"771.688121ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-07-22T05:10:51.850249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.150469Z","time spent":"699.767095ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-22T05:10:51.850643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.017165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-22T05:10:51.850867Z","caller":"traceutil/trace.go:171","msg":"trace[1067420495] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:336; }","duration":"698.316864ms","start":"2026-07-22T05:10:51.152531Z","end":"2026-07-22T05:10:51.850848Z","steps":["trace[1067420495] 'agreement among raft nodes before linearized reading' (duration: 697.988043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.850911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.046803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-07-22T05:10:51.850926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.15252Z","time spent":"698.387268ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-07-22T05:10:51.85095Z","caller":"traceutil/trace.go:171","msg":"trace[1550562240] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:336; }","duration":"725.107126ms","start":"2026-07-22T05:10:51.125832Z","end":"2026-07-22T05:10:51.850939Z","steps":["trace[1550562240] 'agreement among raft nodes before linearized reading' (duration: 725.035262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.850988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.125823Z","time spent":"725.15711ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-22T05:10:51.851068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.033537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-22T05:10:51.85111Z","caller":"traceutil/trace.go:171","msg":"trace[205612932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:336; }","duration":"403.10018ms","start":"2026-07-22T05:10:51.447999Z","end":"2026-07-22T05:10:51.851099Z","steps":["trace[205612932] 'agreement among raft nodes before linearized reading' (duration: 403.025076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.851153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.447986Z","time spent":"403.160184ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T05:10:51.853117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.377039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-0x1b1u\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-07-22T05:10:51.853172Z","caller":"traceutil/trace.go:171","msg":"trace[800047681] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-0x1b1u; range_end:; response_count:1; response_revision:336; }","duration":"127.497707ms","start":"2026-07-22T05:10:51.725661Z","end":"2026-07-22T05:10:51.853159Z","steps":["trace[800047681] 'agreement among raft nodes before linearized reading' (duration: 127.364218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.853324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.925149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-22T05:10:51.853383Z","caller":"traceutil/trace.go:171","msg":"trace[1999539983] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:336; }","duration":"764.000624ms","start":"2026-07-22T05:10:51.089367Z","end":"2026-07-22T05:10:51.853368Z","steps":["trace[1999539983] 'agreement among raft nodes before linearized reading' (duration: 763.889507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:10:51.85342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:10:51.089358Z","time spent":"764.054428ms","remote":"127.0.0.1:55300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-07-22T05:11:17.981033Z","caller":"traceutil/trace.go:171","msg":"trace[2112149243] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"106.552603ms","start":"2026-07-22T05:11:17.874449Z","end":"2026-07-22T05:11:17.981001Z","steps":["trace[2112149243] 'process raft request' (duration: 106.512324ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:17.981178Z","caller":"traceutil/trace.go:171","msg":"trace[1281108054] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"107.124448ms","start":"2026-07-22T05:11:17.874028Z","end":"2026-07-22T05:11:17.981153Z","steps":["trace[1281108054] 'process raft request' (duration: 34.732206ms)","trace[1281108054] 'compare' (duration: 72.050801ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:11:18.186175Z","caller":"traceutil/trace.go:171","msg":"trace[1451088813] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"201.416245ms","start":"2026-07-22T05:11:17.984723Z","end":"2026-07-22T05:11:18.186139Z","steps":["trace[1451088813] 'process raft request' (duration: 147.018483ms)","trace[1451088813] 'compare' (duration: 54.208807ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:11:18.186744Z","caller":"traceutil/trace.go:171","msg":"trace[1957481284] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:513; }","duration":"196.146269ms","start":"2026-07-22T05:11:17.990571Z","end":"2026-07-22T05:11:18.186717Z","steps":["trace[1957481284] 'read index received' (duration: 141.178321ms)","trace[1957481284] 'applied index is now lower than readState.Index' (duration: 54.966458ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:11:18.186833Z","caller":"traceutil/trace.go:171","msg":"trace[1552046319] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"201.406407ms","start":"2026-07-22T05:11:17.985368Z","end":"2026-07-22T05:11:18.186775Z","steps":["trace[1552046319] 'process raft request' (duration: 201.206602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:11:18.187043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.436901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-22T05:11:18.187044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.450881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-22T05:11:18.1871Z","caller":"traceutil/trace.go:171","msg":"trace[2028872399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"192.529918ms","start":"2026-07-22T05:11:17.994555Z","end":"2026-07-22T05:11:18.187085Z","steps":["trace[2028872399] 'agreement among raft nodes before linearized reading' (duration: 192.351672ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:18.187242Z","caller":"traceutil/trace.go:171","msg":"trace[1654354606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"196.682205ms","start":"2026-07-22T05:11:17.990525Z","end":"2026-07-22T05:11:18.187207Z","steps":["trace[1654354606] 'agreement among raft nodes before linearized reading' (duration: 196.337894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:11:18.187122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.409461ms","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-07-22T05:11:18.187521Z","caller":"traceutil/trace.go:171","msg":"trace[889138903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"192.81579ms","start":"2026-07-22T05:11:17.99466Z","end":"2026-07-22T05:11:18.187475Z","steps":["trace[889138903] 'agreement among raft nodes before linearized reading' (duration: 192.375732ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.02564Z","caller":"traceutil/trace.go:171","msg":"trace[1523943001] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"181.422029ms","start":"2026-07-22T05:11:19.844197Z","end":"2026-07-22T05:11:20.025619Z","steps":["trace[1523943001] 'process raft request' (duration: 181.291852ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.03416Z","caller":"traceutil/trace.go:171","msg":"trace[682780001] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"187.132689ms","start":"2026-07-22T05:11:19.846929Z","end":"2026-07-22T05:11:20.034062Z","steps":["trace[682780001] 'process raft request' (duration: 186.908404ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.034432Z","caller":"traceutil/trace.go:171","msg":"trace[324587734] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"153.401458ms","start":"2026-07-22T05:11:19.880988Z","end":"2026-07-22T05:11:20.034389Z","steps":["trace[324587734] 'process raft request' (duration: 153.237322ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.034421Z","caller":"traceutil/trace.go:171","msg":"trace[264078451] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"167.041453ms","start":"2026-07-22T05:11:19.867295Z","end":"2026-07-22T05:11:20.034337Z","steps":["trace[264078451] 'process raft request' (duration: 166.798469ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.034459Z","caller":"traceutil/trace.go:171","msg":"trace[1654911215] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"185.307044ms","start":"2026-07-22T05:11:19.849097Z","end":"2026-07-22T05:11:20.034404Z","steps":["trace[1654911215] 'process raft request' (duration: 184.867494ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.034637Z","caller":"traceutil/trace.go:171","msg":"trace[1495100703] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"143.737657ms","start":"2026-07-22T05:11:19.890889Z","end":"2026-07-22T05:11:20.034626Z","steps":["trace[1495100703] 'process raft request' (duration: 143.375695ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.03465Z","caller":"traceutil/trace.go:171","msg":"trace[345084529] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"139.48823ms","start":"2026-07-22T05:11:19.895152Z","end":"2026-07-22T05:11:20.03464Z","steps":["trace[345084529] 'process raft request' (duration: 139.244016ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.034638Z","caller":"traceutil/trace.go:171","msg":"trace[396534567] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"167.380535ms","start":"2026-07-22T05:11:19.867233Z","end":"2026-07-22T05:11:20.034613Z","steps":["trace[396534567] 'process raft request' (duration: 166.807238ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.034887Z","caller":"traceutil/trace.go:171","msg":"trace[994194005] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"136.468915ms","start":"2026-07-22T05:11:19.898408Z","end":"2026-07-22T05:11:20.034877Z","steps":["trace[994194005] 'process raft request' (duration: 136.101313ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.034897Z","caller":"traceutil/trace.go:171","msg":"trace[1834424272] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"121.020395ms","start":"2026-07-22T05:11:19.913864Z","end":"2026-07-22T05:11:20.034884Z","steps":["trace[1834424272] 'process raft request' (duration: 120.877628ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:20.03476Z","caller":"traceutil/trace.go:171","msg":"trace[391683300] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"159.394311ms","start":"2026-07-22T05:11:19.874956Z","end":"2026-07-22T05:11:20.03435Z","steps":["trace[391683300] 'process raft request' (duration: 159.222485ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:26.275845Z","caller":"traceutil/trace.go:171","msg":"trace[1292564920] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"398.975656ms","start":"2026-07-22T05:11:25.876846Z","end":"2026-07-22T05:11:26.275822Z","steps":["trace[1292564920] 'process raft request' (duration: 398.838588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:11:26.275996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:11:25.876807Z","time spent":"399.117193ms","remote":"127.0.0.1:55294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:11:26.308002Z","caller":"traceutil/trace.go:171","msg":"trace[1478304620] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"326.217038ms","start":"2026-07-22T05:11:25.981735Z","end":"2026-07-22T05:11:26.307952Z","steps":["trace[1478304620] 'process raft request' (duration: 325.44056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:11:26.308211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:11:25.9817Z","time spent":"326.404044ms","remote":"127.0.0.1:55208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T05:11:26.920989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.401391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:11:26.92121Z","caller":"traceutil/trace.go:171","msg":"trace[1370560743] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"487.785572ms","start":"2026-07-22T05:11:26.433404Z","end":"2026-07-22T05:11:26.92119Z","steps":["trace[1370560743] 'read index received' (duration: 250.102906ms)","trace[1370560743] 'applied index is now lower than readState.Index' (duration: 237.681016ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:11:26.921267Z","caller":"traceutil/trace.go:171","msg":"trace[1625470455] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"606.339394ms","start":"2026-07-22T05:11:26.314885Z","end":"2026-07-22T05:11:26.921224Z","steps":["trace[1625470455] 'process raft request' (duration: 606.202526ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:26.921404Z","caller":"traceutil/trace.go:171","msg":"trace[578379155] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"560.118239ms","start":"2026-07-22T05:11:26.361275Z","end":"2026-07-22T05:11:26.921393Z","steps":["trace[578379155] 'process raft request' (duration: 559.872292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:11:26.921449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:11:26.31488Z","time spent":"606.471062ms","remote":"127.0.0.1:55376","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-07-22T05:11:26.921477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:11:26.36125Z","time spent":"560.195797ms","remote":"127.0.0.1:55628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:11:26.921631Z","caller":"traceutil/trace.go:171","msg":"trace[1319062065] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"606.759967ms","start":"2026-07-22T05:11:26.314827Z","end":"2026-07-22T05:11:26.921587Z","steps":["trace[1319062065] 'process raft request' (duration: 368.658398ms)","trace[1319062065] 'compare' (duration: 237.268223ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:11:26.921694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.293434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:11:26.921787Z","caller":"traceutil/trace.go:171","msg":"trace[1426108063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"488.419081ms","start":"2026-07-22T05:11:26.433357Z","end":"2026-07-22T05:11:26.921776Z","steps":["trace[1426108063] 'agreement among raft nodes before linearized reading' (duration: 488.303133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:11:26.921819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:11:26.433341Z","time spent":"488.470701ms","remote":"127.0.0.1:51882","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-07-22T05:11:26.921812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:11:26.31481Z","time spent":"606.921305ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T05:11:26.921868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.765421ms","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-07-22T05:11:26.921908Z","caller":"traceutil/trace.go:171","msg":"trace[237792435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"226.859099ms","start":"2026-07-22T05:11:26.695034Z","end":"2026-07-22T05:11:26.921893Z","steps":["trace[237792435] 'agreement among raft nodes before linearized reading' (duration: 226.728872ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:11:42.357069Z","caller":"traceutil/trace.go:171","msg":"trace[775726789] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"388.524664ms","start":"2026-07-22T05:11:41.968519Z","end":"2026-07-22T05:11:42.357043Z","steps":["trace[775726789] 'process raft request' (duration: 388.385904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:11:42.357236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:11:41.968507Z","time spent":"388.644934ms","remote":"127.0.0.1:55294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:11:42.357446Z","caller":"traceutil/trace.go:171","msg":"trace[237598952] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"190.517296ms","start":"2026-07-22T05:11:42.166887Z","end":"2026-07-22T05:11:42.357404Z","steps":["trace[237598952] 'read index received' (duration: 189.940666ms)","trace[237598952] 'applied index is now lower than readState.Index' (duration: 573.9µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:11:42.357614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.707826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:11:42.357647Z","caller":"traceutil/trace.go:171","msg":"trace[1667302957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"190.798726ms","start":"2026-07-22T05:11:42.166838Z","end":"2026-07-22T05:11:42.357636Z","steps":["trace[1667302957] 'agreement among raft nodes before linearized reading' (duration: 190.718796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:11:42.35769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.177626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-22T05:11:42.357731Z","caller":"traceutil/trace.go:171","msg":"trace[1573995533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"120.259276ms","start":"2026-07-22T05:11:42.23746Z","end":"2026-07-22T05:11:42.357719Z","steps":["trace[1573995533] 'agreement among raft nodes before linearized reading' (duration: 120.125716ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:12:25.293799Z","caller":"traceutil/trace.go:171","msg":"trace[789659191] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"136.729449ms","start":"2026-07-22T05:12:25.157042Z","end":"2026-07-22T05:12:25.293772Z","steps":["trace[789659191] 'process raft request' (duration: 136.573935ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:12:36.240721Z","caller":"traceutil/trace.go:171","msg":"trace[1631523334] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"117.961683ms","start":"2026-07-22T05:12:36.122733Z","end":"2026-07-22T05:12:36.240695Z","steps":["trace[1631523334] 'process raft request' (duration: 88.965438ms)","trace[1631523334] 'compare' (duration: 28.908782ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:12:36.241393Z","caller":"traceutil/trace.go:171","msg":"trace[1400558283] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"117.942252ms","start":"2026-07-22T05:12:36.123432Z","end":"2026-07-22T05:12:36.241374Z","steps":["trace[1400558283] 'process raft request' (duration: 117.794577ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:12:55.548985Z","caller":"traceutil/trace.go:171","msg":"trace[1392991000] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"237.170081ms","start":"2026-07-22T05:12:55.311788Z","end":"2026-07-22T05:12:55.548958Z","steps":["trace[1392991000] 'process raft request' (duration: 236.986883ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:12:55.560069Z","caller":"traceutil/trace.go:171","msg":"trace[1527869422] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1925; }","duration":"126.623548ms","start":"2026-07-22T05:12:55.433417Z","end":"2026-07-22T05:12:55.560041Z","steps":["trace[1527869422] 'read index received' (duration: 115.409453ms)","trace[1527869422] 'applied index is now lower than readState.Index' (duration: 11.204124ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:12:55.560193Z","caller":"traceutil/trace.go:171","msg":"trace[539728517] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"147.473337ms","start":"2026-07-22T05:12:55.412707Z","end":"2026-07-22T05:12:55.56018Z","steps":["trace[539728517] 'process raft request' (duration: 147.198107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:12:55.560559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.120277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:12:55.560633Z","caller":"traceutil/trace.go:171","msg":"trace[1249169675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"127.239951ms","start":"2026-07-22T05:12:55.433373Z","end":"2026-07-22T05:12:55.560613Z","steps":["trace[1249169675] 'agreement among raft nodes before linearized reading' (duration: 127.116726ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:12:55.680818Z","caller":"traceutil/trace.go:171","msg":"trace[225326634] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"116.306347ms","start":"2026-07-22T05:12:55.564484Z","end":"2026-07-22T05:12:55.68079Z","steps":["trace[225326634] 'process raft request' (duration: 116.242285ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:12:55.680961Z","caller":"traceutil/trace.go:171","msg":"trace[1128123561] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"116.438972ms","start":"2026-07-22T05:12:55.564459Z","end":"2026-07-22T05:12:55.680898Z","steps":["trace[1128123561] 'process raft request' (duration: 43.223409ms)","trace[1128123561] 'compare' (duration: 72.827048ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:12:55.728871Z","caller":"traceutil/trace.go:171","msg":"trace[50448924] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1927; }","duration":"142.839537ms","start":"2026-07-22T05:12:55.586003Z","end":"2026-07-22T05:12:55.728843Z","steps":["trace[50448924] 'read index received' (duration: 21.665002ms)","trace[50448924] 'applied index is now lower than readState.Index' (duration: 121.173195ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:12:55.729047Z","caller":"traceutil/trace.go:171","msg":"trace[1091267596] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"164.491798ms","start":"2026-07-22T05:12:55.564521Z","end":"2026-07-22T05:12:55.729013Z","steps":["trace[1091267596] 'process raft request' (duration: 164.167015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:12:55.729141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.114037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2dcd15a0-a950-481a-9ff9-238e52c04e26\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-07-22T05:12:55.729188Z","caller":"traceutil/trace.go:171","msg":"trace[1881797898] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2dcd15a0-a950-481a-9ff9-238e52c04e26; range_end:; response_count:1; response_revision:1854; }","duration":"143.206961ms","start":"2026-07-22T05:12:55.585968Z","end":"2026-07-22T05:12:55.729175Z","steps":["trace[1881797898] 'agreement among raft nodes before linearized reading' (duration: 143.014764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:12:55.729431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.583879ms","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-07-22T05:12:55.729545Z","caller":"traceutil/trace.go:171","msg":"trace[1731270047] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"140.698214ms","start":"2026-07-22T05:12:55.588798Z","end":"2026-07-22T05:12:55.729496Z","steps":["trace[1731270047] 'agreement among raft nodes before linearized reading' (duration: 140.364961ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:13:29.414337Z","caller":"traceutil/trace.go:171","msg":"trace[1851113661] linearizableReadLoop","detail":"{readStateIndex:2174; appliedIndex:2173; }","duration":"153.87409ms","start":"2026-07-22T05:13:29.260441Z","end":"2026-07-22T05:13:29.414315Z","steps":["trace[1851113661] 'read index received' (duration: 111.490109ms)","trace[1851113661] 'applied index is now lower than readState.Index' (duration: 42.383051ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:13:29.414615Z","caller":"traceutil/trace.go:171","msg":"trace[1005778186] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"339.421685ms","start":"2026-07-22T05:13:29.075135Z","end":"2026-07-22T05:13:29.414556Z","steps":["trace[1005778186] 'process raft request' (duration: 296.885237ms)","trace[1005778186] 'compare' (duration: 42.200463ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:13:29.414687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.223576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T05:13:29.415037Z","caller":"traceutil/trace.go:171","msg":"trace[381774699] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2092; }","duration":"154.619515ms","start":"2026-07-22T05:13:29.260404Z","end":"2026-07-22T05:13:29.415024Z","steps":["trace[381774699] 'agreement among raft nodes before linearized reading' (duration: 154.219046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:13:29.415641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:13:29.075127Z","time spent":"339.771302ms","remote":"127.0.0.1:55376","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-07-22T05:13:29.76716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.222665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12381"} {"level":"info","ts":"2026-07-22T05:13:29.767247Z","caller":"traceutil/trace.go:171","msg":"trace[537773030] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2092; }","duration":"176.315499ms","start":"2026-07-22T05:13:29.590913Z","end":"2026-07-22T05:13:29.767228Z","steps":["trace[537773030] 'range keys from in-memory index tree' (duration: 176.079497ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:13:29.847334Z","caller":"traceutil/trace.go:171","msg":"trace[1442332102] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"171.547498ms","start":"2026-07-22T05:13:29.675754Z","end":"2026-07-22T05:13:29.847301Z","steps":["trace[1442332102] 'process raft request' (duration: 171.382901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:13:29.992361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.90684ms","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-07-22T05:13:29.992461Z","caller":"traceutil/trace.go:171","msg":"trace[1072895485] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2097; }","duration":"133.041296ms","start":"2026-07-22T05:13:29.859393Z","end":"2026-07-22T05:13:29.992434Z","steps":["trace[1072895485] 'agreement among raft nodes before linearized reading' (duration: 132.70278ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:14:35.029657Z","caller":"traceutil/trace.go:171","msg":"trace[802916240] linearizableReadLoop","detail":"{readStateIndex:2638; appliedIndex:2637; }","duration":"292.147666ms","start":"2026-07-22T05:14:34.737493Z","end":"2026-07-22T05:14:35.029641Z","steps":["trace[802916240] 'read index received' (duration: 292.052011ms)","trace[802916240] 'applied index is now lower than readState.Index' (duration: 94.925µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:14:35.029765Z","caller":"traceutil/trace.go:171","msg":"trace[982060674] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"302.819561ms","start":"2026-07-22T05:14:34.726938Z","end":"2026-07-22T05:14:35.029757Z","steps":["trace[982060674] 'process raft request' (duration: 302.599439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:14:35.029843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:14:34.726927Z","time spent":"302.853873ms","remote":"127.0.0.1:55376","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-07-22T05:14:35.029986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.431245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.17\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-22T05:14:35.030063Z","caller":"traceutil/trace.go:171","msg":"trace[795978376] range","detail":"{range_begin:/registry/masterleases/199.19.213.17; range_end:; response_count:1; response_revision:2541; }","duration":"140.54167ms","start":"2026-07-22T05:14:34.889504Z","end":"2026-07-22T05:14:35.030046Z","steps":["trace[795978376] 'agreement among raft nodes before linearized reading' (duration: 140.34749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:14:35.030108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.61413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-07-22T05:14:35.030135Z","caller":"traceutil/trace.go:171","msg":"trace[1326993820] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2541; }","duration":"292.663883ms","start":"2026-07-22T05:14:34.737464Z","end":"2026-07-22T05:14:35.030128Z","steps":["trace[1326993820] 'agreement among raft nodes before linearized reading' (duration: 292.575608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:15:06.533697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.411612ms","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-07-22T05:15:06.533788Z","caller":"traceutil/trace.go:171","msg":"trace[629198841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2717; }","duration":"158.54811ms","start":"2026-07-22T05:15:06.375218Z","end":"2026-07-22T05:15:06.533766Z","steps":["trace[629198841] 'range keys from in-memory index tree' (duration: 158.247853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:15:06.534274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.811343ms","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-07-22T05:15:06.534321Z","caller":"traceutil/trace.go:171","msg":"trace[138361748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2717; }","duration":"111.909448ms","start":"2026-07-22T05:15:06.422397Z","end":"2026-07-22T05:15:06.534306Z","steps":["trace[138361748] 'range keys from in-memory index tree' (duration: 111.675625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:15:06.534503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.212805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:15:06.534624Z","caller":"traceutil/trace.go:171","msg":"trace[1167925282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2717; }","duration":"102.095113ms","start":"2026-07-22T05:15:06.432507Z","end":"2026-07-22T05:15:06.534603Z","steps":["trace[1167925282] 'range keys from in-memory index tree' (duration: 101.12244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:15:06.534532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.273113ms","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-07-22T05:15:06.535455Z","caller":"traceutil/trace.go:171","msg":"trace[1969662805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2717; }","duration":"114.212633ms","start":"2026-07-22T05:15:06.421146Z","end":"2026-07-22T05:15:06.535358Z","steps":["trace[1969662805] 'range keys from in-memory index tree' (duration: 113.004258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:15:07.222775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.420214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:15:07.222873Z","caller":"traceutil/trace.go:171","msg":"trace[1095286017] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2720; }","duration":"281.612704ms","start":"2026-07-22T05:15:06.941241Z","end":"2026-07-22T05:15:07.222853Z","steps":["trace[1095286017] 'count revisions from in-memory index tree' (duration: 281.204641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:15:07.223296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.341426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:15:07.223415Z","caller":"traceutil/trace.go:171","msg":"trace[2000158584] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2720; }","duration":"132.513096ms","start":"2026-07-22T05:15:07.090863Z","end":"2026-07-22T05:15:07.223376Z","steps":["trace[2000158584] 'count revisions from in-memory index tree' (duration: 132.22831ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:15:12.585872Z","caller":"traceutil/trace.go:171","msg":"trace[1137999387] linearizableReadLoop","detail":"{readStateIndex:2853; appliedIndex:2852; }","duration":"220.576695ms","start":"2026-07-22T05:15:12.365268Z","end":"2026-07-22T05:15:12.585845Z","steps":["trace[1137999387] 'read index received' (duration: 220.336642ms)","trace[1137999387] 'applied index is now lower than readState.Index' (duration: 238.643µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:15:12.586076Z","caller":"traceutil/trace.go:171","msg":"trace[181294683] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"275.092577ms","start":"2026-07-22T05:15:12.310953Z","end":"2026-07-22T05:15:12.586046Z","steps":["trace[181294683] 'process raft request' (duration: 274.717476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:15:12.586305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.957066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T05:15:12.5864Z","caller":"traceutil/trace.go:171","msg":"trace[1023302925] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2747; }","duration":"221.468004ms","start":"2026-07-22T05:15:12.36491Z","end":"2026-07-22T05:15:12.586378Z","steps":["trace[1023302925] 'agreement among raft nodes before linearized reading' (duration: 221.062392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:15:12.587071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.236542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:15:12.587167Z","caller":"traceutil/trace.go:171","msg":"trace[332673551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2747; }","duration":"153.345908ms","start":"2026-07-22T05:15:12.433809Z","end":"2026-07-22T05:15:12.587155Z","steps":["trace[332673551] 'agreement among raft nodes before linearized reading' (duration: 152.509382ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:48.862692Z","caller":"traceutil/trace.go:171","msg":"trace[1479582960] transaction","detail":"{read_only:false; response_revision:4073; number_of_response:1; }","duration":"261.502353ms","start":"2026-07-22T05:18:48.601166Z","end":"2026-07-22T05:18:48.862668Z","steps":["trace[1479582960] 'process raft request' (duration: 261.286855ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:48.864086Z","caller":"traceutil/trace.go:171","msg":"trace[905287476] linearizableReadLoop","detail":"{readStateIndex:4243; appliedIndex:4241; }","duration":"191.992198ms","start":"2026-07-22T05:18:48.672057Z","end":"2026-07-22T05:18:48.864049Z","steps":["trace[905287476] 'read index received' (duration: 190.481045ms)","trace[905287476] 'applied index is now lower than readState.Index' (duration: 1.510253ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:18:48.864105Z","caller":"traceutil/trace.go:171","msg":"trace[243592899] transaction","detail":"{read_only:false; response_revision:4074; number_of_response:1; }","duration":"262.201087ms","start":"2026-07-22T05:18:48.601887Z","end":"2026-07-22T05:18:48.864088Z","steps":["trace[243592899] 'process raft request' (duration: 262.01164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:48.864299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.222416ms","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-07-22T05:18:48.864347Z","caller":"traceutil/trace.go:171","msg":"trace[67904274] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4074; }","duration":"192.307128ms","start":"2026-07-22T05:18:48.672022Z","end":"2026-07-22T05:18:48.864329Z","steps":["trace[67904274] 'agreement among raft nodes before linearized reading' (duration: 192.140722ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:53.832537Z","caller":"traceutil/trace.go:171","msg":"trace[2023230315] transaction","detail":"{read_only:false; response_revision:4412; number_of_response:1; }","duration":"143.136051ms","start":"2026-07-22T05:18:53.689376Z","end":"2026-07-22T05:18:53.832512Z","steps":["trace[2023230315] 'process raft request' (duration: 142.962645ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:53.842921Z","caller":"traceutil/trace.go:171","msg":"trace[1163953160] transaction","detail":"{read_only:false; response_revision:4413; number_of_response:1; }","duration":"142.091695ms","start":"2026-07-22T05:18:53.700807Z","end":"2026-07-22T05:18:53.842899Z","steps":["trace[1163953160] 'process raft request' (duration: 140.660335ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:53.850108Z","caller":"traceutil/trace.go:171","msg":"trace[936748331] transaction","detail":"{read_only:false; response_revision:4414; number_of_response:1; }","duration":"110.647984ms","start":"2026-07-22T05:18:53.739416Z","end":"2026-07-22T05:18:53.850064Z","steps":["trace[936748331] 'process raft request' (duration: 103.335058ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:54.884084Z","caller":"traceutil/trace.go:171","msg":"trace[691929335] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"134.229134ms","start":"2026-07-22T05:18:54.74983Z","end":"2026-07-22T05:18:54.884059Z","steps":["trace[691929335] 'process raft request' (duration: 133.955224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:01.727654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.568032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:19:01.727778Z","caller":"traceutil/trace.go:171","msg":"trace[1230827830] linearizableReadLoop","detail":"{readStateIndex:4821; appliedIndex:4820; }","duration":"255.205905ms","start":"2026-07-22T05:19:01.472548Z","end":"2026-07-22T05:19:01.727754Z","steps":["trace[1230827830] 'read index received' (duration: 7.419436ms)","trace[1230827830] 'applied index is now lower than readState.Index' (duration: 247.785339ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:01.727777Z","caller":"traceutil/trace.go:171","msg":"trace[1360186907] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"256.793462ms","start":"2026-07-22T05:19:01.470966Z","end":"2026-07-22T05:19:01.727759Z","steps":["trace[1360186907] 'compare' (duration: 247.442867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:01.727911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.653379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:19:01.727968Z","caller":"traceutil/trace.go:171","msg":"trace[181887130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4641; }","duration":"294.765683ms","start":"2026-07-22T05:19:01.433186Z","end":"2026-07-22T05:19:01.727951Z","steps":["trace[181887130] 'agreement among raft nodes before linearized reading' (duration: 294.653789ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:01.729538Z","caller":"traceutil/trace.go:171","msg":"trace[1648406433] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"243.988903ms","start":"2026-07-22T05:19:01.48552Z","end":"2026-07-22T05:19:01.729509Z","steps":["trace[1648406433] 'process raft request' (duration: 243.749605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:01.730969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.653734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-22T05:19:01.731024Z","caller":"traceutil/trace.go:171","msg":"trace[1209865061] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4643; }","duration":"247.726577ms","start":"2026-07-22T05:19:01.483284Z","end":"2026-07-22T05:19:01.731011Z","steps":["trace[1209865061] 'agreement among raft nodes before linearized reading' (duration: 247.575501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:01.73147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.449662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362732"} {"level":"info","ts":"2026-07-22T05:19:01.731587Z","caller":"traceutil/trace.go:171","msg":"trace[635882143] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4643; }","duration":"104.583807ms","start":"2026-07-22T05:19:01.626961Z","end":"2026-07-22T05:19:01.731544Z","steps":["trace[635882143] 'agreement among raft nodes before linearized reading' (duration: 103.771348ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:02.769234Z","caller":"traceutil/trace.go:171","msg":"trace[995315830] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"158.491617ms","start":"2026-07-22T05:19:02.610717Z","end":"2026-07-22T05:19:02.769209Z","steps":["trace[995315830] 'process raft request' (duration: 158.323421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:08.515914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522834740999770146,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T05:19:08.714819Z","caller":"traceutil/trace.go:171","msg":"trace[344327053] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"705.615892ms","start":"2026-07-22T05:19:08.009126Z","end":"2026-07-22T05:19:08.714742Z","steps":["trace[344327053] 'process raft request' (duration: 669.641318ms)","trace[344327053] 'compare' (duration: 35.694784ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:19:08.715057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:08.009096Z","time spent":"705.802419ms","remote":"127.0.0.1:55198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:19:08.742301Z","caller":"traceutil/trace.go:171","msg":"trace[1337304728] linearizableReadLoop","detail":"{readStateIndex:4926; appliedIndex:4924; }","duration":"727.342496ms","start":"2026-07-22T05:19:08.014931Z","end":"2026-07-22T05:19:08.742274Z","steps":["trace[1337304728] 'read index received' (duration: 663.09922ms)","trace[1337304728] 'applied index is now lower than readState.Index' (duration: 64.242106ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:08.742424Z","caller":"traceutil/trace.go:171","msg":"trace[977590319] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"307.151718ms","start":"2026-07-22T05:19:08.435258Z","end":"2026-07-22T05:19:08.74241Z","steps":["trace[977590319] 'process raft request' (duration: 306.866748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:08.742661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:08.43523Z","time spent":"307.230301ms","remote":"127.0.0.1:55376","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-07-22T05:19:08.742722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.23605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T05:19:08.742814Z","caller":"traceutil/trace.go:171","msg":"trace[731463015] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4742; }","duration":"742.399666ms","start":"2026-07-22T05:19:08.000397Z","end":"2026-07-22T05:19:08.742797Z","steps":["trace[731463015] 'agreement among raft nodes before linearized reading' (duration: 742.149526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:08.742896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:08.00038Z","time spent":"742.461397ms","remote":"127.0.0.1:55572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-22T05:19:08.780612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.087502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-22T05:19:08.780669Z","caller":"traceutil/trace.go:171","msg":"trace[1823521474] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4743; }","duration":"760.165034ms","start":"2026-07-22T05:19:08.020489Z","end":"2026-07-22T05:19:08.780654Z","steps":["trace[1823521474] 'agreement among raft nodes before linearized reading' (duration: 759.993628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:08.780697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:08.02048Z","time spent":"760.211616ms","remote":"127.0.0.1:55208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-07-22T05:19:08.781175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.608991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:72 size:60099"} {"level":"info","ts":"2026-07-22T05:19:08.781206Z","caller":"traceutil/trace.go:171","msg":"trace[1169412166] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:72; response_revision:4743; }","duration":"760.666103ms","start":"2026-07-22T05:19:08.02053Z","end":"2026-07-22T05:19:08.781197Z","steps":["trace[1169412166] 'agreement among raft nodes before linearized reading' (duration: 760.363092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:08.781228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:08.020513Z","time spent":"760.708035ms","remote":"127.0.0.1:55476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":72,"response size":60122,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-07-22T05:19:08.781504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.236816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362732"} {"level":"info","ts":"2026-07-22T05:19:08.781527Z","caller":"traceutil/trace.go:171","msg":"trace[1957353631] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4743; }","duration":"562.295978ms","start":"2026-07-22T05:19:08.219224Z","end":"2026-07-22T05:19:08.78152Z","steps":["trace[1957353631] 'agreement among raft nodes before linearized reading' (duration: 561.565061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:08.781547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:08.219203Z","time spent":"562.33873ms","remote":"127.0.0.1:55214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2362755,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-07-22T05:19:08.901077Z","caller":"traceutil/trace.go:171","msg":"trace[823865840] linearizableReadLoop","detail":"{readStateIndex:4930; appliedIndex:4928; }","duration":"102.306865ms","start":"2026-07-22T05:19:08.798745Z","end":"2026-07-22T05:19:08.901052Z","steps":["trace[823865840] 'read index received' (duration: 53.833606ms)","trace[823865840] 'applied index is now lower than readState.Index' (duration: 48.472049ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:08.901203Z","caller":"traceutil/trace.go:171","msg":"trace[99615757] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"103.146426ms","start":"2026-07-22T05:19:08.798044Z","end":"2026-07-22T05:19:08.901191Z","steps":["trace[99615757] 'process raft request' (duration: 102.861066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:08.90153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.474015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1187"} {"level":"info","ts":"2026-07-22T05:19:08.901614Z","caller":"traceutil/trace.go:171","msg":"trace[1312883027] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:4746; }","duration":"101.60104ms","start":"2026-07-22T05:19:08.799993Z","end":"2026-07-22T05:19:08.901594Z","steps":["trace[1312883027] 'agreement among raft nodes before linearized reading' (duration: 101.351341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:08.90168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.876457ms","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-07-22T05:19:08.901782Z","caller":"traceutil/trace.go:171","msg":"trace[941608751] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4746; }","duration":"103.075543ms","start":"2026-07-22T05:19:08.798685Z","end":"2026-07-22T05:19:08.901761Z","steps":["trace[941608751] 'agreement among raft nodes before linearized reading' (duration: 102.72468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:09.108832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.383406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:19:09.108933Z","caller":"traceutil/trace.go:171","msg":"trace[279964310] linearizableReadLoop","detail":"{readStateIndex:4936; appliedIndex:4935; }","duration":"165.979012ms","start":"2026-07-22T05:19:08.942939Z","end":"2026-07-22T05:19:09.108918Z","steps":["trace[279964310] 'read index received' (duration: 19.397009ms)","trace[279964310] 'applied index is now lower than readState.Index' (duration: 146.580593ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:09.109098Z","caller":"traceutil/trace.go:171","msg":"trace[859801906] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"191.632519ms","start":"2026-07-22T05:19:08.917455Z","end":"2026-07-22T05:19:09.109088Z","steps":["trace[859801906] 'process raft request' (duration: 44.937402ms)","trace[859801906] 'compare' (duration: 146.084525ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:19:09.109371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.25403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-07-22T05:19:09.10941Z","caller":"traceutil/trace.go:171","msg":"trace[639385869] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4752; }","duration":"184.315491ms","start":"2026-07-22T05:19:08.925086Z","end":"2026-07-22T05:19:09.109402Z","steps":["trace[639385869] 'agreement among raft nodes before linearized reading' (duration: 184.153846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:09.109402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.634535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-keystone-peer-discovery\" ","response":"range_response_count:1 size:814"} {"level":"warn","ts":"2026-07-22T05:19:09.109442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.19024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-22T05:19:09.109449Z","caller":"traceutil/trace.go:171","msg":"trace[2132639585] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-keystone-peer-discovery; range_end:; response_count:1; response_revision:4752; }","duration":"192.708668ms","start":"2026-07-22T05:19:08.916728Z","end":"2026-07-22T05:19:09.109436Z","steps":["trace[2132639585] 'agreement among raft nodes before linearized reading' (duration: 192.564132ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:09.109469Z","caller":"traceutil/trace.go:171","msg":"trace[944282482] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4752; }","duration":"161.393945ms","start":"2026-07-22T05:19:08.946867Z","end":"2026-07-22T05:19:09.109458Z","steps":["trace[944282482] 'agreement among raft nodes before linearized reading' (duration: 161.335532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:09.109473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.306069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T05:19:09.109553Z","caller":"traceutil/trace.go:171","msg":"trace[140464978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4752; }","duration":"153.439354ms","start":"2026-07-22T05:19:08.956096Z","end":"2026-07-22T05:19:09.109535Z","steps":["trace[140464978] 'agreement among raft nodes before linearized reading' (duration: 153.198636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:09.109376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.597461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-22T05:19:09.109661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.954877ms","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-07-22T05:19:09.109665Z","caller":"traceutil/trace.go:171","msg":"trace[1295609775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4752; }","duration":"164.909243ms","start":"2026-07-22T05:19:08.944748Z","end":"2026-07-22T05:19:09.109657Z","steps":["trace[1295609775] 'agreement among raft nodes before linearized reading' (duration: 164.541419ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:09.109685Z","caller":"traceutil/trace.go:171","msg":"trace[1081356082] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4752; }","duration":"147.006569ms","start":"2026-07-22T05:19:08.962669Z","end":"2026-07-22T05:19:09.109676Z","steps":["trace[1081356082] 'agreement among raft nodes before linearized reading' (duration: 146.941906ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:09.282893Z","caller":"traceutil/trace.go:171","msg":"trace[1251115631] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"100.663396ms","start":"2026-07-22T05:19:09.182189Z","end":"2026-07-22T05:19:09.282853Z","steps":["trace[1251115631] 'process raft request' (duration: 83.84411ms)","trace[1251115631] 'compare' (duration: 16.638779ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:09.609906Z","caller":"traceutil/trace.go:171","msg":"trace[274031768] linearizableReadLoop","detail":"{readStateIndex:4944; appliedIndex:4942; }","duration":"243.122043ms","start":"2026-07-22T05:19:09.366761Z","end":"2026-07-22T05:19:09.609883Z","steps":["trace[274031768] 'read index received' (duration: 1.08606ms)","trace[274031768] 'applied index is now lower than readState.Index' (duration: 242.035133ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:09.610005Z","caller":"traceutil/trace.go:171","msg":"trace[651682026] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"247.339827ms","start":"2026-07-22T05:19:09.362643Z","end":"2026-07-22T05:19:09.609983Z","steps":["trace[651682026] 'process raft request' (duration: 242.615584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:09.610115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.33317ms","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-07-22T05:19:09.610435Z","caller":"traceutil/trace.go:171","msg":"trace[223290616] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4760; }","duration":"243.681723ms","start":"2026-07-22T05:19:09.366741Z","end":"2026-07-22T05:19:09.610423Z","steps":["trace[223290616] 'agreement among raft nodes before linearized reading' (duration: 243.237587ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:09.617743Z","caller":"traceutil/trace.go:171","msg":"trace[222965684] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"244.777993ms","start":"2026-07-22T05:19:09.372934Z","end":"2026-07-22T05:19:09.617712Z","steps":["trace[222965684] 'process raft request' (duration: 244.362718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:09.617992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.104018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-07-22T05:19:09.618045Z","caller":"traceutil/trace.go:171","msg":"trace[1717720453] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4761; }","duration":"250.197882ms","start":"2026-07-22T05:19:09.367835Z","end":"2026-07-22T05:19:09.618033Z","steps":["trace[1717720453] 'agreement among raft nodes before linearized reading' (duration: 250.017515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:09.618234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.652389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-07-22T05:19:09.618272Z","caller":"traceutil/trace.go:171","msg":"trace[1311475510] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4761; }","duration":"170.700221ms","start":"2026-07-22T05:19:09.447551Z","end":"2026-07-22T05:19:09.618251Z","steps":["trace[1311475510] 'agreement among raft nodes before linearized reading' (duration: 170.626258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:09.61839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.908891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:19:09.618414Z","caller":"traceutil/trace.go:171","msg":"trace[1398629192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4761; }","duration":"184.934532ms","start":"2026-07-22T05:19:09.433474Z","end":"2026-07-22T05:19:09.618408Z","steps":["trace[1398629192] 'agreement among raft nodes before linearized reading' (duration: 184.89436ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:12.230141Z","caller":"traceutil/trace.go:171","msg":"trace[1619423220] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"277.367629ms","start":"2026-07-22T05:19:11.952752Z","end":"2026-07-22T05:19:12.230119Z","steps":["trace[1619423220] 'process raft request' (duration: 277.231584ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:12.230885Z","caller":"traceutil/trace.go:171","msg":"trace[82885602] linearizableReadLoop","detail":"{readStateIndex:4996; appliedIndex:4993; }","duration":"193.165587ms","start":"2026-07-22T05:19:12.037694Z","end":"2026-07-22T05:19:12.23086Z","steps":["trace[82885602] 'read index received' (duration: 192.294585ms)","trace[82885602] 'applied index is now lower than readState.Index' (duration: 869.792µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:12.230911Z","caller":"traceutil/trace.go:171","msg":"trace[1117339162] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"274.985141ms","start":"2026-07-22T05:19:11.955896Z","end":"2026-07-22T05:19:12.230881Z","steps":["trace[1117339162] 'process raft request' (duration: 274.786284ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:12.230934Z","caller":"traceutil/trace.go:171","msg":"trace[1773463598] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"274.626428ms","start":"2026-07-22T05:19:11.956266Z","end":"2026-07-22T05:19:12.230892Z","steps":["trace[1773463598] 'process raft request' (duration: 274.555115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:12.231121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.396445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T05:19:12.231215Z","caller":"traceutil/trace.go:171","msg":"trace[2060741682] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4811; }","duration":"193.548311ms","start":"2026-07-22T05:19:12.037637Z","end":"2026-07-22T05:19:12.231185Z","steps":["trace[2060741682] 'agreement among raft nodes before linearized reading' (duration: 193.344934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:20.588552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.952129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-07-22T05:19:20.588607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.489208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:19:20.588635Z","caller":"traceutil/trace.go:171","msg":"trace[946788969] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4948; }","duration":"213.122956ms","start":"2026-07-22T05:19:20.375492Z","end":"2026-07-22T05:19:20.588615Z","steps":["trace[946788969] 'range keys from in-memory index tree' (duration: 212.705089ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:20.58866Z","caller":"traceutil/trace.go:171","msg":"trace[1783975070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4948; }","duration":"155.585462ms","start":"2026-07-22T05:19:20.433058Z","end":"2026-07-22T05:19:20.588643Z","steps":["trace[1783975070] 'range keys from in-memory index tree' (duration: 155.393314ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:20:42.08128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2904} {"level":"info","ts":"2026-07-22T05:20:42.155521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2904,"took":"72.619611ms","hash":2879361333,"current-db-size-bytes":53608448,"current-db-size":"54 MB","current-db-size-in-use-bytes":30208000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-22T05:20:42.155578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2879361333,"revision":2904,"compact-revision":-1} {"level":"warn","ts":"2026-07-22T05:24:29.494709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522834740999776455,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T05:24:29.559945Z","caller":"traceutil/trace.go:171","msg":"trace[1182085307] transaction","detail":"{read_only:false; response_revision:7070; number_of_response:1; }","duration":"630.82825ms","start":"2026-07-22T05:24:28.929091Z","end":"2026-07-22T05:24:29.55992Z","steps":["trace[1182085307] 'process raft request' (duration: 630.019598ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:24:29.56074Z","caller":"traceutil/trace.go:171","msg":"trace[1772728140] linearizableReadLoop","detail":"{readStateIndex:7334; appliedIndex:7332; }","duration":"566.243011ms","start":"2026-07-22T05:24:28.99443Z","end":"2026-07-22T05:24:29.560673Z","steps":["trace[1772728140] 'read index received' (duration: 564.619927ms)","trace[1772728140] 'applied index is now lower than readState.Index' (duration: 1.621704ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:24:29.560769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:24:28.929074Z","time spent":"630.95314ms","remote":"127.0.0.1:55376","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-07-22T05:24:29.560911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.462752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T05:24:29.560968Z","caller":"traceutil/trace.go:171","msg":"trace[1564102939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7071; }","duration":"566.558243ms","start":"2026-07-22T05:24:28.994399Z","end":"2026-07-22T05:24:29.560958Z","steps":["trace[1564102939] 'agreement among raft nodes before linearized reading' (duration: 566.347412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:24:29.561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:24:28.994384Z","time spent":"566.610193ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-22T05:24:29.56106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.708593ms","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-07-22T05:24:29.561094Z","caller":"traceutil/trace.go:171","msg":"trace[1129633025] transaction","detail":"{read_only:false; response_revision:7071; number_of_response:1; }","duration":"265.467262ms","start":"2026-07-22T05:24:29.295244Z","end":"2026-07-22T05:24:29.560711Z","steps":["trace[1129633025] 'process raft request' (duration: 264.62253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:24:29.561124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.768247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-22T05:24:29.561137Z","caller":"traceutil/trace.go:171","msg":"trace[1940956382] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7071; }","duration":"457.810893ms","start":"2026-07-22T05:24:29.103297Z","end":"2026-07-22T05:24:29.561108Z","steps":["trace[1940956382] 'agreement among raft nodes before linearized reading' (duration: 457.604093ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:24:29.561191Z","caller":"traceutil/trace.go:171","msg":"trace[198624097] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7071; }","duration":"339.873678ms","start":"2026-07-22T05:24:29.2213Z","end":"2026-07-22T05:24:29.561174Z","steps":["trace[198624097] 'agreement among raft nodes before linearized reading' (duration: 339.679918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:24:29.561197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:24:29.103274Z","time spent":"457.911354ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T05:24:29.561234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:24:29.221265Z","time spent":"339.959729ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T05:24:29.561312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.381825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:24:29.561812Z","caller":"traceutil/trace.go:171","msg":"trace[2011347916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7071; }","duration":"128.904336ms","start":"2026-07-22T05:24:29.432893Z","end":"2026-07-22T05:24:29.561797Z","steps":["trace[2011347916] 'agreement among raft nodes before linearized reading' (duration: 128.052264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:24:29.565714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.042886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" ","response":"range_response_count:71 size:4335024"} {"level":"info","ts":"2026-07-22T05:24:29.565825Z","caller":"traceutil/trace.go:171","msg":"trace[1313944057] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:71; response_revision:7071; }","duration":"107.199106ms","start":"2026-07-22T05:24:29.458602Z","end":"2026-07-22T05:24:29.565801Z","steps":["trace[1313944057] 'agreement among raft nodes before linearized reading' (duration: 102.356983ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:24:57.382879Z","caller":"traceutil/trace.go:171","msg":"trace[755157061] transaction","detail":"{read_only:false; response_revision:7212; number_of_response:1; }","duration":"387.161168ms","start":"2026-07-22T05:24:56.995688Z","end":"2026-07-22T05:24:57.382849Z","steps":["trace[755157061] 'process raft request' (duration: 387.032777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:24:57.383018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:24:56.995668Z","time spent":"387.278988ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:25:42.088758Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5636} {"level":"info","ts":"2026-07-22T05:25:42.161394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5636,"took":"71.301681ms","hash":1826252338,"current-db-size-bytes":53608448,"current-db-size":"54 MB","current-db-size-in-use-bytes":18415616,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-22T05:25:42.161446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1826252338,"revision":5636,"compact-revision":2904}