{"level":"warn","ts":"2026-10-15T14:08:33.405701Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:08:33.405809Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.139: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.204.45.139:2380","--initial-cluster=instance=https://199.204.45.139:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.139:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.139:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T14:08:33.405887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:08:33.405897Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.139:2380"]} {"level":"info","ts":"2026-10-15T14:08:33.40592Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T14:08:33.406668Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.139:2379"]} {"level":"info","ts":"2026-10-15T14:08:33.40675Z","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.204.45.139:2380"],"listen-peer-urls":["https://199.204.45.139:2380"],"advertise-client-urls":["https://199.204.45.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.139:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T14:08:33.411535Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.540071ms"} {"level":"info","ts":"2026-10-15T14:08:33.421285Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5739a4baaf9cb6ee","cluster-id":"f3dfd8b01c2185e2"} {"level":"info","ts":"2026-10-15T14:08:33.421391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:08:33.421416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became follower at term 0"} {"level":"info","ts":"2026-10-15T14:08:33.421429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5739a4baaf9cb6ee [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:08:33.421436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became follower at term 1"} {"level":"info","ts":"2026-10-15T14:08:33.421477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee switched to configuration voters=(6285235876689393390)"} {"level":"warn","ts":"2026-10-15T14:08:33.432221Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:08:33.436937Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:08:33.438243Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-15T14:08:33.441001Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5739a4baaf9cb6ee","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:08:33.441124Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5739a4baaf9cb6ee","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:08:33.441204Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T14:08:33.44128Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T14:08:33.441291Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T14:08:33.442283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee switched to configuration voters=(6285235876689393390)"} {"level":"info","ts":"2026-10-15T14:08:33.442362Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f3dfd8b01c2185e2","local-member-id":"5739a4baaf9cb6ee","added-peer-id":"5739a4baaf9cb6ee","added-peer-peer-urls":["https://199.204.45.139:2380"]} {"level":"info","ts":"2026-10-15T14:08:33.443284Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T14:08:33.443452Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5739a4baaf9cb6ee","initial-advertise-peer-urls":["https://199.204.45.139:2380"],"listen-peer-urls":["https://199.204.45.139:2380"],"advertise-client-urls":["https://199.204.45.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:08:33.44348Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:08:33.443473Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.139:2380"} {"level":"info","ts":"2026-10-15T14:08:33.443538Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.139:2380"} {"level":"info","ts":"2026-10-15T14:08:34.322681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:08:34.322725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:08:34.322751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee received MsgPreVoteResp from 5739a4baaf9cb6ee at term 1"} {"level":"info","ts":"2026-10-15T14:08:34.322763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:08:34.322771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee received MsgVoteResp from 5739a4baaf9cb6ee at term 2"} {"level":"info","ts":"2026-10-15T14:08:34.32278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became leader at term 2"} {"level":"info","ts":"2026-10-15T14:08:34.322789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5739a4baaf9cb6ee elected leader 5739a4baaf9cb6ee at term 2"} {"level":"info","ts":"2026-10-15T14:08:34.324004Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5739a4baaf9cb6ee","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.139:2379]}","request-path":"/0/members/5739a4baaf9cb6ee/attributes","cluster-id":"f3dfd8b01c2185e2","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:08:34.324056Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:08:34.324145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:08:34.324165Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:08:34.32432Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:08:34.32444Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:08:34.325903Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f3dfd8b01c2185e2","local-member-id":"5739a4baaf9cb6ee","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:08:34.326373Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:08:34.326415Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:08:34.329038Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:08:34.331657Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.139:2379"} {"level":"info","ts":"2026-10-15T14:09:19.331318Z","caller":"traceutil/trace.go:171","msg":"trace[2131364996] transaction","detail":"{read_only:false; number_of_response:0; response_revision:556; }","duration":"133.022824ms","start":"2026-10-15T14:09:19.198265Z","end":"2026-10-15T14:09:19.331288Z","steps":["trace[2131364996] 'process raft request' (duration: 114.79178ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:19.331781Z","caller":"traceutil/trace.go:171","msg":"trace[95070157] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"133.172528ms","start":"2026-10-15T14:09:19.198583Z","end":"2026-10-15T14:09:19.331756Z","steps":["trace[95070157] 'process raft request' (duration: 132.63075ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:19.332547Z","caller":"traceutil/trace.go:171","msg":"trace[1256369466] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"113.34552ms","start":"2026-10-15T14:09:19.219179Z","end":"2026-10-15T14:09:19.332525Z","steps":["trace[1256369466] 'process raft request' (duration: 112.322487ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:19.655201Z","caller":"traceutil/trace.go:171","msg":"trace[877170859] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"104.066787ms","start":"2026-10-15T14:09:19.550977Z","end":"2026-10-15T14:09:19.655044Z","steps":["trace[877170859] 'process raft request' (duration: 103.888985ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:19.661079Z","caller":"traceutil/trace.go:171","msg":"trace[1315975372] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"107.717725ms","start":"2026-10-15T14:09:19.553326Z","end":"2026-10-15T14:09:19.661044Z","steps":["trace[1315975372] 'process raft request' (duration: 107.414131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:26.169174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.160524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:09:26.169329Z","caller":"traceutil/trace.go:171","msg":"trace[1404631578] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"164.188789ms","start":"2026-10-15T14:09:26.005119Z","end":"2026-10-15T14:09:26.169308Z","steps":["trace[1404631578] 'read index received' (duration: 68.06µs)","trace[1404631578] 'applied index is now lower than readState.Index' (duration: 164.118799ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:09:26.169415Z","caller":"traceutil/trace.go:171","msg":"trace[1128981335] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"366.260736ms","start":"2026-10-15T14:09:25.80312Z","end":"2026-10-15T14:09:26.169381Z","steps":["trace[1128981335] 'process raft request' (duration: 156.25381ms)","trace[1128981335] 'compare' (duration: 208.76973ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:09:26.169551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.415853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T14:09:26.169582Z","caller":"traceutil/trace.go:171","msg":"trace[72090419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"164.521775ms","start":"2026-10-15T14:09:26.00505Z","end":"2026-10-15T14:09:26.169572Z","steps":["trace[72090419] 'agreement among raft nodes before linearized reading' (duration: 164.309832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:26.169589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:09:25.803104Z","time spent":"366.397438ms","remote":"127.0.0.1:41682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:09:26.417143Z","caller":"traceutil/trace.go:171","msg":"trace[1763804592] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"242.547836ms","start":"2026-10-15T14:09:26.174563Z","end":"2026-10-15T14:09:26.417111Z","steps":["trace[1763804592] 'process raft request' (duration: 242.317323ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:26.41718Z","caller":"traceutil/trace.go:171","msg":"trace[1636936337] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"241.964908ms","start":"2026-10-15T14:09:26.175188Z","end":"2026-10-15T14:09:26.417153Z","steps":["trace[1636936337] 'process raft request' (duration: 241.832716ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:26.417172Z","caller":"traceutil/trace.go:171","msg":"trace[422011708] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"242.772259ms","start":"2026-10-15T14:09:26.174345Z","end":"2026-10-15T14:09:26.417118Z","steps":["trace[422011708] 'process raft request' (duration: 163.518251ms)","trace[422011708] 'compare' (duration: 78.781662ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:09:26.417321Z","caller":"traceutil/trace.go:171","msg":"trace[887584220] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:678; }","duration":"195.9316ms","start":"2026-10-15T14:09:26.221363Z","end":"2026-10-15T14:09:26.417294Z","steps":["trace[887584220] 'read index received' (duration: 116.413409ms)","trace[887584220] 'applied index is now lower than readState.Index' (duration: 79.517161ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:09:26.417569Z","caller":"traceutil/trace.go:171","msg":"trace[480450495] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"242.283282ms","start":"2026-10-15T14:09:26.175261Z","end":"2026-10-15T14:09:26.417544Z","steps":["trace[480450495] 'process raft request' (duration: 241.802316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:26.41767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.278985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2dc6j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-15T14:09:26.417767Z","caller":"traceutil/trace.go:171","msg":"trace[900334557] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2dc6j; range_end:; response_count:1; response_revision:656; }","duration":"196.390516ms","start":"2026-10-15T14:09:26.221356Z","end":"2026-10-15T14:09:26.417746Z","steps":["trace[900334557] 'agreement among raft nodes before linearized reading' (duration: 196.127913ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:26.571046Z","caller":"traceutil/trace.go:171","msg":"trace[1605071951] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"134.381297ms","start":"2026-10-15T14:09:26.43663Z","end":"2026-10-15T14:09:26.571011Z","steps":["trace[1605071951] 'process raft request' (duration: 134.066013ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:27.175853Z","caller":"traceutil/trace.go:171","msg":"trace[634890946] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"481.435519ms","start":"2026-10-15T14:09:26.694389Z","end":"2026-10-15T14:09:27.175825Z","steps":["trace[634890946] 'read index received' (duration: 481.170495ms)","trace[634890946] 'applied index is now lower than readState.Index' (duration: 263.834µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:09:27.176057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.001134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:09:27.176229Z","caller":"traceutil/trace.go:171","msg":"trace[1087448996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"422.188626ms","start":"2026-10-15T14:09:26.753982Z","end":"2026-10-15T14:09:27.17617Z","steps":["trace[1087448996] 'agreement among raft nodes before linearized reading' (duration: 422.022244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:27.176291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:09:26.753971Z","time spent":"422.304588ms","remote":"127.0.0.1:41496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T14:09:27.176513Z","caller":"traceutil/trace.go:171","msg":"trace[1512902483] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"519.975518ms","start":"2026-10-15T14:09:26.655888Z","end":"2026-10-15T14:09:27.175863Z","steps":["trace[1512902483] 'process raft request' (duration: 519.715684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:27.176771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.367341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:09:27.176864Z","caller":"traceutil/trace.go:171","msg":"trace[652896742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"482.548364ms","start":"2026-10-15T14:09:26.694303Z","end":"2026-10-15T14:09:27.176851Z","steps":["trace[652896742] 'agreement among raft nodes before linearized reading' (duration: 481.679213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:27.176931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:09:26.694286Z","time spent":"482.635555ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T14:09:27.177084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:09:26.655856Z","time spent":"520.796549ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:09:42.703453Z","caller":"traceutil/trace.go:171","msg":"trace[355512874] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"106.551288ms","start":"2026-10-15T14:09:42.59687Z","end":"2026-10-15T14:09:42.703421Z","steps":["trace[355512874] 'read index received' (duration: 48.790456ms)","trace[355512874] 'applied index is now lower than readState.Index' (duration: 57.759312ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:09:42.703739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.836621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T14:09:42.703855Z","caller":"traceutil/trace.go:171","msg":"trace[657336627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:815; }","duration":"107.001195ms","start":"2026-10-15T14:09:42.596832Z","end":"2026-10-15T14:09:42.703834Z","steps":["trace[657336627] 'agreement among raft nodes before linearized reading' (duration: 106.710581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:42.70416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.603575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T14:09:42.704284Z","caller":"traceutil/trace.go:171","msg":"trace[869155649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:815; }","duration":"101.757726ms","start":"2026-10-15T14:09:42.602508Z","end":"2026-10-15T14:09:42.704266Z","steps":["trace[869155649] 'agreement among raft nodes before linearized reading' (duration: 101.508913ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:10:59.19869Z","caller":"traceutil/trace.go:171","msg":"trace[221392719] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"273.379876ms","start":"2026-10-15T14:10:58.92529Z","end":"2026-10-15T14:10:59.19867Z","steps":["trace[221392719] 'process raft request' (duration: 273.271532ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:10:59.19901Z","caller":"traceutil/trace.go:171","msg":"trace[372189615] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1964; }","duration":"179.333415ms","start":"2026-10-15T14:10:59.019668Z","end":"2026-10-15T14:10:59.199001Z","steps":["trace[372189615] 'read index received' (duration: 179.329915ms)","trace[372189615] 'applied index is now lower than readState.Index' (duration: 2.52µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:10:59.19913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.449509ms","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-10-15T14:10:59.199156Z","caller":"traceutil/trace.go:171","msg":"trace[1977081091] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1888; }","duration":"179.532142ms","start":"2026-10-15T14:10:59.019612Z","end":"2026-10-15T14:10:59.199145Z","steps":["trace[1977081091] 'agreement among raft nodes before linearized reading' (duration: 179.427928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:59.385791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.13686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:10:59.386148Z","caller":"traceutil/trace.go:171","msg":"trace[732865791] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"187.071871ms","start":"2026-10-15T14:10:59.199027Z","end":"2026-10-15T14:10:59.386099Z","steps":["trace[732865791] 'read index received' (duration: 32.469866ms)","trace[732865791] 'applied index is now lower than readState.Index' (duration: 154.600305ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:10:59.38637Z","caller":"traceutil/trace.go:171","msg":"trace[1979547640] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"312.896134ms","start":"2026-10-15T14:10:59.073449Z","end":"2026-10-15T14:10:59.386345Z","steps":["trace[1979547640] 'process raft request' (duration: 158.102672ms)","trace[1979547640] 'compare' (duration: 153.83162ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:10:59.386369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.02685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-10-15T14:10:59.386451Z","caller":"traceutil/trace.go:171","msg":"trace[1574392664] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1889; }","duration":"190.173865ms","start":"2026-10-15T14:10:59.196257Z","end":"2026-10-15T14:10:59.38643Z","steps":["trace[1574392664] 'agreement among raft nodes before linearized reading' (duration: 189.993649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:59.386477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:10:59.073427Z","time spent":"312.995757ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:10:59.386652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.479274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-15T14:10:59.386692Z","caller":"traceutil/trace.go:171","msg":"trace[691485092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1889; }","duration":"269.552197ms","start":"2026-10-15T14:10:59.117128Z","end":"2026-10-15T14:10:59.38668Z","steps":["trace[691485092] 'agreement among raft nodes before linearized reading' (duration: 269.062401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:59.387086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.94818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T14:10:59.387203Z","caller":"traceutil/trace.go:171","msg":"trace[805962588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1889; }","duration":"264.118647ms","start":"2026-10-15T14:10:59.123054Z","end":"2026-10-15T14:10:59.387173Z","steps":["trace[805962588] 'agreement among raft nodes before linearized reading' (duration: 263.252817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:59.981801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.761232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83369c9d-e17e-43d0-9c37-779e3184f058\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-15T14:10:59.981821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.4986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:10:59.981879Z","caller":"traceutil/trace.go:171","msg":"trace[55719070] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83369c9d-e17e-43d0-9c37-779e3184f058; range_end:; response_count:1; response_revision:1894; }","duration":"259.901996ms","start":"2026-10-15T14:10:59.721956Z","end":"2026-10-15T14:10:59.981858Z","steps":["trace[55719070] 'range keys from in-memory index tree' (duration: 259.546905ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:10:59.981912Z","caller":"traceutil/trace.go:171","msg":"trace[169779750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1894; }","duration":"226.648115ms","start":"2026-10-15T14:10:59.755245Z","end":"2026-10-15T14:10:59.981893Z","steps":["trace[169779750] 'range keys from in-memory index tree' (duration: 226.440288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:59.981906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.601881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83369c9d-e17e-43d0-9c37-779e3184f058\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-15T14:10:59.982006Z","caller":"traceutil/trace.go:171","msg":"trace[389964959] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83369c9d-e17e-43d0-9c37-779e3184f058; range_end:; response_count:1; response_revision:1894; }","duration":"213.692994ms","start":"2026-10-15T14:10:59.76827Z","end":"2026-10-15T14:10:59.981963Z","steps":["trace[389964959] 'range keys from in-memory index tree' (duration: 213.448367ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:14.28206Z","caller":"traceutil/trace.go:171","msg":"trace[849799387] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"126.785027ms","start":"2026-10-15T14:11:14.155243Z","end":"2026-10-15T14:11:14.282028Z","steps":["trace[849799387] 'read index received' (duration: 126.567351ms)","trace[849799387] 'applied index is now lower than readState.Index' (duration: 216.266µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:11:14.28216Z","caller":"traceutil/trace.go:171","msg":"trace[846794220] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"129.406048ms","start":"2026-10-15T14:11:14.152726Z","end":"2026-10-15T14:11:14.282132Z","steps":["trace[846794220] 'process raft request' (duration: 129.083899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:11:14.282495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.21276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-15T14:11:14.282604Z","caller":"traceutil/trace.go:171","msg":"trace[1332126578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2017; }","duration":"127.381406ms","start":"2026-10-15T14:11:14.155196Z","end":"2026-10-15T14:11:14.282577Z","steps":["trace[1332126578] 'agreement among raft nodes before linearized reading' (duration: 126.959003ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:33.751296Z","caller":"traceutil/trace.go:171","msg":"trace[1194928215] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"199.529131ms","start":"2026-10-15T14:11:33.551738Z","end":"2026-10-15T14:11:33.751268Z","steps":["trace[1194928215] 'process raft request' (duration: 199.088219ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:33.969915Z","caller":"traceutil/trace.go:171","msg":"trace[1564562830] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"210.233899ms","start":"2026-10-15T14:11:33.759638Z","end":"2026-10-15T14:11:33.969872Z","steps":["trace[1564562830] 'process raft request' (duration: 191.845441ms)","trace[1564562830] 'compare' (duration: 17.522083ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:11:34.050888Z","caller":"traceutil/trace.go:171","msg":"trace[1161071826] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"291.193549ms","start":"2026-10-15T14:11:33.759653Z","end":"2026-10-15T14:11:34.050846Z","steps":["trace[1161071826] 'process raft request' (duration: 291.000684ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:34.051238Z","caller":"traceutil/trace.go:171","msg":"trace[43410443] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"291.449036ms","start":"2026-10-15T14:11:33.759761Z","end":"2026-10-15T14:11:34.05121Z","steps":["trace[43410443] 'process raft request' (duration: 291.041474ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:34.051336Z","caller":"traceutil/trace.go:171","msg":"trace[1494189735] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"291.509378ms","start":"2026-10-15T14:11:33.759793Z","end":"2026-10-15T14:11:34.051302Z","steps":["trace[1494189735] 'process raft request' (duration: 291.25134ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:34.051249Z","caller":"traceutil/trace.go:171","msg":"trace[1703153203] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2215; }","duration":"179.573652ms","start":"2026-10-15T14:11:33.871643Z","end":"2026-10-15T14:11:34.051217Z","steps":["trace[1703153203] 'read index received' (duration: 79.811403ms)","trace[1703153203] 'applied index is now lower than readState.Index' (duration: 99.760949ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:11:34.051557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.86071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:11:34.051988Z","caller":"traceutil/trace.go:171","msg":"trace[747730319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2136; }","duration":"180.379485ms","start":"2026-10-15T14:11:33.871594Z","end":"2026-10-15T14:11:34.051948Z","steps":["trace[747730319] 'agreement among raft nodes before linearized reading' (duration: 179.727527ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:34.171056Z","caller":"traceutil/trace.go:171","msg":"trace[1834540849] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"114.612199ms","start":"2026-10-15T14:11:34.056402Z","end":"2026-10-15T14:11:34.171014Z","steps":["trace[1834540849] 'process raft request' (duration: 16.194863ms)","trace[1834540849] 'compare' (duration: 98.107907ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:11:34.546468Z","caller":"traceutil/trace.go:171","msg":"trace[1745732235] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"153.447681ms","start":"2026-10-15T14:11:34.392992Z","end":"2026-10-15T14:11:34.546439Z","steps":["trace[1745732235] 'read index received' (duration: 68.432488ms)","trace[1745732235] 'applied index is now lower than readState.Index' (duration: 85.013893ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:11:34.546813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.792149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-15T14:11:34.546821Z","caller":"traceutil/trace.go:171","msg":"trace[406658084] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"191.116868ms","start":"2026-10-15T14:11:34.355518Z","end":"2026-10-15T14:11:34.546635Z","steps":["trace[406658084] 'process raft request' (duration: 105.992642ms)","trace[406658084] 'compare' (duration: 84.422316ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:11:34.546991Z","caller":"traceutil/trace.go:171","msg":"trace[695260906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2138; }","duration":"154.017487ms","start":"2026-10-15T14:11:34.392951Z","end":"2026-10-15T14:11:34.546968Z","steps":["trace[695260906] 'agreement among raft nodes before linearized reading' (duration: 153.705777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:11:34.830055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.809929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:11:34.830524Z","caller":"traceutil/trace.go:171","msg":"trace[721467487] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"182.017447ms","start":"2026-10-15T14:11:34.648473Z","end":"2026-10-15T14:11:34.830491Z","steps":["trace[721467487] 'process raft request' (duration: 19.6243ms)","trace[721467487] 'compare' (duration: 161.608745ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:12:38.588444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.200365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:12:38.588582Z","caller":"traceutil/trace.go:171","msg":"trace[1052059118] linearizableReadLoop","detail":"{readStateIndex:2684; appliedIndex:2682; }","duration":"206.247621ms","start":"2026-10-15T14:12:38.382306Z","end":"2026-10-15T14:12:38.588554Z","steps":["trace[1052059118] 'read index received' (duration: 73.049375ms)","trace[1052059118] 'applied index is now lower than readState.Index' (duration: 133.197445ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:12:38.588801Z","caller":"traceutil/trace.go:171","msg":"trace[55044754] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"218.748318ms","start":"2026-10-15T14:12:38.370039Z","end":"2026-10-15T14:12:38.588787Z","steps":["trace[55044754] 'process raft request' (duration: 85.144703ms)","trace[55044754] 'compare' (duration: 132.97666ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:12:38.588831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.419289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:12:38.588854Z","caller":"traceutil/trace.go:171","msg":"trace[544652425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2586; }","duration":"105.466789ms","start":"2026-10-15T14:12:38.483379Z","end":"2026-10-15T14:12:38.588846Z","steps":["trace[544652425] 'agreement among raft nodes before linearized reading' (duration: 105.374088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:12:38.588827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.496986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-15T14:12:38.58887Z","caller":"traceutil/trace.go:171","msg":"trace[661471286] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"216.951747ms","start":"2026-10-15T14:12:38.3719Z","end":"2026-10-15T14:12:38.588852Z","steps":["trace[661471286] 'process raft request' (duration: 216.615759ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:12:38.588918Z","caller":"traceutil/trace.go:171","msg":"trace[1615787134] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2586; }","duration":"206.605409ms","start":"2026-10-15T14:12:38.382289Z","end":"2026-10-15T14:12:38.588895Z","steps":["trace[1615787134] 'agreement among raft nodes before linearized reading' (duration: 206.364023ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:17:38.519627Z","caller":"traceutil/trace.go:171","msg":"trace[637065532] linearizableReadLoop","detail":"{readStateIndex:5018; appliedIndex:5017; }","duration":"109.813074ms","start":"2026-10-15T14:17:38.409792Z","end":"2026-10-15T14:17:38.519605Z","steps":["trace[637065532] 'read index received' (duration: 109.654721ms)","trace[637065532] 'applied index is now lower than readState.Index' (duration: 157.303µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:17:38.519976Z","caller":"traceutil/trace.go:171","msg":"trace[1910180133] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"129.705513ms","start":"2026-10-15T14:17:38.390247Z","end":"2026-10-15T14:17:38.519953Z","steps":["trace[1910180133] 'process raft request' (duration: 129.226536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:38.520134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.311952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T14:17:38.520221Z","caller":"traceutil/trace.go:171","msg":"trace[1931949056] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4833; }","duration":"110.440544ms","start":"2026-10-15T14:17:38.409757Z","end":"2026-10-15T14:17:38.520197Z","steps":["trace[1931949056] 'agreement among raft nodes before linearized reading' (duration: 110.153449ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:17:43.830657Z","caller":"traceutil/trace.go:171","msg":"trace[778207082] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"277.740275ms","start":"2026-10-15T14:17:43.552885Z","end":"2026-10-15T14:17:43.830625Z","steps":["trace[778207082] 'process raft request' (duration: 277.526691ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:17:43.968696Z","caller":"traceutil/trace.go:171","msg":"trace[341246957] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"127.679279ms","start":"2026-10-15T14:17:43.840983Z","end":"2026-10-15T14:17:43.968662Z","steps":["trace[341246957] 'process raft request' (duration: 105.715398ms)","trace[341246957] 'compare' (duration: 21.855959ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:17:57.832483Z","caller":"traceutil/trace.go:171","msg":"trace[734729292] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5330; }","duration":"351.018506ms","start":"2026-10-15T14:17:57.481442Z","end":"2026-10-15T14:17:57.83246Z","steps":["trace[734729292] 'read index received' (duration: 350.847063ms)","trace[734729292] 'applied index is now lower than readState.Index' (duration: 170.673µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:17:57.83296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.350707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-10-15T14:17:57.833021Z","caller":"traceutil/trace.go:171","msg":"trace[1244840499] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5139; }","duration":"336.440058ms","start":"2026-10-15T14:17:57.496566Z","end":"2026-10-15T14:17:57.833006Z","steps":["trace[1244840499] 'agreement among raft nodes before linearized reading' (duration: 336.213624ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:57.833049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:57.496553Z","time spent":"336.489219ms","remote":"127.0.0.1:36220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-10-15T14:17:57.833263Z","caller":"traceutil/trace.go:171","msg":"trace[88725046] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"351.877479ms","start":"2026-10-15T14:17:57.481361Z","end":"2026-10-15T14:17:57.833239Z","steps":["trace[88725046] 'process raft request' (duration: 350.980946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:57.833338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.87516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.139\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-15T14:17:57.833414Z","caller":"traceutil/trace.go:171","msg":"trace[2066003686] range","detail":"{range_begin:/registry/masterleases/199.204.45.139; range_end:; response_count:1; response_revision:5139; }","duration":"352.008191ms","start":"2026-10-15T14:17:57.481389Z","end":"2026-10-15T14:17:57.833397Z","steps":["trace[2066003686] 'agreement among raft nodes before linearized reading' (duration: 351.800547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:57.833449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:57.481372Z","time spent":"352.069432ms","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.139\" "} {"level":"warn","ts":"2026-10-15T14:17:57.833604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:57.481334Z","time spent":"352.047673ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:17:58.109944Z","caller":"traceutil/trace.go:171","msg":"trace[1867681924] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"116.957577ms","start":"2026-10-15T14:17:57.992969Z","end":"2026-10-15T14:17:58.109927Z","steps":["trace[1867681924] 'process raft request' (duration: 116.807575ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:17:58.110072Z","caller":"traceutil/trace.go:171","msg":"trace[1787748934] linearizableReadLoop","detail":"{readStateIndex:5336; appliedIndex:5334; }","duration":"113.950633ms","start":"2026-10-15T14:17:57.996114Z","end":"2026-10-15T14:17:58.110064Z","steps":["trace[1787748934] 'read index received' (duration: 39.48208ms)","trace[1787748934] 'applied index is now lower than readState.Index' (duration: 74.467903ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:17:58.110202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.074154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-10-15T14:17:58.110226Z","caller":"traceutil/trace.go:171","msg":"trace[1334986365] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5144; }","duration":"114.145636ms","start":"2026-10-15T14:17:57.996075Z","end":"2026-10-15T14:17:58.11022Z","steps":["trace[1334986365] 'agreement among raft nodes before linearized reading' (duration: 114.022514ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:34.376102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2887} {"level":"info","ts":"2026-10-15T14:18:34.445504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2887,"took":"68.211838ms","hash":3870476254,"current-db-size-bytes":53067776,"current-db-size":"53 MB","current-db-size-in-use-bytes":28282880,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:18:34.445572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3870476254,"revision":2887,"compact-revision":-1} {"level":"info","ts":"2026-10-15T14:23:34.384714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5452} {"level":"info","ts":"2026-10-15T14:23:34.468022Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5452,"took":"81.764188ms","hash":3209665345,"current-db-size-bytes":53067776,"current-db-size":"53 MB","current-db-size-in-use-bytes":18497536,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-15T14:23:34.468113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3209665345,"revision":5452,"compact-revision":2887}