{"level":"warn","ts":"2026-09-18T15:08:53.591915Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T15:08:53.592032Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.116:2380","--initial-cluster=instance=https://162.253.55.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-18T15:08:53.592127Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T15:08:53.592143Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.116:2380"]} {"level":"info","ts":"2026-09-18T15:08:53.592165Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-18T15:08:53.592903Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"]} {"level":"info","ts":"2026-09-18T15:08:53.59301Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.116:2380"],"listen-peer-urls":["https://162.253.55.116:2380"],"advertise-client-urls":["https://162.253.55.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-18T15:08:53.597815Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.569804ms"} {"level":"info","ts":"2026-09-18T15:08:53.610356Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9755887b76c83964","cluster-id":"5ee78cd75a03071"} {"level":"info","ts":"2026-09-18T15:08:53.610482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T15:08:53.610573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became follower at term 0"} {"level":"info","ts":"2026-09-18T15:08:53.610595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9755887b76c83964 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T15:08:53.610609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became follower at term 1"} {"level":"info","ts":"2026-09-18T15:08:53.610661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=(10904772136602712420)"} {"level":"warn","ts":"2026-09-18T15:08:53.627483Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T15:08:53.628877Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T15:08:53.630271Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-18T15:08:53.632272Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9755887b76c83964","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T15:08:53.632544Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9755887b76c83964","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T15:08:53.632707Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T15:08:53.633322Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T15:08:53.63335Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T15:08:53.633982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=(10904772136602712420)"} {"level":"info","ts":"2026-09-18T15:08:53.634205Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5ee78cd75a03071","local-member-id":"9755887b76c83964","added-peer-id":"9755887b76c83964","added-peer-peer-urls":["https://162.253.55.116:2380"]} {"level":"info","ts":"2026-09-18T15:08:53.636064Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-18T15:08:53.636186Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.116:2380"} {"level":"info","ts":"2026-09-18T15:08:53.636225Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.116:2380"} {"level":"info","ts":"2026-09-18T15:08:53.636306Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9755887b76c83964","initial-advertise-peer-urls":["https://162.253.55.116:2380"],"listen-peer-urls":["https://162.253.55.116:2380"],"advertise-client-urls":["https://162.253.55.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T15:08:53.636353Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T15:08:54.111102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T15:08:54.111207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T15:08:54.111244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 received MsgPreVoteResp from 9755887b76c83964 at term 1"} {"level":"info","ts":"2026-09-18T15:08:54.111264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became candidate at term 2"} {"level":"info","ts":"2026-09-18T15:08:54.111278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 received MsgVoteResp from 9755887b76c83964 at term 2"} {"level":"info","ts":"2026-09-18T15:08:54.1113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became leader at term 2"} {"level":"info","ts":"2026-09-18T15:08:54.111318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9755887b76c83964 elected leader 9755887b76c83964 at term 2"} {"level":"info","ts":"2026-09-18T15:08:54.121988Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9755887b76c83964","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.116:2379]}","request-path":"/0/members/9755887b76c83964/attributes","cluster-id":"5ee78cd75a03071","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T15:08:54.122656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:08:54.122632Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:08:54.122794Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T15:08:54.122787Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:08:54.122853Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T15:08:54.124948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.116:2379"} {"level":"info","ts":"2026-09-18T15:08:54.130343Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5ee78cd75a03071","local-member-id":"9755887b76c83964","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:08:54.130458Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:08:54.130552Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:08:54.131523Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-18T15:09:00.114255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.783323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:09:00.114358Z","caller":"traceutil/trace.go:171","msg":"trace[1548022277] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:267; }","duration":"110.34621ms","start":"2026-09-18T15:09:00.003999Z","end":"2026-09-18T15:09:00.114346Z","steps":["trace[1548022277] 'read index received' (duration: 41.852µs)","trace[1548022277] 'applied index is now lower than readState.Index' (duration: 110.303028ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:09:00.114445Z","caller":"traceutil/trace.go:171","msg":"trace[601778254] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"356.024494ms","start":"2026-09-18T15:08:59.75837Z","end":"2026-09-18T15:09:00.114395Z","steps":["trace[601778254] 'process raft request' (duration: 151.737571ms)","trace[601778254] 'compare' (duration: 203.061675ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:09:00.114534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.479485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:09:00.11459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:08:59.758344Z","time spent":"356.18952ms","remote":"127.0.0.1:35574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:09:00.114675Z","caller":"traceutil/trace.go:171","msg":"trace[715076104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:260; }","duration":"110.647051ms","start":"2026-09-18T15:09:00.003993Z","end":"2026-09-18T15:09:00.11464Z","steps":["trace[715076104] 'agreement among raft nodes before linearized reading' (duration: 110.402102ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:00.543381Z","caller":"traceutil/trace.go:171","msg":"trace[1631989933] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"422.397369ms","start":"2026-09-18T15:09:00.120957Z","end":"2026-09-18T15:09:00.543355Z","steps":["trace[1631989933] 'read index received' (duration: 422.260684ms)","trace[1631989933] 'applied index is now lower than readState.Index' (duration: 135.485µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:09:00.543419Z","caller":"traceutil/trace.go:171","msg":"trace[1801244370] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"424.921858ms","start":"2026-09-18T15:09:00.118468Z","end":"2026-09-18T15:09:00.54339Z","steps":["trace[1801244370] 'process raft request' (duration: 424.751301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:09:00.543561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:00.118449Z","time spent":"425.037923ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:09:00.543562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.590177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-18T15:09:00.543599Z","caller":"traceutil/trace.go:171","msg":"trace[2074661175] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"422.65199ms","start":"2026-09-18T15:09:00.120937Z","end":"2026-09-18T15:09:00.543589Z","steps":["trace[2074661175] 'agreement among raft nodes before linearized reading' (duration: 422.525095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:09:00.54364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:00.120929Z","time spent":"422.705741ms","remote":"127.0.0.1:37830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T15:09:00.691267Z","caller":"traceutil/trace.go:171","msg":"trace[1498018533] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"449.184133ms","start":"2026-09-18T15:09:00.242067Z","end":"2026-09-18T15:09:00.691251Z","steps":["trace[1498018533] 'process raft request' (duration: 449.100219ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:00.691321Z","caller":"traceutil/trace.go:171","msg":"trace[284221672] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"448.08764ms","start":"2026-09-18T15:09:00.243227Z","end":"2026-09-18T15:09:00.691314Z","steps":["trace[284221672] 'process raft request' (duration: 448.012547ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:00.69133Z","caller":"traceutil/trace.go:171","msg":"trace[522410080] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"448.140122ms","start":"2026-09-18T15:09:00.243178Z","end":"2026-09-18T15:09:00.691318Z","steps":["trace[522410080] 'process raft request' (duration: 448.028038ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:00.691305Z","caller":"traceutil/trace.go:171","msg":"trace[2077667209] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:269; }","duration":"147.83372ms","start":"2026-09-18T15:09:00.543441Z","end":"2026-09-18T15:09:00.691275Z","steps":["trace[2077667209] 'read index received' (duration: 147.591271ms)","trace[2077667209] 'applied index is now lower than readState.Index' (duration: 238.749µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:09:00.691376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:00.24322Z","time spent":"448.120882ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:09:00.691431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:00.243171Z","time spent":"448.188345ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:09:00.691382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:00.242056Z","time spent":"449.270096ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:09:00.691267Z","caller":"traceutil/trace.go:171","msg":"trace[581497158] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"449.156192ms","start":"2026-09-18T15:09:00.24208Z","end":"2026-09-18T15:09:00.691236Z","steps":["trace[581497158] 'process raft request' (duration: 448.940954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:09:00.691547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:00.24207Z","time spent":"449.462773ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:09:00.691536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.930572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-09-18T15:09:00.691588Z","caller":"traceutil/trace.go:171","msg":"trace[1804211346] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:261; }","duration":"450.032727ms","start":"2026-09-18T15:09:00.24154Z","end":"2026-09-18T15:09:00.691573Z","steps":["trace[1804211346] 'agreement among raft nodes before linearized reading' (duration: 449.904622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:09:00.691684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:00.241524Z","time spent":"450.148341ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5920,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-09-18T15:09:00.713468Z","caller":"traceutil/trace.go:171","msg":"trace[1408247969] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"165.602403ms","start":"2026-09-18T15:09:00.547835Z","end":"2026-09-18T15:09:00.713437Z","steps":["trace[1408247969] 'process raft request' (duration: 165.369494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:09:00.713751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.495818ms","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-09-18T15:09:00.713814Z","caller":"traceutil/trace.go:171","msg":"trace[920540955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:263; }","duration":"166.591002ms","start":"2026-09-18T15:09:00.547207Z","end":"2026-09-18T15:09:00.713797Z","steps":["trace[920540955] 'agreement among raft nodes before linearized reading' (duration: 166.360433ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:00.713992Z","caller":"traceutil/trace.go:171","msg":"trace[14350991] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"165.181646ms","start":"2026-09-18T15:09:00.548794Z","end":"2026-09-18T15:09:00.713976Z","steps":["trace[14350991] 'process raft request' (duration: 164.551141ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:45.912379Z","caller":"traceutil/trace.go:171","msg":"trace[1732461568] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"136.827976ms","start":"2026-09-18T15:09:45.775519Z","end":"2026-09-18T15:09:45.912347Z","steps":["trace[1732461568] 'process raft request' (duration: 136.14237ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:46.219461Z","caller":"traceutil/trace.go:171","msg":"trace[1572076687] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"241.091177ms","start":"2026-09-18T15:09:45.978343Z","end":"2026-09-18T15:09:46.219434Z","steps":["trace[1572076687] 'process raft request' (duration: 240.953544ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:46.325245Z","caller":"traceutil/trace.go:171","msg":"trace[1472509989] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"319.229596ms","start":"2026-09-18T15:09:46.005987Z","end":"2026-09-18T15:09:46.325217Z","steps":["trace[1472509989] 'read index received' (duration: 213.463043ms)","trace[1472509989] 'applied index is now lower than readState.Index' (duration: 105.765343ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:09:46.325329Z","caller":"traceutil/trace.go:171","msg":"trace[1762021174] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"346.378059ms","start":"2026-09-18T15:09:45.978923Z","end":"2026-09-18T15:09:46.325301Z","steps":["trace[1762021174] 'process raft request' (duration: 293.644321ms)","trace[1762021174] 'compare' (duration: 52.506233ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:09:46.325394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.39179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:09:46.325466Z","caller":"traceutil/trace.go:171","msg":"trace[1487678885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"319.517853ms","start":"2026-09-18T15:09:46.005928Z","end":"2026-09-18T15:09:46.325446Z","steps":["trace[1487678885] 'agreement among raft nodes before linearized reading' (duration: 319.40217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:09:46.325513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:46.005903Z","time spent":"319.599635ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T15:09:46.325692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:45.978904Z","time spent":"346.483752ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:09:46.846307Z","caller":"traceutil/trace.go:171","msg":"trace[403181679] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"457.365184ms","start":"2026-09-18T15:09:46.388899Z","end":"2026-09-18T15:09:46.846264Z","steps":["trace[403181679] 'process raft request' (duration: 456.930473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:09:46.846537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:46.388876Z","time spent":"457.537189ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:09:47.014562Z","caller":"traceutil/trace.go:171","msg":"trace[1481822928] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"586.817058ms","start":"2026-09-18T15:09:46.427719Z","end":"2026-09-18T15:09:47.014536Z","steps":["trace[1481822928] 'process raft request' (duration: 586.440099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:09:47.01483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:09:46.427694Z","time spent":"587.009883ms","remote":"127.0.0.1:35638","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-09-18T15:09:47.015299Z","caller":"traceutil/trace.go:171","msg":"trace[762663102] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"219.974118ms","start":"2026-09-18T15:09:46.795311Z","end":"2026-09-18T15:09:47.015285Z","steps":["trace[762663102] 'process raft request' (duration: 219.258201ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:09:48.718551Z","caller":"traceutil/trace.go:171","msg":"trace[1735868471] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"185.654389ms","start":"2026-09-18T15:09:48.532811Z","end":"2026-09-18T15:09:48.718465Z","steps":["trace[1735868471] 'process raft request' (duration: 150.41917ms)","trace[1735868471] 'compare' (duration: 35.015714ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:10:32.812482Z","caller":"traceutil/trace.go:171","msg":"trace[1812749812] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"185.473201ms","start":"2026-09-18T15:10:32.626975Z","end":"2026-09-18T15:10:32.812448Z","steps":["trace[1812749812] 'process raft request' (duration: 185.195406ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:10:33.024475Z","caller":"traceutil/trace.go:171","msg":"trace[1066281414] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"385.703432ms","start":"2026-09-18T15:10:32.638714Z","end":"2026-09-18T15:10:33.024417Z","steps":["trace[1066281414] 'process raft request' (duration: 385.240904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:10:33.024974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:10:32.638693Z","time spent":"385.893095ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:10:33.031096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.577062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T15:10:33.031212Z","caller":"traceutil/trace.go:171","msg":"trace[1098313492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"325.765955ms","start":"2026-09-18T15:10:32.705433Z","end":"2026-09-18T15:10:33.031199Z","steps":["trace[1098313492] 'agreement among raft nodes before linearized reading' (duration: 325.43976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:10:33.031311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:10:32.705407Z","time spent":"325.892328ms","remote":"127.0.0.1:35638","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":"info","ts":"2026-09-18T15:10:33.030813Z","caller":"traceutil/trace.go:171","msg":"trace[290211060] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"325.255747ms","start":"2026-09-18T15:10:32.705497Z","end":"2026-09-18T15:10:33.030753Z","steps":["trace[290211060] 'read index received' (duration: 106.996999ms)","trace[290211060] 'applied index is now lower than readState.Index' (duration: 218.257358ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:10:55.586224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.684247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-18T15:10:55.586312Z","caller":"traceutil/trace.go:171","msg":"trace[232766387] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1435; }","duration":"118.784279ms","start":"2026-09-18T15:10:55.467508Z","end":"2026-09-18T15:10:55.586293Z","steps":["trace[232766387] 'range keys from in-memory index tree' (duration: 118.507744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:23.219445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.057029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:11:23.219561Z","caller":"traceutil/trace.go:171","msg":"trace[2143676116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"212.225152ms","start":"2026-09-18T15:11:23.007304Z","end":"2026-09-18T15:11:23.21953Z","steps":["trace[2143676116] 'range keys from in-memory index tree' (duration: 211.953138ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:11:23.219946Z","caller":"traceutil/trace.go:171","msg":"trace[1552356525] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"126.066278ms","start":"2026-09-18T15:11:23.093864Z","end":"2026-09-18T15:11:23.21993Z","steps":["trace[1552356525] 'process raft request' (duration: 104.408368ms)","trace[1552356525] 'compare' (duration: 21.059183ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:11:23.628777Z","caller":"traceutil/trace.go:171","msg":"trace[843029497] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"408.009143ms","start":"2026-09-18T15:11:23.220736Z","end":"2026-09-18T15:11:23.628745Z","steps":["trace[843029497] 'read index received' (duration: 313.147153ms)","trace[843029497] 'applied index is now lower than readState.Index' (duration: 94.8602ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:11:23.628923Z","caller":"traceutil/trace.go:171","msg":"trace[917454096] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"495.273831ms","start":"2026-09-18T15:11:23.133635Z","end":"2026-09-18T15:11:23.628909Z","steps":["trace[917454096] 'process raft request' (duration: 400.147517ms)","trace[917454096] 'compare' (duration: 94.788019ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:11:23.628994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.235285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:11:23.629068Z","caller":"traceutil/trace.go:171","msg":"trace[1298070087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1852; }","duration":"408.352048ms","start":"2026-09-18T15:11:23.220698Z","end":"2026-09-18T15:11:23.62905Z","steps":["trace[1298070087] 'agreement among raft nodes before linearized reading' (duration: 408.226966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:23.629077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:23.133607Z","time spent":"495.345372ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:11:23.629101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:23.220682Z","time spent":"408.412568ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T15:11:23.649251Z","caller":"traceutil/trace.go:171","msg":"trace[1084896957] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"422.205082ms","start":"2026-09-18T15:11:23.227007Z","end":"2026-09-18T15:11:23.649212Z","steps":["trace[1084896957] 'process raft request' (duration: 422.103101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:23.64934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.489771ms","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-09-18T15:11:23.649423Z","caller":"traceutil/trace.go:171","msg":"trace[718044382] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1854; }","duration":"394.637023ms","start":"2026-09-18T15:11:23.254764Z","end":"2026-09-18T15:11:23.649401Z","steps":["trace[718044382] 'agreement among raft nodes before linearized reading' (duration: 394.39552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:23.649475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:23.254707Z","time spent":"394.756525ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T15:11:23.649634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.682524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4787406c-7a0b-4ec5-a350-02e345e105db\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-18T15:11:23.649676Z","caller":"traceutil/trace.go:171","msg":"trace[1521214825] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4787406c-7a0b-4ec5-a350-02e345e105db; range_end:; response_count:1; response_revision:1854; }","duration":"217.785455ms","start":"2026-09-18T15:11:23.43188Z","end":"2026-09-18T15:11:23.649665Z","steps":["trace[1521214825] 'agreement among raft nodes before linearized reading' (duration: 217.652034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:23.649668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.547367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-09-18T15:11:23.64965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:23.226984Z","time spent":"422.350674ms","remote":"127.0.0.1:35638","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-09-18T15:11:23.649732Z","caller":"traceutil/trace.go:171","msg":"trace[546645521] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"171.654988ms","start":"2026-09-18T15:11:23.478064Z","end":"2026-09-18T15:11:23.649719Z","steps":["trace[546645521] 'agreement among raft nodes before linearized reading' (duration: 171.502256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:23.649869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.771652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T15:11:23.649901Z","caller":"traceutil/trace.go:171","msg":"trace[987031039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1854; }","duration":"325.829633ms","start":"2026-09-18T15:11:23.324061Z","end":"2026-09-18T15:11:23.649891Z","steps":["trace[987031039] 'agreement among raft nodes before linearized reading' (duration: 325.739852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:23.649925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:23.324038Z","time spent":"325.880124ms","remote":"127.0.0.1:35638","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":"info","ts":"2026-09-18T15:11:23.650021Z","caller":"traceutil/trace.go:171","msg":"trace[422303165] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"424.518164ms","start":"2026-09-18T15:11:23.22549Z","end":"2026-09-18T15:11:23.650008Z","steps":["trace[422303165] 'process raft request' (duration: 423.465299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:23.650139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:23.225457Z","time spent":"424.593255ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:11:23.806634Z","caller":"traceutil/trace.go:171","msg":"trace[1681702310] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"150.356632ms","start":"2026-09-18T15:11:23.656232Z","end":"2026-09-18T15:11:23.806589Z","steps":["trace[1681702310] 'process raft request' (duration: 73.506774ms)","trace[1681702310] 'compare' (duration: 76.589705ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:11:24.071457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.574809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-18T15:11:24.071519Z","caller":"traceutil/trace.go:171","msg":"trace[1599514653] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1858; }","duration":"137.69456ms","start":"2026-09-18T15:11:23.933809Z","end":"2026-09-18T15:11:24.071504Z","steps":["trace[1599514653] 'range keys from in-memory index tree' (duration: 137.382896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:24.071466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.490919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4787406c-7a0b-4ec5-a350-02e345e105db\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-18T15:11:24.071749Z","caller":"traceutil/trace.go:171","msg":"trace[1006383173] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4787406c-7a0b-4ec5-a350-02e345e105db; range_end:; response_count:1; response_revision:1858; }","duration":"161.769362ms","start":"2026-09-18T15:11:23.909916Z","end":"2026-09-18T15:11:24.071685Z","steps":["trace[1006383173] 'range keys from in-memory index tree' (duration: 161.226045ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:11:36.963137Z","caller":"traceutil/trace.go:171","msg":"trace[1466336627] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"101.706715ms","start":"2026-09-18T15:11:36.861383Z","end":"2026-09-18T15:11:36.963089Z","steps":["trace[1466336627] 'process raft request' (duration: 101.365411ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:11:53.134702Z","caller":"traceutil/trace.go:171","msg":"trace[535811462] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"117.301467ms","start":"2026-09-18T15:11:53.017372Z","end":"2026-09-18T15:11:53.134673Z","steps":["trace[535811462] 'process raft request' (duration: 117.172146ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:11:58.728507Z","caller":"traceutil/trace.go:171","msg":"trace[1463499517] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"166.931052ms","start":"2026-09-18T15:11:58.561547Z","end":"2026-09-18T15:11:58.728478Z","steps":["trace[1463499517] 'process raft request' (duration: 166.74397ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:11:59.206662Z","caller":"traceutil/trace.go:171","msg":"trace[395931007] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"166.996268ms","start":"2026-09-18T15:11:59.03963Z","end":"2026-09-18T15:11:59.206626Z","steps":["trace[395931007] 'process raft request' (duration: 166.754495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:59.489885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.713043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:11:59.490032Z","caller":"traceutil/trace.go:171","msg":"trace[2035222224] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"325.717967ms","start":"2026-09-18T15:11:59.164256Z","end":"2026-09-18T15:11:59.489974Z","steps":["trace[2035222224] 'process raft request' (duration: 207.813721ms)","trace[2035222224] 'compare' (duration: 117.575312ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:11:59.490112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:59.164227Z","time spent":"325.839609ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:11:59.565588Z","caller":"traceutil/trace.go:171","msg":"trace[1384579801] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2188; }","duration":"206.661447ms","start":"2026-09-18T15:11:59.358897Z","end":"2026-09-18T15:11:59.565559Z","steps":["trace[1384579801] 'read index received' (duration: 13.078547ms)","trace[1384579801] 'applied index is now lower than readState.Index' (duration: 193.58164ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:11:59.565714Z","caller":"traceutil/trace.go:171","msg":"trace[1422956408] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"235.629598ms","start":"2026-09-18T15:11:59.330073Z","end":"2026-09-18T15:11:59.565703Z","steps":["trace[1422956408] 'process raft request' (duration: 235.324324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:59.566067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.152354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:11:59.566141Z","caller":"traceutil/trace.go:171","msg":"trace[451575461] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2106; }","duration":"207.295066ms","start":"2026-09-18T15:11:59.358827Z","end":"2026-09-18T15:11:59.566122Z","steps":["trace[451575461] 'agreement among raft nodes before linearized reading' (duration: 207.167244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:59.878242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.782391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:11:59.878356Z","caller":"traceutil/trace.go:171","msg":"trace[674546803] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"312.66603ms","start":"2026-09-18T15:11:59.56567Z","end":"2026-09-18T15:11:59.878336Z","steps":["trace[674546803] 'read index received' (duration: 23.549116ms)","trace[674546803] 'applied index is now lower than readState.Index' (duration: 289.115424ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:11:59.878444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.720468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:11:59.878571Z","caller":"traceutil/trace.go:171","msg":"trace[37676335] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2107; }","duration":"479.807039ms","start":"2026-09-18T15:11:59.398657Z","end":"2026-09-18T15:11:59.878465Z","steps":["trace[37676335] 'agreement among raft nodes before linearized reading' (duration: 479.725958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:11:59.878624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:59.398631Z","time spent":"479.979771ms","remote":"127.0.0.1:37814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-09-18T15:11:59.87884Z","caller":"traceutil/trace.go:171","msg":"trace[40227083] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"445.732717ms","start":"2026-09-18T15:11:59.43305Z","end":"2026-09-18T15:11:59.878783Z","steps":["trace[40227083] 'process raft request' (duration: 156.288459ms)","trace[40227083] 'compare' (duration: 288.658699ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:11:59.878995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:11:59.433021Z","time spent":"445.89577ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:12:00.067789Z","caller":"traceutil/trace.go:171","msg":"trace[1601990441] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"150.678529ms","start":"2026-09-18T15:11:59.91708Z","end":"2026-09-18T15:12:00.067758Z","steps":["trace[1601990441] 'read index received' (duration: 109.897527ms)","trace[1601990441] 'applied index is now lower than readState.Index' (duration: 40.779632ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:12:00.06793Z","caller":"traceutil/trace.go:171","msg":"trace[518602971] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"171.211237ms","start":"2026-09-18T15:11:59.896706Z","end":"2026-09-18T15:12:00.067917Z","steps":["trace[518602971] 'process raft request' (duration: 130.348114ms)","trace[518602971] 'compare' (duration: 40.276826ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:12:00.068181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.064995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-18T15:12:00.068267Z","caller":"traceutil/trace.go:171","msg":"trace[1831375702] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2109; }","duration":"151.216206ms","start":"2026-09-18T15:11:59.917031Z","end":"2026-09-18T15:12:00.068247Z","steps":["trace[1831375702] 'agreement among raft nodes before linearized reading' (duration: 150.989744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:12:00.068243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.058745ms","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-09-18T15:12:00.068373Z","caller":"traceutil/trace.go:171","msg":"trace[935829806] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2109; }","duration":"151.199846ms","start":"2026-09-18T15:11:59.91713Z","end":"2026-09-18T15:12:00.06833Z","steps":["trace[935829806] 'agreement among raft nodes before linearized reading' (duration: 150.908743ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:13:05.305802Z","caller":"traceutil/trace.go:171","msg":"trace[641968494] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"118.050618ms","start":"2026-09-18T15:13:05.187714Z","end":"2026-09-18T15:13:05.305764Z","steps":["trace[641968494] 'process raft request' (duration: 117.831308ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:13:05.311491Z","caller":"traceutil/trace.go:171","msg":"trace[1433179047] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"120.359141ms","start":"2026-09-18T15:13:05.191104Z","end":"2026-09-18T15:13:05.311463Z","steps":["trace[1433179047] 'process raft request' (duration: 120.144181ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:13:41.51517Z","caller":"traceutil/trace.go:171","msg":"trace[1142196432] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"149.560604ms","start":"2026-09-18T15:13:41.365578Z","end":"2026-09-18T15:13:41.515139Z","steps":["trace[1142196432] 'process raft request' (duration: 149.268333ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:24.048856Z","caller":"traceutil/trace.go:171","msg":"trace[404883016] transaction","detail":"{read_only:false; response_revision:3700; number_of_response:1; }","duration":"110.518088ms","start":"2026-09-18T15:16:23.938314Z","end":"2026-09-18T15:16:24.048832Z","steps":["trace[404883016] 'process raft request' (duration: 110.421826ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:24.048904Z","caller":"traceutil/trace.go:171","msg":"trace[1226910903] transaction","detail":"{read_only:false; response_revision:3699; number_of_response:1; }","duration":"110.594899ms","start":"2026-09-18T15:16:23.938254Z","end":"2026-09-18T15:16:24.048849Z","steps":["trace[1226910903] 'process raft request' (duration: 99.291079ms)","trace[1226910903] 'compare' (duration: 11.035475ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:18:04.475541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.217179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T15:18:04.475614Z","caller":"traceutil/trace.go:171","msg":"trace[501293285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4768; }","duration":"106.364302ms","start":"2026-09-18T15:18:04.369235Z","end":"2026-09-18T15:18:04.475599Z","steps":["trace[501293285] 'range keys from in-memory index tree' (duration: 105.994706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:04.680423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.380696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-18T15:18:04.680509Z","caller":"traceutil/trace.go:171","msg":"trace[2024718084] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4769; }","duration":"104.483988ms","start":"2026-09-18T15:18:04.575996Z","end":"2026-09-18T15:18:04.68048Z","steps":["trace[2024718084] 'range keys from in-memory index tree' (duration: 104.237884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.234726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.271418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:18:13.234809Z","caller":"traceutil/trace.go:171","msg":"trace[2054678059] linearizableReadLoop","detail":"{readStateIndex:5138; appliedIndex:5137; }","duration":"572.824398ms","start":"2026-09-18T15:18:12.661972Z","end":"2026-09-18T15:18:13.234797Z","steps":["trace[2054678059] 'read index received' (duration: 305.438578ms)","trace[2054678059] 'applied index is now lower than readState.Index' (duration: 267.38504ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:18:13.235038Z","caller":"traceutil/trace.go:171","msg":"trace[996103616] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"634.261315ms","start":"2026-09-18T15:18:12.600771Z","end":"2026-09-18T15:18:13.235032Z","steps":["trace[996103616] 'process raft request' (duration: 366.608831ms)","trace[996103616] 'compare' (duration: 267.091155ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:18:13.23508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.600757Z","time spent":"634.303127ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:18:13.235262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.291626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T15:18:13.235281Z","caller":"traceutil/trace.go:171","msg":"trace[1636109871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4946; }","duration":"573.354848ms","start":"2026-09-18T15:18:12.661922Z","end":"2026-09-18T15:18:13.235276Z","steps":["trace[1636109871] 'agreement among raft nodes before linearized reading' (duration: 573.275586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.235294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.661893Z","time spent":"573.399068ms","remote":"127.0.0.1:35638","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-09-18T15:18:13.305477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.724442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T15:18:13.305595Z","caller":"traceutil/trace.go:171","msg":"trace[1986263269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4947; }","duration":"538.843664ms","start":"2026-09-18T15:18:12.766697Z","end":"2026-09-18T15:18:13.30554Z","steps":["trace[1986263269] 'agreement among raft nodes before linearized reading' (duration: 538.61598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.305646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.766691Z","time spent":"538.943026ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T15:18:13.305465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.338231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:18:13.306709Z","caller":"traceutil/trace.go:171","msg":"trace[816466147] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4947; }","duration":"558.598893ms","start":"2026-09-18T15:18:12.748093Z","end":"2026-09-18T15:18:13.306692Z","steps":["trace[816466147] 'agreement among raft nodes before linearized reading' (duration: 557.307091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.306822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.748037Z","time spent":"558.757307ms","remote":"127.0.0.1:35614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:18:13.307125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.894945ms","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":"warn","ts":"2026-09-18T15:18:13.305678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.152148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:18:13.307245Z","caller":"traceutil/trace.go:171","msg":"trace[63326396] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4947; }","duration":"223.733427ms","start":"2026-09-18T15:18:13.083464Z","end":"2026-09-18T15:18:13.307197Z","steps":["trace[63326396] 'agreement among raft nodes before linearized reading' (duration: 222.182449ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.307174Z","caller":"traceutil/trace.go:171","msg":"trace[1656865384] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4947; }","duration":"486.990856ms","start":"2026-09-18T15:18:12.820165Z","end":"2026-09-18T15:18:13.307156Z","steps":["trace[1656865384] 'agreement among raft nodes before linearized reading' (duration: 486.781533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.307474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.820131Z","time spent":"487.325532ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T15:18:13.307436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.66195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-18T15:18:13.307583Z","caller":"traceutil/trace.go:171","msg":"trace[1587531497] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"155.704154ms","start":"2026-09-18T15:18:13.151868Z","end":"2026-09-18T15:18:13.307572Z","steps":["trace[1587531497] 'process raft request' (duration: 153.20666ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.307662Z","caller":"traceutil/trace.go:171","msg":"trace[1087629136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4947; }","duration":"482.905375ms","start":"2026-09-18T15:18:12.824691Z","end":"2026-09-18T15:18:13.307596Z","steps":["trace[1087629136] 'agreement among raft nodes before linearized reading' (duration: 482.405186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.307748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.824648Z","time spent":"483.079997ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T15:18:13.308262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.385828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-18T15:18:13.308348Z","caller":"traceutil/trace.go:171","msg":"trace[1351188280] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4947; }","duration":"623.112135ms","start":"2026-09-18T15:18:12.685212Z","end":"2026-09-18T15:18:13.308324Z","steps":["trace[1351188280] 'agreement among raft nodes before linearized reading' (duration: 620.303416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.308391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.685192Z","time spent":"623.186637ms","remote":"127.0.0.1:35574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":920,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-09-18T15:18:13.30866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.718353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T15:18:13.308715Z","caller":"traceutil/trace.go:171","msg":"trace[1368637586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4947; }","duration":"542.816115ms","start":"2026-09-18T15:18:12.765888Z","end":"2026-09-18T15:18:13.308704Z","steps":["trace[1368637586] 'agreement among raft nodes before linearized reading' (duration: 539.909374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.308753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.76587Z","time spent":"542.874516ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-18T15:18:13.312195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.615741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-18T15:18:13.312323Z","caller":"traceutil/trace.go:171","msg":"trace[1016145009] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4947; }","duration":"529.819725ms","start":"2026-09-18T15:18:12.782436Z","end":"2026-09-18T15:18:13.312256Z","steps":["trace[1016145009] 'agreement among raft nodes before linearized reading' (duration: 522.837872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.312398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:12.782417Z","time spent":"529.945417ms","remote":"127.0.0.1:37818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207441,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-09-18T15:18:13.521454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.314648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:18:13.521725Z","caller":"traceutil/trace.go:171","msg":"trace[160347865] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"283.216369ms","start":"2026-09-18T15:18:13.238483Z","end":"2026-09-18T15:18:13.521699Z","steps":["trace[160347865] 'process raft request' (duration: 149.422972ms)","trace[160347865] 'compare' (duration: 133.190466ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:18:13.52191Z","caller":"traceutil/trace.go:171","msg":"trace[1965824105] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"250.651412ms","start":"2026-09-18T15:18:13.271227Z","end":"2026-09-18T15:18:13.521878Z","steps":["trace[1965824105] 'process raft request' (duration: 250.369807ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.52191Z","caller":"traceutil/trace.go:171","msg":"trace[727059659] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5139; }","duration":"216.658831ms","start":"2026-09-18T15:18:13.305215Z","end":"2026-09-18T15:18:13.521874Z","steps":["trace[727059659] 'read index received' (duration: 82.702912ms)","trace[727059659] 'applied index is now lower than readState.Index' (duration: 133.951809ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:18:13.522183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.995117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T15:18:13.52222Z","caller":"traceutil/trace.go:171","msg":"trace[801665640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4949; }","duration":"230.077538ms","start":"2026-09-18T15:18:13.292129Z","end":"2026-09-18T15:18:13.522206Z","steps":["trace[801665640] 'agreement among raft nodes before linearized reading' (duration: 229.874795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.607332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.8622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4029f991-cb3e-438c-a672-67d062ed8810\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-18T15:18:13.607433Z","caller":"traceutil/trace.go:171","msg":"trace[1914801645] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4029f991-cb3e-438c-a672-67d062ed8810; range_end:; response_count:1; response_revision:4953; }","duration":"296.986932ms","start":"2026-09-18T15:18:13.310419Z","end":"2026-09-18T15:18:13.607406Z","steps":["trace[1914801645] 'agreement among raft nodes before linearized reading' (duration: 296.697547ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.607747Z","caller":"traceutil/trace.go:171","msg":"trace[112340698] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"292.528923ms","start":"2026-09-18T15:18:13.315166Z","end":"2026-09-18T15:18:13.607695Z","steps":["trace[112340698] 'process raft request' (duration: 291.7892ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.607991Z","caller":"traceutil/trace.go:171","msg":"trace[1886093874] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"291.174759ms","start":"2026-09-18T15:18:13.316763Z","end":"2026-09-18T15:18:13.607938Z","steps":["trace[1886093874] 'process raft request' (duration: 290.246153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.608029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.153144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"warn","ts":"2026-09-18T15:18:13.608468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.043422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-18T15:18:13.608522Z","caller":"traceutil/trace.go:171","msg":"trace[213865304] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4953; }","duration":"194.098113ms","start":"2026-09-18T15:18:13.414406Z","end":"2026-09-18T15:18:13.608504Z","steps":["trace[213865304] 'agreement among raft nodes before linearized reading' (duration: 193.94183ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.608516Z","caller":"traceutil/trace.go:171","msg":"trace[906661148] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4953; }","duration":"220.677213ms","start":"2026-09-18T15:18:13.387815Z","end":"2026-09-18T15:18:13.608492Z","steps":["trace[906661148] 'agreement among raft nodes before linearized reading' (duration: 220.089882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.608071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.786259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-55856dc5b-csp66.18d67350a507b56f\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-09-18T15:18:13.608829Z","caller":"traceutil/trace.go:171","msg":"trace[1279170933] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-55856dc5b-csp66.18d67350a507b56f; range_end:; response_count:1; response_revision:4953; }","duration":"219.591963ms","start":"2026-09-18T15:18:13.389219Z","end":"2026-09-18T15:18:13.608811Z","steps":["trace[1279170933] 'agreement among raft nodes before linearized reading' (duration: 218.747589ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.6081Z","caller":"traceutil/trace.go:171","msg":"trace[1056274821] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"297.158755ms","start":"2026-09-18T15:18:13.310914Z","end":"2026-09-18T15:18:13.608072Z","steps":["trace[1056274821] 'process raft request' (duration: 295.883923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.6082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.611691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-18T15:18:13.609341Z","caller":"traceutil/trace.go:171","msg":"trace[2031531131] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4953; }","duration":"260.745912ms","start":"2026-09-18T15:18:13.348579Z","end":"2026-09-18T15:18:13.609325Z","steps":["trace[2031531131] 'agreement among raft nodes before linearized reading' (duration: 259.53904ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.608194Z","caller":"traceutil/trace.go:171","msg":"trace[823067990] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"290.269104ms","start":"2026-09-18T15:18:13.317893Z","end":"2026-09-18T15:18:13.608162Z","steps":["trace[823067990] 'process raft request' (duration: 289.171585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.607777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.83676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4029f991-cb3e-438c-a672-67d062ed8810\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-18T15:18:13.60965Z","caller":"traceutil/trace.go:171","msg":"trace[906797796] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4029f991-cb3e-438c-a672-67d062ed8810; range_end:; response_count:1; response_revision:4953; }","duration":"138.747504ms","start":"2026-09-18T15:18:13.47089Z","end":"2026-09-18T15:18:13.609638Z","steps":["trace[906797796] 'agreement among raft nodes before linearized reading' (duration: 136.392143ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.680284Z","caller":"traceutil/trace.go:171","msg":"trace[236465258] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"116.022192ms","start":"2026-09-18T15:18:13.564246Z","end":"2026-09-18T15:18:13.680269Z","steps":["trace[236465258] 'process raft request' (duration: 115.989542ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.680495Z","caller":"traceutil/trace.go:171","msg":"trace[735701954] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"153.7765ms","start":"2026-09-18T15:18:13.52669Z","end":"2026-09-18T15:18:13.680467Z","steps":["trace[735701954] 'process raft request' (duration: 108.778214ms)","trace[735701954] 'compare' (duration: 44.67091ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:18:13.759263Z","caller":"traceutil/trace.go:171","msg":"trace[1283599549] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"145.243738ms","start":"2026-09-18T15:18:13.613987Z","end":"2026-09-18T15:18:13.75923Z","steps":["trace[1283599549] 'process raft request' (duration: 144.995834ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.759415Z","caller":"traceutil/trace.go:171","msg":"trace[1310084334] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5145; }","duration":"144.618388ms","start":"2026-09-18T15:18:13.61477Z","end":"2026-09-18T15:18:13.759389Z","steps":["trace[1310084334] 'read index received' (duration: 20.714867ms)","trace[1310084334] 'applied index is now lower than readState.Index' (duration: 123.902461ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:18:13.759764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.969914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3b7004f1-dff4-4ffc-a283-6c91c837f5e6\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-18T15:18:13.759874Z","caller":"traceutil/trace.go:171","msg":"trace[1228754782] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3b7004f1-dff4-4ffc-a283-6c91c837f5e6; range_end:; response_count:1; response_revision:4957; }","duration":"145.127027ms","start":"2026-09-18T15:18:13.614729Z","end":"2026-09-18T15:18:13.759856Z","steps":["trace[1228754782] 'agreement among raft nodes before linearized reading' (duration: 144.870963ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.759912Z","caller":"traceutil/trace.go:171","msg":"trace[1757746911] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"131.586887ms","start":"2026-09-18T15:18:13.628284Z","end":"2026-09-18T15:18:13.759871Z","steps":["trace[1757746911] 'process raft request' (duration: 130.931046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.759971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.650879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-18T15:18:13.76007Z","caller":"traceutil/trace.go:171","msg":"trace[998809257] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4957; }","duration":"113.801192ms","start":"2026-09-18T15:18:13.646209Z","end":"2026-09-18T15:18:13.76001Z","steps":["trace[998809257] 'agreement among raft nodes before linearized reading' (duration: 113.449536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.912949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.452339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:18:13.913121Z","caller":"traceutil/trace.go:171","msg":"trace[235285298] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"148.554087ms","start":"2026-09-18T15:18:13.76451Z","end":"2026-09-18T15:18:13.913064Z","steps":["trace[235285298] 'process raft request' (duration: 44.883844ms)","trace[235285298] 'compare' (duration: 103.119954ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:18:13.927467Z","caller":"traceutil/trace.go:171","msg":"trace[295274022] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"160.191543ms","start":"2026-09-18T15:18:13.767225Z","end":"2026-09-18T15:18:13.927417Z","steps":["trace[295274022] 'process raft request' (duration: 159.955209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:13.927779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.266609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-18T15:18:13.927802Z","caller":"traceutil/trace.go:171","msg":"trace[932550276] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"158.379981ms","start":"2026-09-18T15:18:13.769381Z","end":"2026-09-18T15:18:13.927761Z","steps":["trace[932550276] 'process raft request' (duration: 158.050265ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.927847Z","caller":"traceutil/trace.go:171","msg":"trace[217898860] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4963; }","duration":"158.37494ms","start":"2026-09-18T15:18:13.769455Z","end":"2026-09-18T15:18:13.92783Z","steps":["trace[217898860] 'agreement among raft nodes before linearized reading' (duration: 158.178956ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.927576Z","caller":"traceutil/trace.go:171","msg":"trace[30044033] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"158.38677ms","start":"2026-09-18T15:18:13.769135Z","end":"2026-09-18T15:18:13.927521Z","steps":["trace[30044033] 'process raft request' (duration: 158.152806ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.927958Z","caller":"traceutil/trace.go:171","msg":"trace[404721738] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"158.732717ms","start":"2026-09-18T15:18:13.769207Z","end":"2026-09-18T15:18:13.92794Z","steps":["trace[404721738] 'process raft request' (duration: 158.166867ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:13.9276Z","caller":"traceutil/trace.go:171","msg":"trace[1912461989] linearizableReadLoop","detail":"{readStateIndex:5154; appliedIndex:5149; }","duration":"158.037705ms","start":"2026-09-18T15:18:13.769504Z","end":"2026-09-18T15:18:13.927542Z","steps":["trace[1912461989] 'read index received' (duration: 39.869885ms)","trace[1912461989] 'applied index is now lower than readState.Index' (duration: 118.16665ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:18:13.928263Z","caller":"traceutil/trace.go:171","msg":"trace[187222711] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"129.062672ms","start":"2026-09-18T15:18:13.799177Z","end":"2026-09-18T15:18:13.92824Z","steps":["trace[187222711] 'process raft request' (duration: 128.304489ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:14.225725Z","caller":"traceutil/trace.go:171","msg":"trace[911442394] linearizableReadLoop","detail":"{readStateIndex:5156; appliedIndex:5155; }","duration":"221.602976ms","start":"2026-09-18T15:18:14.004094Z","end":"2026-09-18T15:18:14.225697Z","steps":["trace[911442394] 'read index received' (duration: 221.425942ms)","trace[911442394] 'applied index is now lower than readState.Index' (duration: 175.834µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:18:14.225862Z","caller":"traceutil/trace.go:171","msg":"trace[1361140048] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"242.658117ms","start":"2026-09-18T15:18:13.983189Z","end":"2026-09-18T15:18:14.225847Z","steps":["trace[1361140048] 'process raft request' (duration: 242.162768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:14.226091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.978162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:18:14.226155Z","caller":"traceutil/trace.go:171","msg":"trace[1278259956] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4964; }","duration":"222.098264ms","start":"2026-09-18T15:18:14.004037Z","end":"2026-09-18T15:18:14.226136Z","steps":["trace[1278259956] 'agreement among raft nodes before linearized reading' (duration: 221.976242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:14.246662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.057358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:18:14.246732Z","caller":"traceutil/trace.go:171","msg":"trace[1705832301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4965; }","duration":"241.148059ms","start":"2026-09-18T15:18:14.005566Z","end":"2026-09-18T15:18:14.246714Z","steps":["trace[1705832301] 'agreement among raft nodes before linearized reading' (duration: 240.453437ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:14.523654Z","caller":"traceutil/trace.go:171","msg":"trace[173582495] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"106.96129ms","start":"2026-09-18T15:18:14.416661Z","end":"2026-09-18T15:18:14.523622Z","steps":["trace[173582495] 'process raft request' (duration: 69.691552ms)","trace[173582495] 'compare' (duration: 36.908231ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:18:27.030102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4135607057354463357,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:18:27.224517Z","caller":"traceutil/trace.go:171","msg":"trace[1920961207] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"705.649882ms","start":"2026-09-18T15:18:26.51883Z","end":"2026-09-18T15:18:27.22448Z","steps":["trace[1920961207] 'process raft request' (duration: 705.451349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.224676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:26.51879Z","time spent":"705.803085ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:18:27.449167Z","caller":"traceutil/trace.go:171","msg":"trace[389538028] linearizableReadLoop","detail":"{readStateIndex:5311; appliedIndex:5310; }","duration":"919.139438ms","start":"2026-09-18T15:18:26.529991Z","end":"2026-09-18T15:18:27.44913Z","steps":["trace[389538028] 'read index received' (duration: 694.481718ms)","trace[389538028] 'applied index is now lower than readState.Index' (duration: 224.64031ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:18:27.449492Z","caller":"traceutil/trace.go:171","msg":"trace[1147644383] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"476.895741ms","start":"2026-09-18T15:18:26.972489Z","end":"2026-09-18T15:18:27.449385Z","steps":["trace[1147644383] 'process raft request' (duration: 392.35718ms)","trace[1147644383] 'compare' (duration: 84.086334ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:18:27.449601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.582195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-18T15:18:27.449663Z","caller":"traceutil/trace.go:171","msg":"trace[1347999136] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5115; }","duration":"919.693757ms","start":"2026-09-18T15:18:26.529954Z","end":"2026-09-18T15:18:27.449648Z","steps":["trace[1347999136] 'agreement among raft nodes before linearized reading' (duration: 919.431512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.449704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:26.529935Z","time spent":"919.762399ms","remote":"127.0.0.1:35534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-18T15:18:27.449874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:26.972473Z","time spent":"477.149765ms","remote":"127.0.0.1:35638","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-09-18T15:18:27.498638Z","caller":"traceutil/trace.go:171","msg":"trace[1487220939] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"239.416477ms","start":"2026-09-18T15:18:27.25907Z","end":"2026-09-18T15:18:27.498486Z","steps":["trace[1487220939] 'process raft request' (duration: 239.109971ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:27.498846Z","caller":"traceutil/trace.go:171","msg":"trace[1741955183] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"239.565461ms","start":"2026-09-18T15:18:27.259234Z","end":"2026-09-18T15:18:27.4988Z","steps":["trace[1741955183] 'process raft request' (duration: 239.137832ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:27.498991Z","caller":"traceutil/trace.go:171","msg":"trace[426252389] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"239.479679ms","start":"2026-09-18T15:18:27.259438Z","end":"2026-09-18T15:18:27.498918Z","steps":["trace[426252389] 'process raft request' (duration: 239.201314ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:27.499635Z","caller":"traceutil/trace.go:171","msg":"trace[1843271965] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"239.950597ms","start":"2026-09-18T15:18:27.259673Z","end":"2026-09-18T15:18:27.499624Z","steps":["trace[1843271965] 'process raft request' (duration: 239.188594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.500027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.253286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:18:27.500723Z","caller":"traceutil/trace.go:171","msg":"trace[705060849] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5120; }","duration":"355.016489ms","start":"2026-09-18T15:18:27.145694Z","end":"2026-09-18T15:18:27.50071Z","steps":["trace[705060849] 'agreement among raft nodes before linearized reading' (duration: 354.253956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.500765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:27.145671Z","time spent":"355.07994ms","remote":"127.0.0.1:37626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-09-18T15:18:27.500788Z","caller":"traceutil/trace.go:171","msg":"trace[711477606] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"137.056506ms","start":"2026-09-18T15:18:27.363706Z","end":"2026-09-18T15:18:27.500762Z","steps":["trace[711477606] 'process raft request' (duration: 135.353217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.500955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.644499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:18:27.501019Z","caller":"traceutil/trace.go:171","msg":"trace[280233389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5120; }","duration":"494.752551ms","start":"2026-09-18T15:18:27.00625Z","end":"2026-09-18T15:18:27.501003Z","steps":["trace[280233389] 'agreement among raft nodes before linearized reading' (duration: 494.604849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.501055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:27.006236Z","time spent":"494.811372ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T15:18:27.501514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.534483ms","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-09-18T15:18:27.501558Z","caller":"traceutil/trace.go:171","msg":"trace[776142959] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5120; }","duration":"539.616433ms","start":"2026-09-18T15:18:26.961928Z","end":"2026-09-18T15:18:27.501545Z","steps":["trace[776142959] 'agreement among raft nodes before linearized reading' (duration: 539.491511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.501578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:18:26.961905Z","time spent":"539.668435ms","remote":"127.0.0.1:53116","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":"warn","ts":"2026-09-18T15:18:27.501621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.830173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-18T15:18:27.501671Z","caller":"traceutil/trace.go:171","msg":"trace[1283761114] range","detail":"{range_begin:/registry/masterleases/162.253.55.116; range_end:; response_count:1; response_revision:5120; }","duration":"186.903423ms","start":"2026-09-18T15:18:27.314753Z","end":"2026-09-18T15:18:27.501657Z","steps":["trace[1283761114] 'agreement among raft nodes before linearized reading' (duration: 186.71582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.75713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.290255ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-09-18T15:18:27.757254Z","caller":"traceutil/trace.go:171","msg":"trace[742109744] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5316; }","duration":"249.485733ms","start":"2026-09-18T15:18:27.50775Z","end":"2026-09-18T15:18:27.757236Z","steps":["trace[742109744] 'read index received' (duration: 52.137377ms)","trace[742109744] 'applied index is now lower than readState.Index' (duration: 197.346596ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:18:27.757973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.207965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-09-18T15:18:27.758112Z","caller":"traceutil/trace.go:171","msg":"trace[1810002973] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:5120; }","duration":"250.419859ms","start":"2026-09-18T15:18:27.507675Z","end":"2026-09-18T15:18:27.758095Z","steps":["trace[1810002973] 'agreement among raft nodes before linearized reading' (duration: 249.878869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.758212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.155181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-18T15:18:27.758295Z","caller":"traceutil/trace.go:171","msg":"trace[1825510273] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5120; }","duration":"151.267903ms","start":"2026-09-18T15:18:27.607006Z","end":"2026-09-18T15:18:27.758274Z","steps":["trace[1825510273] 'agreement among raft nodes before linearized reading' (duration: 150.773324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:18:27.75873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.902876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T15:18:27.758847Z","caller":"traceutil/trace.go:171","msg":"trace[637027708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5120; }","duration":"155.017458ms","start":"2026-09-18T15:18:27.603811Z","end":"2026-09-18T15:18:27.758829Z","steps":["trace[637027708] 'agreement among raft nodes before linearized reading' (duration: 153.789317ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:27.933537Z","caller":"traceutil/trace.go:171","msg":"trace[2132197960] linearizableReadLoop","detail":"{readStateIndex:5326; appliedIndex:5324; }","duration":"114.3144ms","start":"2026-09-18T15:18:27.819192Z","end":"2026-09-18T15:18:27.933506Z","steps":["trace[2132197960] 'read index received' (duration: 21.1988ms)","trace[2132197960] 'applied index is now lower than readState.Index' (duration: 93.1143ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:18:27.933801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.596275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-09-18T15:18:27.933846Z","caller":"traceutil/trace.go:171","msg":"trace[825663205] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5129; }","duration":"114.687767ms","start":"2026-09-18T15:18:27.819147Z","end":"2026-09-18T15:18:27.933834Z","steps":["trace[825663205] 'agreement among raft nodes before linearized reading' (duration: 114.528164ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:27.933615Z","caller":"traceutil/trace.go:171","msg":"trace[1908678667] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"114.836419ms","start":"2026-09-18T15:18:27.818749Z","end":"2026-09-18T15:18:27.933585Z","steps":["trace[1908678667] 'process raft request' (duration: 81.430447ms)","trace[1908678667] 'compare' (duration: 33.115507ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:18:54.19358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2830} {"level":"warn","ts":"2026-09-18T15:18:54.391303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.50874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:18:54.391772Z","caller":"traceutil/trace.go:171","msg":"trace[1385463588] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"131.247715ms","start":"2026-09-18T15:18:54.260504Z","end":"2026-09-18T15:18:54.391752Z","steps":["trace[1385463588] 'process raft request' (duration: 130.923028ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:18:54.391786Z","caller":"traceutil/trace.go:171","msg":"trace[212574791] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"136.212979ms","start":"2026-09-18T15:18:54.255514Z","end":"2026-09-18T15:18:54.391727Z","steps":["trace[212574791] 'process raft request' (duration: 34.170139ms)","trace[212574791] 'compare' (duration: 38.28706ms)","trace[212574791] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:552; } (duration: 63.049368ms)"],"step_count":3} {"level":"info","ts":"2026-09-18T15:18:54.435476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2830,"took":"240.188281ms","hash":3545917049,"current-db-size-bytes":51408896,"current-db-size":"51 MB","current-db-size-in-use-bytes":27578368,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T15:18:54.435535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3545917049,"revision":2830,"compact-revision":-1} {"level":"info","ts":"2026-09-18T15:23:54.200704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5311} {"level":"info","ts":"2026-09-18T15:23:54.284639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5311,"took":"82.310593ms","hash":2244801040,"current-db-size-bytes":51408896,"current-db-size":"51 MB","current-db-size-in-use-bytes":18432000,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-18T15:23:54.284695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2244801040,"revision":5311,"compact-revision":2830}