{"level":"warn","ts":"2026-10-14T17:40:29.784022Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:40:29.784142Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.10: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.10:2380","--initial-cluster=instance=https://162.253.55.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.10:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-14T17:40:29.784231Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:40:29.784242Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-10-14T17:40:29.784259Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-14T17:40:29.784955Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"]} {"level":"info","ts":"2026-10-14T17:40:29.785033Z","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.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.10:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-14T17:40:29.79182Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.507787ms"} {"level":"info","ts":"2026-10-14T17:40:29.803049Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"373d5d49ce4adaff","cluster-id":"467836e907a15d60"} {"level":"info","ts":"2026-10-14T17:40:29.803128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:40:29.803149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 0"} {"level":"info","ts":"2026-10-14T17:40:29.803163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 373d5d49ce4adaff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:40:29.803169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 1"} {"level":"info","ts":"2026-10-14T17:40:29.803206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"warn","ts":"2026-10-14T17:40:29.813949Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:40:29.816041Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:40:29.817789Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-14T17:40:29.820405Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"373d5d49ce4adaff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:40:29.820584Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T17:40:29.820669Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T17:40:29.820609Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"373d5d49ce4adaff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:40:29.820738Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T17:40:29.821723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"info","ts":"2026-10-14T17:40:29.823326Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","added-peer-id":"373d5d49ce4adaff","added-peer-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-10-14T17:40:29.828529Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-14T17:40:29.82873Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-10-14T17:40:29.828787Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-10-14T17:40:29.82891Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"373d5d49ce4adaff","initial-advertise-peer-urls":["https://162.253.55.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:40:29.82897Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:40:30.103372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:40:30.103439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:40:30.103505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgPreVoteResp from 373d5d49ce4adaff at term 1"} {"level":"info","ts":"2026-10-14T17:40:30.103526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:40:30.103536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgVoteResp from 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-10-14T17:40:30.103547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became leader at term 2"} {"level":"info","ts":"2026-10-14T17:40:30.103557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 373d5d49ce4adaff elected leader 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-10-14T17:40:30.106746Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"373d5d49ce4adaff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.10:2379]}","request-path":"/0/members/373d5d49ce4adaff/attributes","cluster-id":"467836e907a15d60","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:40:30.106756Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:40:30.107037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:40:30.107002Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:40:30.107613Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:40:30.107751Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:40:30.110309Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:40:30.111332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.10:2379"} {"level":"info","ts":"2026-10-14T17:40:30.115856Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:40:30.115975Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:40:30.116012Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:40:44.81928Z","caller":"traceutil/trace.go:171","msg":"trace[213425005] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"126.252029ms","start":"2026-10-14T17:40:44.692994Z","end":"2026-10-14T17:40:44.819246Z","steps":["trace[213425005] 'read index received' (duration: 126.089216ms)","trace[213425005] 'applied index is now lower than readState.Index' (duration: 161.403µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:40:44.819455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.429052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-14T17:40:44.819524Z","caller":"traceutil/trace.go:171","msg":"trace[320554731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"126.574294ms","start":"2026-10-14T17:40:44.692937Z","end":"2026-10-14T17:40:44.819512Z","steps":["trace[320554731] 'agreement among raft nodes before linearized reading' (duration: 126.444632ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:40:44.819759Z","caller":"traceutil/trace.go:171","msg":"trace[303937849] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"255.89581ms","start":"2026-10-14T17:40:44.563844Z","end":"2026-10-14T17:40:44.819739Z","steps":["trace[303937849] 'process raft request' (duration: 255.170716ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:13.535668Z","caller":"traceutil/trace.go:171","msg":"trace[244401743] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"100.326025ms","start":"2026-10-14T17:41:13.435303Z","end":"2026-10-14T17:41:13.535629Z","steps":["trace[244401743] 'process raft request' (duration: 94.304713ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:13.535823Z","caller":"traceutil/trace.go:171","msg":"trace[1724810622] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"100.191953ms","start":"2026-10-14T17:41:13.435591Z","end":"2026-10-14T17:41:13.535783Z","steps":["trace[1724810622] 'process raft request' (duration: 99.935499ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:20.294632Z","caller":"traceutil/trace.go:171","msg":"trace[981506147] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"180.436054ms","start":"2026-10-14T17:41:20.114171Z","end":"2026-10-14T17:41:20.294607Z","steps":["trace[981506147] 'process raft request' (duration: 180.20251ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:20.348537Z","caller":"traceutil/trace.go:171","msg":"trace[1672962527] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"155.367068ms","start":"2026-10-14T17:41:20.193108Z","end":"2026-10-14T17:41:20.348475Z","steps":["trace[1672962527] 'process raft request' (duration: 154.654487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:20.961802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.722221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:41:20.961979Z","caller":"traceutil/trace.go:171","msg":"trace[829801185] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"525.982597ms","start":"2026-10-14T17:41:20.435927Z","end":"2026-10-14T17:41:20.96191Z","steps":["trace[829801185] 'process raft request' (duration: 128.561666ms)","trace[829801185] 'compare' (duration: 396.556018ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:41:20.961912Z","caller":"traceutil/trace.go:171","msg":"trace[1699503456] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"313.919753ms","start":"2026-10-14T17:41:20.647965Z","end":"2026-10-14T17:41:20.961884Z","steps":["trace[1699503456] 'read index received' (duration: 33.881µs)","trace[1699503456] 'applied index is now lower than readState.Index' (duration: 313.884732ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:41:20.962139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.153586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qfbmw\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-14T17:41:20.962219Z","caller":"traceutil/trace.go:171","msg":"trace[37033670] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qfbmw; range_end:; response_count:1; response_revision:641; }","duration":"314.287129ms","start":"2026-10-14T17:41:20.647913Z","end":"2026-10-14T17:41:20.9622Z","steps":["trace[37033670] 'agreement among raft nodes before linearized reading' (duration: 314.073075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:20.962267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:20.647888Z","time spent":"314.360738ms","remote":"127.0.0.1:43210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-qfbmw\" "} {"level":"warn","ts":"2026-10-14T17:41:20.962242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:20.435889Z","time spent":"526.16586ms","remote":"127.0.0.1:43346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T17:41:21.190027Z","caller":"traceutil/trace.go:171","msg":"trace[1402445712] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"185.653144ms","start":"2026-10-14T17:41:21.004351Z","end":"2026-10-14T17:41:21.190004Z","steps":["trace[1402445712] 'read index received' (duration: 185.423661ms)","trace[1402445712] 'applied index is now lower than readState.Index' (duration: 228.633µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:41:21.190175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.810968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:41:21.190211Z","caller":"traceutil/trace.go:171","msg":"trace[1943006104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"185.912179ms","start":"2026-10-14T17:41:21.004289Z","end":"2026-10-14T17:41:21.190201Z","steps":["trace[1943006104] 'agreement among raft nodes before linearized reading' (duration: 185.832338ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:21.190176Z","caller":"traceutil/trace.go:171","msg":"trace[1728885292] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"208.186454ms","start":"2026-10-14T17:41:20.981938Z","end":"2026-10-14T17:41:21.190125Z","steps":["trace[1728885292] 'process raft request' (duration: 207.90082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:21.1903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.17529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-14T17:41:21.190392Z","caller":"traceutil/trace.go:171","msg":"trace[142998839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"139.313793ms","start":"2026-10-14T17:41:21.051046Z","end":"2026-10-14T17:41:21.19036Z","steps":["trace[142998839] 'agreement among raft nodes before linearized reading' (duration: 139.16165ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:37.09373Z","caller":"traceutil/trace.go:171","msg":"trace[2046611883] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"150.852394ms","start":"2026-10-14T17:41:36.942836Z","end":"2026-10-14T17:41:37.093689Z","steps":["trace[2046611883] 'process raft request' (duration: 150.610521ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:37.247187Z","caller":"traceutil/trace.go:171","msg":"trace[226839447] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"149.927858ms","start":"2026-10-14T17:41:37.097236Z","end":"2026-10-14T17:41:37.247164Z","steps":["trace[226839447] 'read index received' (duration: 149.762486ms)","trace[226839447] 'applied index is now lower than readState.Index' (duration: 164.572µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:41:37.247245Z","caller":"traceutil/trace.go:171","msg":"trace[1878851269] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"163.007343ms","start":"2026-10-14T17:41:37.084217Z","end":"2026-10-14T17:41:37.247225Z","steps":["trace[1878851269] 'process raft request' (duration: 162.802199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:37.247293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.038138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:41:37.247348Z","caller":"traceutil/trace.go:171","msg":"trace[1763915922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"150.13714ms","start":"2026-10-14T17:41:37.097199Z","end":"2026-10-14T17:41:37.247336Z","steps":["trace[1763915922] 'agreement among raft nodes before linearized reading' (duration: 150.049928ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:07.423348Z","caller":"traceutil/trace.go:171","msg":"trace[733285311] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"176.092076ms","start":"2026-10-14T17:42:07.247229Z","end":"2026-10-14T17:42:07.423321Z","steps":["trace[733285311] 'process raft request' (duration: 175.961854ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:52.368773Z","caller":"traceutil/trace.go:171","msg":"trace[1367906480] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"124.382135ms","start":"2026-10-14T17:42:52.244367Z","end":"2026-10-14T17:42:52.36875Z","steps":["trace[1367906480] 'process raft request' (duration: 113.709832ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:52.368996Z","caller":"traceutil/trace.go:171","msg":"trace[1959657858] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"121.449434ms","start":"2026-10-14T17:42:52.247524Z","end":"2026-10-14T17:42:52.368974Z","steps":["trace[1959657858] 'process raft request' (duration: 121.17075ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:52.369029Z","caller":"traceutil/trace.go:171","msg":"trace[1023681569] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1871; }","duration":"124.473907ms","start":"2026-10-14T17:42:52.244532Z","end":"2026-10-14T17:42:52.369006Z","steps":["trace[1023681569] 'read index received' (duration: 15.666457ms)","trace[1023681569] 'applied index is now lower than readState.Index' (duration: 108.80584ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:52.370053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.498698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-10-14T17:42:52.370122Z","caller":"traceutil/trace.go:171","msg":"trace[1018376472] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1812; }","duration":"125.576768ms","start":"2026-10-14T17:42:52.244526Z","end":"2026-10-14T17:42:52.370103Z","steps":["trace[1018376472] 'agreement among raft nodes before linearized reading' (duration: 125.426867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:54.438188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.162211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:42:54.438781Z","caller":"traceutil/trace.go:171","msg":"trace[1173594742] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"251.38269ms","start":"2026-10-14T17:42:54.187362Z","end":"2026-10-14T17:42:54.438745Z","steps":["trace[1173594742] 'process raft request' (duration: 105.340389ms)","trace[1173594742] 'compare' (duration: 144.978279ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:54.722593Z","caller":"traceutil/trace.go:171","msg":"trace[1867561773] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"203.174167ms","start":"2026-10-14T17:42:54.51936Z","end":"2026-10-14T17:42:54.722534Z","steps":["trace[1867561773] 'read index received' (duration: 175.986109ms)","trace[1867561773] 'applied index is now lower than readState.Index' (duration: 27.186658ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:54.722633Z","caller":"traceutil/trace.go:171","msg":"trace[1428215189] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"218.387419ms","start":"2026-10-14T17:42:54.5042Z","end":"2026-10-14T17:42:54.722587Z","steps":["trace[1428215189] 'process raft request' (duration: 191.210771ms)","trace[1428215189] 'compare' (duration: 26.935916ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:54.722896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.515381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1bf16bbb-2664-4358-86a0-8cbb2d68cfa4\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-14T17:42:54.723004Z","caller":"traceutil/trace.go:171","msg":"trace[229660247] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1bf16bbb-2664-4358-86a0-8cbb2d68cfa4; range_end:; response_count:1; response_revision:1877; }","duration":"203.662184ms","start":"2026-10-14T17:42:54.519322Z","end":"2026-10-14T17:42:54.722984Z","steps":["trace[229660247] 'agreement among raft nodes before linearized reading' (duration: 203.417741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:54.722895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.885396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18de764c701f931a\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-10-14T17:42:54.723121Z","caller":"traceutil/trace.go:171","msg":"trace[578368665] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18de764c701f931a; range_end:; response_count:1; response_revision:1877; }","duration":"109.175079ms","start":"2026-10-14T17:42:54.613927Z","end":"2026-10-14T17:42:54.723102Z","steps":["trace[578368665] 'agreement among raft nodes before linearized reading' (duration: 108.837235ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:43:00.599346Z","caller":"traceutil/trace.go:171","msg":"trace[1144506825] linearizableReadLoop","detail":"{readStateIndex:2019; appliedIndex:2018; }","duration":"118.313144ms","start":"2026-10-14T17:43:00.48101Z","end":"2026-10-14T17:43:00.599323Z","steps":["trace[1144506825] 'read index received' (duration: 118.066531ms)","trace[1144506825] 'applied index is now lower than readState.Index' (duration: 245.353µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:43:00.599411Z","caller":"traceutil/trace.go:171","msg":"trace[2074127554] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"135.011179ms","start":"2026-10-14T17:43:00.464374Z","end":"2026-10-14T17:43:00.599385Z","steps":["trace[2074127554] 'process raft request' (duration: 134.777447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:43:00.599666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.626157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4887"} {"level":"info","ts":"2026-10-14T17:43:00.599728Z","caller":"traceutil/trace.go:171","msg":"trace[158346767] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1951; }","duration":"118.727648ms","start":"2026-10-14T17:43:00.480985Z","end":"2026-10-14T17:43:00.599713Z","steps":["trace[158346767] 'agreement among raft nodes before linearized reading' (duration: 118.480465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:43:27.335769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.758373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-14T17:43:27.335873Z","caller":"traceutil/trace.go:171","msg":"trace[1136464976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2147; }","duration":"172.926674ms","start":"2026-10-14T17:43:27.162924Z","end":"2026-10-14T17:43:27.335851Z","steps":["trace[1136464976] 'range keys from in-memory index tree' (duration: 172.60663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:43:27.336081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.316377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-14T17:43:27.336134Z","caller":"traceutil/trace.go:171","msg":"trace[230851716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2147; }","duration":"169.57407ms","start":"2026-10-14T17:43:27.166545Z","end":"2026-10-14T17:43:27.336119Z","steps":["trace[230851716] 'range keys from in-memory index tree' (duration: 169.164326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:43:27.336142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.924037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-10-14T17:43:27.336153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.446019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:43:27.336245Z","caller":"traceutil/trace.go:171","msg":"trace[370681719] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2147; }","duration":"196.08528ms","start":"2026-10-14T17:43:27.140136Z","end":"2026-10-14T17:43:27.336222Z","steps":["trace[370681719] 'range keys from in-memory index tree' (duration: 195.698445ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:43:27.336248Z","caller":"traceutil/trace.go:171","msg":"trace[835534005] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2147; }","duration":"103.579491ms","start":"2026-10-14T17:43:27.232652Z","end":"2026-10-14T17:43:27.336231Z","steps":["trace[835534005] 'count revisions from in-memory index tree' (duration: 103.358959ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:43:27.504247Z","caller":"traceutil/trace.go:171","msg":"trace[1302117722] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2225; }","duration":"136.767438ms","start":"2026-10-14T17:43:27.367455Z","end":"2026-10-14T17:43:27.504223Z","steps":["trace[1302117722] 'read index received' (duration: 24.725731ms)","trace[1302117722] 'applied index is now lower than readState.Index' (duration: 112.040677ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:43:27.504287Z","caller":"traceutil/trace.go:171","msg":"trace[1417583517] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"163.099314ms","start":"2026-10-14T17:43:27.341142Z","end":"2026-10-14T17:43:27.504241Z","steps":["trace[1417583517] 'process raft request' (duration: 134.369472ms)","trace[1417583517] 'compare' (duration: 28.53523ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:43:27.504348Z","caller":"traceutil/trace.go:171","msg":"trace[1262777720] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"160.014764ms","start":"2026-10-14T17:43:27.344305Z","end":"2026-10-14T17:43:27.504319Z","steps":["trace[1262777720] 'process raft request' (duration: 159.840842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:43:27.504428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.952659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:43:27.504471Z","caller":"traceutil/trace.go:171","msg":"trace[785281099] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2150; }","duration":"137.04408ms","start":"2026-10-14T17:43:27.367415Z","end":"2026-10-14T17:43:27.504459Z","steps":["trace[785281099] 'agreement among raft nodes before linearized reading' (duration: 136.948839ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:43:27.641708Z","caller":"traceutil/trace.go:171","msg":"trace[1641888926] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"130.352782ms","start":"2026-10-14T17:43:27.51133Z","end":"2026-10-14T17:43:27.641682Z","steps":["trace[1641888926] 'process raft request' (duration: 122.925697ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:22.139482Z","caller":"traceutil/trace.go:171","msg":"trace[1986979686] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"138.912838ms","start":"2026-10-14T17:44:22.000533Z","end":"2026-10-14T17:44:22.139446Z","steps":["trace[1986979686] 'process raft request' (duration: 124.220669ms)","trace[1986979686] 'compare' (duration: 14.497174ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:12.628926Z","caller":"traceutil/trace.go:171","msg":"trace[1210780477] transaction","detail":"{read_only:false; response_revision:4388; number_of_response:1; }","duration":"101.715135ms","start":"2026-10-14T17:47:12.527191Z","end":"2026-10-14T17:47:12.628906Z","steps":["trace[1210780477] 'process raft request' (duration: 101.614024ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:12.691395Z","caller":"traceutil/trace.go:171","msg":"trace[1095749312] transaction","detail":"{read_only:false; response_revision:4389; number_of_response:1; }","duration":"156.487165ms","start":"2026-10-14T17:47:12.534889Z","end":"2026-10-14T17:47:12.691376Z","steps":["trace[1095749312] 'process raft request' (duration: 156.175199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:12.702568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.764502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360973"} {"level":"info","ts":"2026-10-14T17:47:12.70281Z","caller":"traceutil/trace.go:171","msg":"trace[2037823822] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4389; }","duration":"107.016396ms","start":"2026-10-14T17:47:12.595745Z","end":"2026-10-14T17:47:12.702761Z","steps":["trace[2037823822] 'agreement among raft nodes before linearized reading' (duration: 95.814204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:16.66975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.58603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-14T17:47:16.669829Z","caller":"traceutil/trace.go:171","msg":"trace[703301417] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4476; }","duration":"117.685593ms","start":"2026-10-14T17:47:16.552127Z","end":"2026-10-14T17:47:16.669813Z","steps":["trace[703301417] 'range keys from in-memory index tree' (duration: 117.473808ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:21.219227Z","caller":"traceutil/trace.go:171","msg":"trace[1665604943] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"128.427323ms","start":"2026-10-14T17:47:21.09077Z","end":"2026-10-14T17:47:21.219197Z","steps":["trace[1665604943] 'process raft request' (duration: 128.25056ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:21.23183Z","caller":"traceutil/trace.go:171","msg":"trace[1929570180] linearizableReadLoop","detail":"{readStateIndex:4785; appliedIndex:4783; }","duration":"110.657563ms","start":"2026-10-14T17:47:21.121155Z","end":"2026-10-14T17:47:21.231813Z","steps":["trace[1929570180] 'read index received' (duration: 97.878918ms)","trace[1929570180] 'applied index is now lower than readState.Index' (duration: 12.777645ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:21.232033Z","caller":"traceutil/trace.go:171","msg":"trace[970895056] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"139.803385ms","start":"2026-10-14T17:47:21.092193Z","end":"2026-10-14T17:47:21.231997Z","steps":["trace[970895056] 'process raft request' (duration: 139.52471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:21.232058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.882197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-14T17:47:21.232272Z","caller":"traceutil/trace.go:171","msg":"trace[740703466] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4584; }","duration":"111.122072ms","start":"2026-10-14T17:47:21.12112Z","end":"2026-10-14T17:47:21.232242Z","steps":["trace[740703466] 'agreement among raft nodes before linearized reading' (duration: 110.783836ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:22.632711Z","caller":"traceutil/trace.go:171","msg":"trace[504799046] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"124.089005ms","start":"2026-10-14T17:47:22.508598Z","end":"2026-10-14T17:47:22.632687Z","steps":["trace[504799046] 'process raft request' (duration: 123.934402ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:24.2472Z","caller":"traceutil/trace.go:171","msg":"trace[1783281098] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"113.373969ms","start":"2026-10-14T17:47:24.133775Z","end":"2026-10-14T17:47:24.247149Z","steps":["trace[1783281098] 'process raft request' (duration: 108.537958ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:24.247476Z","caller":"traceutil/trace.go:171","msg":"trace[1603305673] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"112.767659ms","start":"2026-10-14T17:47:24.134678Z","end":"2026-10-14T17:47:24.247445Z","steps":["trace[1603305673] 'process raft request' (duration: 112.402852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:35.37376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.868339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:47:35.373957Z","caller":"traceutil/trace.go:171","msg":"trace[1383797306] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4989; }","duration":"359.048269ms","start":"2026-10-14T17:47:35.014883Z","end":"2026-10-14T17:47:35.373931Z","steps":["trace[1383797306] 'read index received' (duration: 222.714214ms)","trace[1383797306] 'applied index is now lower than readState.Index' (duration: 136.331845ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:35.374054Z","caller":"traceutil/trace.go:171","msg":"trace[402412715] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"360.151047ms","start":"2026-10-14T17:47:35.013856Z","end":"2026-10-14T17:47:35.374007Z","steps":["trace[402412715] 'process raft request' (duration: 223.915782ms)","trace[402412715] 'compare' (duration: 135.664415ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:47:35.374204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:47:35.013842Z","time spent":"360.282689ms","remote":"127.0.0.1:43100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:47:35.37443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.533197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9761"} {"level":"info","ts":"2026-10-14T17:47:35.374525Z","caller":"traceutil/trace.go:171","msg":"trace[900269440] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4779; }","duration":"359.591419ms","start":"2026-10-14T17:47:35.01488Z","end":"2026-10-14T17:47:35.374472Z","steps":["trace[900269440] 'agreement among raft nodes before linearized reading' (duration: 359.250793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:35.374554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.62988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:47:35.374992Z","caller":"traceutil/trace.go:171","msg":"trace[2128884312] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4779; }","duration":"254.098778ms","start":"2026-10-14T17:47:35.12088Z","end":"2026-10-14T17:47:35.374979Z","steps":["trace[2128884312] 'agreement among raft nodes before linearized reading' (duration: 253.63484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:35.374617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:47:35.014854Z","time spent":"359.745481ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9783,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-10-14T17:48:19.756605Z","caller":"traceutil/trace.go:171","msg":"trace[2046718208] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"103.502622ms","start":"2026-10-14T17:48:19.653078Z","end":"2026-10-14T17:48:19.75658Z","steps":["trace[2046718208] 'process raft request' (duration: 103.386281ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:33.975302Z","caller":"traceutil/trace.go:171","msg":"trace[1704305855] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"342.405469ms","start":"2026-10-14T17:48:33.632858Z","end":"2026-10-14T17:48:33.975263Z","steps":["trace[1704305855] 'process raft request' (duration: 244.420668ms)","trace[1704305855] 'compare' (duration: 97.577685ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:33.975409Z","caller":"traceutil/trace.go:171","msg":"trace[2081875159] linearizableReadLoop","detail":"{readStateIndex:5579; appliedIndex:5578; }","duration":"172.663661ms","start":"2026-10-14T17:48:33.802714Z","end":"2026-10-14T17:48:33.975378Z","steps":["trace[2081875159] 'read index received' (duration: 74.66422ms)","trace[2081875159] 'applied index is now lower than readState.Index' (duration: 97.996921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:48:33.975525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:33.63284Z","time spent":"342.56625ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:48:33.975703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.971796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-14T17:48:33.975795Z","caller":"traceutil/trace.go:171","msg":"trace[1179844831] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5343; }","duration":"173.111109ms","start":"2026-10-14T17:48:33.802663Z","end":"2026-10-14T17:48:33.975774Z","steps":["trace[1179844831] 'agreement among raft nodes before linearized reading' (duration: 172.824144ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:33.975871Z","caller":"traceutil/trace.go:171","msg":"trace[1410419279] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"157.19746ms","start":"2026-10-14T17:48:33.818539Z","end":"2026-10-14T17:48:33.975737Z","steps":["trace[1410419279] 'process raft request' (duration: 156.754653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:33.976436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.317542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T17:48:33.9765Z","caller":"traceutil/trace.go:171","msg":"trace[395343722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5344; }","duration":"165.396873ms","start":"2026-10-14T17:48:33.811088Z","end":"2026-10-14T17:48:33.976485Z","steps":["trace[395343722] 'agreement among raft nodes before linearized reading' (duration: 164.603251ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:50:30.251415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3167} {"level":"info","ts":"2026-10-14T17:50:30.373944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3167,"took":"121.0067ms","hash":2107019233,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":30986240,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T17:50:30.37401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2107019233,"revision":3167,"compact-revision":-1}