{"level":"warn","ts":"2026-10-09T02:14:37.936019Z","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-09T02:14:37.936108Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.69: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.69:2380","--initial-cluster=instance=https://162.253.55.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.69: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-09T02:14:37.936173Z","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-09T02:14:37.93618Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.69:2380"]} {"level":"info","ts":"2026-10-09T02:14:37.936199Z","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-09T02:14:37.936774Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"]} {"level":"info","ts":"2026-10-09T02:14:37.93684Z","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.69:2380"],"listen-peer-urls":["https://162.253.55.69:2380"],"advertise-client-urls":["https://162.253.55.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.69: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-09T02:14:37.971173Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"34.123316ms"} {"level":"info","ts":"2026-10-09T02:14:37.987298Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"905ccde8a677f143","cluster-id":"6fd07fe3af558460"} {"level":"info","ts":"2026-10-09T02:14:37.987453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T02:14:37.987553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became follower at term 0"} {"level":"info","ts":"2026-10-09T02:14:37.987573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 905ccde8a677f143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T02:14:37.987581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became follower at term 1"} {"level":"info","ts":"2026-10-09T02:14:37.987717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=(10402415638427988291)"} {"level":"warn","ts":"2026-10-09T02:14:37.99453Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T02:14:37.999085Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T02:14:38.000381Z","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-09T02:14:38.0022Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"905ccde8a677f143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T02:14:38.002342Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"905ccde8a677f143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T02:14:38.003098Z","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-09T02:14:38.003198Z","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-09T02:14:38.003206Z","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-09T02:14:38.00777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=(10402415638427988291)"} {"level":"info","ts":"2026-10-09T02:14:38.008024Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6fd07fe3af558460","local-member-id":"905ccde8a677f143","added-peer-id":"905ccde8a677f143","added-peer-peer-urls":["https://162.253.55.69:2380"]} {"level":"info","ts":"2026-10-09T02:14:38.009702Z","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-09T02:14:38.009769Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.69:2380"} {"level":"info","ts":"2026-10-09T02:14:38.009826Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.69:2380"} {"level":"info","ts":"2026-10-09T02:14:38.010849Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"905ccde8a677f143","initial-advertise-peer-urls":["https://162.253.55.69:2380"],"listen-peer-urls":["https://162.253.55.69:2380"],"advertise-client-urls":["https://162.253.55.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T02:14:38.010917Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T02:14:38.788866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T02:14:38.78891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T02:14:38.788934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 received MsgPreVoteResp from 905ccde8a677f143 at term 1"} {"level":"info","ts":"2026-10-09T02:14:38.788947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became candidate at term 2"} {"level":"info","ts":"2026-10-09T02:14:38.788957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 received MsgVoteResp from 905ccde8a677f143 at term 2"} {"level":"info","ts":"2026-10-09T02:14:38.788966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became leader at term 2"} {"level":"info","ts":"2026-10-09T02:14:38.788975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 905ccde8a677f143 elected leader 905ccde8a677f143 at term 2"} {"level":"info","ts":"2026-10-09T02:14:38.819184Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"905ccde8a677f143","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.69:2379]}","request-path":"/0/members/905ccde8a677f143/attributes","cluster-id":"6fd07fe3af558460","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T02:14:38.819215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:14:38.819224Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:14:38.819307Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:14:38.819525Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T02:14:38.819582Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T02:14:38.821067Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T02:14:38.82199Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.69:2379"} {"level":"info","ts":"2026-10-09T02:14:38.828961Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6fd07fe3af558460","local-member-id":"905ccde8a677f143","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:14:38.829132Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:14:38.829179Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:14:52.621822Z","caller":"traceutil/trace.go:171","msg":"trace[2123488092] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"109.21353ms","start":"2026-10-09T02:14:52.512579Z","end":"2026-10-09T02:14:52.621792Z","steps":["trace[2123488092] 'process raft request' (duration: 109.022439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:14:53.1039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.914624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:14:53.104048Z","caller":"traceutil/trace.go:171","msg":"trace[111693321] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"457.320142ms","start":"2026-10-09T02:14:52.646709Z","end":"2026-10-09T02:14:53.104029Z","steps":["trace[111693321] 'read index received' (duration: 196.613571ms)","trace[111693321] 'applied index is now lower than readState.Index' (duration: 260.704941ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:14:53.104141Z","caller":"traceutil/trace.go:171","msg":"trace[1810092542] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"478.559967ms","start":"2026-10-09T02:14:52.625566Z","end":"2026-10-09T02:14:53.104126Z","steps":["trace[1810092542] 'process raft request' (duration: 217.725024ms)","trace[1810092542] 'compare' (duration: 259.773882ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:14:53.104213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:14:52.625544Z","time spent":"478.625837ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:14:53.104647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.629085ms","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-10-09T02:14:53.104717Z","caller":"traceutil/trace.go:171","msg":"trace[1190869746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"188.780926ms","start":"2026-10-09T02:14:52.915915Z","end":"2026-10-09T02:14:53.104696Z","steps":["trace[1190869746] 'agreement among raft nodes before linearized reading' (duration: 188.617895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:14:53.104757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.04181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-10-09T02:14:53.104832Z","caller":"traceutil/trace.go:171","msg":"trace[373757465] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:400; }","duration":"458.320173ms","start":"2026-10-09T02:14:52.646494Z","end":"2026-10-09T02:14:53.104815Z","steps":["trace[373757465] 'agreement among raft nodes before linearized reading' (duration: 458.064941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:14:53.104886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:14:52.646479Z","time spent":"458.395344ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5349,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-10-09T02:14:53.214796Z","caller":"traceutil/trace.go:171","msg":"trace[1373800692] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"104.86534ms","start":"2026-10-09T02:14:53.109905Z","end":"2026-10-09T02:14:53.214771Z","steps":["trace[1373800692] 'process raft request' (duration: 101.038632ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:14:53.22056Z","caller":"traceutil/trace.go:171","msg":"trace[1388577406] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"105.240234ms","start":"2026-10-09T02:14:53.115305Z","end":"2026-10-09T02:14:53.220545Z","steps":["trace[1388577406] 'process raft request' (duration: 105.152163ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:15:27.809627Z","caller":"traceutil/trace.go:171","msg":"trace[862930342] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"104.348953ms","start":"2026-10-09T02:15:27.705252Z","end":"2026-10-09T02:15:27.809584Z","steps":["trace[862930342] 'process raft request' (duration: 27.387634ms)","trace[862930342] 'compare' (duration: 76.694846ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:15:28.679175Z","caller":"traceutil/trace.go:171","msg":"trace[497266930] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"374.215177ms","start":"2026-10-09T02:15:28.304937Z","end":"2026-10-09T02:15:28.679152Z","steps":["trace[497266930] 'read index received' (duration: 373.27855ms)","trace[497266930] 'applied index is now lower than readState.Index' (duration: 932.797µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:15:28.67925Z","caller":"traceutil/trace.go:171","msg":"trace[566388734] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"442.679632ms","start":"2026-10-09T02:15:28.236539Z","end":"2026-10-09T02:15:28.679219Z","steps":["trace[566388734] 'process raft request' (duration: 441.858785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:15:28.679381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.42076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-09T02:15:28.679399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:15:28.236503Z","time spent":"442.813793ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:15:28.679451Z","caller":"traceutil/trace.go:171","msg":"trace[951017883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"374.52075ms","start":"2026-10-09T02:15:28.304899Z","end":"2026-10-09T02:15:28.679419Z","steps":["trace[951017883] 'agreement among raft nodes before linearized reading' (duration: 374.340129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:15:28.6795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:15:28.304878Z","time spent":"374.608861ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T02:15:28.993943Z","caller":"traceutil/trace.go:171","msg":"trace[1375521903] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"144.737184ms","start":"2026-10-09T02:15:28.849178Z","end":"2026-10-09T02:15:28.993916Z","steps":["trace[1375521903] 'read index received' (duration: 131.507741ms)","trace[1375521903] 'applied index is now lower than readState.Index' (duration: 13.228343ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:15:28.994182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.989616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-09T02:15:28.994218Z","caller":"traceutil/trace.go:171","msg":"trace[1784254134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"145.082836ms","start":"2026-10-09T02:15:28.849126Z","end":"2026-10-09T02:15:28.994209Z","steps":["trace[1784254134] 'agreement among raft nodes before linearized reading' (duration: 144.892515ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:15:28.99446Z","caller":"traceutil/trace.go:171","msg":"trace[1105044161] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"240.795402ms","start":"2026-10-09T02:15:28.75362Z","end":"2026-10-09T02:15:28.994416Z","steps":["trace[1105044161] 'process raft request' (duration: 227.156996ms)","trace[1105044161] 'compare' (duration: 13.01334ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:15:45.410998Z","caller":"traceutil/trace.go:171","msg":"trace[374967915] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"193.528846ms","start":"2026-10-09T02:15:45.217432Z","end":"2026-10-09T02:15:45.410961Z","steps":["trace[374967915] 'process raft request' (duration: 193.325335ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:16:54.6848Z","caller":"traceutil/trace.go:171","msg":"trace[1758880398] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"122.442894ms","start":"2026-10-09T02:16:54.562288Z","end":"2026-10-09T02:16:54.684731Z","steps":["trace[1758880398] 'process raft request' (duration: 55.73081ms)","trace[1758880398] 'compare' (duration: 66.283998ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:17:07.960995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.620172ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:17:07.961161Z","caller":"traceutil/trace.go:171","msg":"trace[1241816907] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1891; }","duration":"100.81972ms","start":"2026-10-09T02:17:07.860309Z","end":"2026-10-09T02:17:07.961129Z","steps":["trace[1241816907] 'range keys from in-memory index tree' (duration: 100.595492ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:17:08.357776Z","caller":"traceutil/trace.go:171","msg":"trace[762952752] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"101.921608ms","start":"2026-10-09T02:17:08.255822Z","end":"2026-10-09T02:17:08.357743Z","steps":["trace[762952752] 'process raft request' (duration: 89.734233ms)","trace[762952752] 'compare' (duration: 11.838173ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:17:13.708281Z","caller":"traceutil/trace.go:171","msg":"trace[1811591226] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2024; }","duration":"113.71342ms","start":"2026-10-09T02:17:13.594537Z","end":"2026-10-09T02:17:13.708251Z","steps":["trace[1811591226] 'read index received' (duration: 113.41141ms)","trace[1811591226] 'applied index is now lower than readState.Index' (duration: 300.8µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:17:13.708431Z","caller":"traceutil/trace.go:171","msg":"trace[203327949] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"144.374092ms","start":"2026-10-09T02:17:13.563968Z","end":"2026-10-09T02:17:13.708342Z","steps":["trace[203327949] 'process raft request' (duration: 144.113574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:17:13.708478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.910086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:17:13.708529Z","caller":"traceutil/trace.go:171","msg":"trace[368340612] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1952; }","duration":"114.04294ms","start":"2026-10-09T02:17:13.59447Z","end":"2026-10-09T02:17:13.708513Z","steps":["trace[368340612] 'agreement among raft nodes before linearized reading' (duration: 113.896366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:17:37.461764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.173447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-09T02:17:37.461854Z","caller":"traceutil/trace.go:171","msg":"trace[1970428518] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2110; }","duration":"134.320292ms","start":"2026-10-09T02:17:37.327516Z","end":"2026-10-09T02:17:37.461837Z","steps":["trace[1970428518] 'range keys from in-memory index tree' (duration: 133.982162ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:17:43.523405Z","caller":"traceutil/trace.go:171","msg":"trace[129201332] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"190.271604ms","start":"2026-10-09T02:17:43.333101Z","end":"2026-10-09T02:17:43.523372Z","steps":["trace[129201332] 'process raft request' (duration: 147.63493ms)","trace[129201332] 'compare' (duration: 42.360046ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:18:48.852372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.274798ms","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-10-09T02:18:48.852503Z","caller":"traceutil/trace.go:171","msg":"trace[1019171910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2599; }","duration":"154.470622ms","start":"2026-10-09T02:18:48.698006Z","end":"2026-10-09T02:18:48.852476Z","steps":["trace[1019171910] 'range keys from in-memory index tree' (duration: 154.038132ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:19:20.46547Z","caller":"traceutil/trace.go:171","msg":"trace[1490605516] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2873; }","duration":"181.599905ms","start":"2026-10-09T02:19:20.283842Z","end":"2026-10-09T02:19:20.465442Z","steps":["trace[1490605516] 'read index received' (duration: 181.378581ms)","trace[1490605516] 'applied index is now lower than readState.Index' (duration: 220.294µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:19:20.465527Z","caller":"traceutil/trace.go:171","msg":"trace[1132083504] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"217.795557ms","start":"2026-10-09T02:19:20.247698Z","end":"2026-10-09T02:19:20.465493Z","steps":["trace[1132083504] 'process raft request' (duration: 217.595174ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:19:20.465656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.80945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-09T02:19:20.465688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.967876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:19:20.465701Z","caller":"traceutil/trace.go:171","msg":"trace[406498223] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2773; }","duration":"181.915962ms","start":"2026-10-09T02:19:20.283769Z","end":"2026-10-09T02:19:20.465685Z","steps":["trace[406498223] 'agreement among raft nodes before linearized reading' (duration: 181.82284ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:19:20.465759Z","caller":"traceutil/trace.go:171","msg":"trace[1276050847] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2773; }","duration":"160.059069ms","start":"2026-10-09T02:19:20.305667Z","end":"2026-10-09T02:19:20.465726Z","steps":["trace[1276050847] 'agreement among raft nodes before linearized reading' (duration: 159.943235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:00.92514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.16419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:24:00.92524Z","caller":"traceutil/trace.go:171","msg":"trace[81700296] linearizableReadLoop","detail":"{readStateIndex:5086; appliedIndex:5085; }","duration":"213.447221ms","start":"2026-10-09T02:24:00.711777Z","end":"2026-10-09T02:24:00.925224Z","steps":["trace[81700296] 'read index received' (duration: 95.129949ms)","trace[81700296] 'applied index is now lower than readState.Index' (duration: 118.316072ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:24:00.925502Z","caller":"traceutil/trace.go:171","msg":"trace[605326140] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"234.638192ms","start":"2026-10-09T02:24:00.690834Z","end":"2026-10-09T02:24:00.925472Z","steps":["trace[605326140] 'process raft request' (duration: 116.067096ms)","trace[605326140] 'compare' (duration: 118.056629ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:24:00.926187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.399763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-10-09T02:24:00.926229Z","caller":"traceutil/trace.go:171","msg":"trace[669600792] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4902; }","duration":"214.474233ms","start":"2026-10-09T02:24:00.711744Z","end":"2026-10-09T02:24:00.926218Z","steps":["trace[669600792] 'agreement among raft nodes before linearized reading' (duration: 213.520262ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:02.669548Z","caller":"traceutil/trace.go:171","msg":"trace[1755031022] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5102; }","duration":"104.109379ms","start":"2026-10-09T02:24:02.565415Z","end":"2026-10-09T02:24:02.669524Z","steps":["trace[1755031022] 'read index received' (duration: 103.903607ms)","trace[1755031022] 'applied index is now lower than readState.Index' (duration: 204.802µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:24:02.669696Z","caller":"traceutil/trace.go:171","msg":"trace[182742564] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"237.309708ms","start":"2026-10-09T02:24:02.432348Z","end":"2026-10-09T02:24:02.669658Z","steps":["trace[182742564] 'process raft request' (duration: 237.041665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:02.669773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.344812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-09T02:24:02.669817Z","caller":"traceutil/trace.go:171","msg":"trace[1426496352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4918; }","duration":"104.431233ms","start":"2026-10-09T02:24:02.56537Z","end":"2026-10-09T02:24:02.669801Z","steps":["trace[1426496352] 'agreement among raft nodes before linearized reading' (duration: 104.244681ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:08.369194Z","caller":"traceutil/trace.go:171","msg":"trace[85450629] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"105.984527ms","start":"2026-10-09T02:24:08.263184Z","end":"2026-10-09T02:24:08.369169Z","steps":["trace[85450629] 'process raft request' (duration: 70.729728ms)","trace[85450629] 'compare' (duration: 34.922165ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:24:17.734861Z","caller":"traceutil/trace.go:171","msg":"trace[378237700] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"144.128829ms","start":"2026-10-09T02:24:17.590692Z","end":"2026-10-09T02:24:17.734821Z","steps":["trace[378237700] 'process raft request' (duration: 138.484355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:22.139513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.38358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-09T02:24:22.139609Z","caller":"traceutil/trace.go:171","msg":"trace[386379309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5260; }","duration":"121.551101ms","start":"2026-10-09T02:24:22.018035Z","end":"2026-10-09T02:24:22.139586Z","steps":["trace[386379309] 'range keys from in-memory index tree' (duration: 121.195817ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:22.552171Z","caller":"traceutil/trace.go:171","msg":"trace[2076012809] linearizableReadLoop","detail":"{readStateIndex:5458; appliedIndex:5456; }","duration":"389.541094ms","start":"2026-10-09T02:24:22.162605Z","end":"2026-10-09T02:24:22.552146Z","steps":["trace[2076012809] 'read index received' (duration: 3.644051ms)","trace[2076012809] 'applied index is now lower than readState.Index' (duration: 385.896023ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:24:22.552297Z","caller":"traceutil/trace.go:171","msg":"trace[411719306] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"389.837246ms","start":"2026-10-09T02:24:22.162444Z","end":"2026-10-09T02:24:22.552282Z","steps":["trace[411719306] 'process raft request' (duration: 319.389017ms)","trace[411719306] 'compare' (duration: 70.186266ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:24:22.552414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.162427Z","time spent":"389.902827ms","remote":"127.0.0.1:52760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:24:22.552513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.347455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-10-09T02:24:22.552559Z","caller":"traceutil/trace.go:171","msg":"trace[1126002720] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5262; }","duration":"383.417605ms","start":"2026-10-09T02:24:22.169128Z","end":"2026-10-09T02:24:22.552546Z","steps":["trace[1126002720] 'agreement among raft nodes before linearized reading' (duration: 383.230943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:22.552589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.169117Z","time spent":"383.464525ms","remote":"127.0.0.1:38246","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-10-09T02:24:22.552572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.641438ms","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-09T02:24:22.552643Z","caller":"traceutil/trace.go:171","msg":"trace[777109875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5262; }","duration":"117.767349ms","start":"2026-10-09T02:24:22.434858Z","end":"2026-10-09T02:24:22.552626Z","steps":["trace[777109875] 'agreement among raft nodes before linearized reading' (duration: 117.539936ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:22.552806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.187511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11323"} {"level":"info","ts":"2026-10-09T02:24:22.552883Z","caller":"traceutil/trace.go:171","msg":"trace[1545274937] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5262; }","duration":"390.285072ms","start":"2026-10-09T02:24:22.162581Z","end":"2026-10-09T02:24:22.552867Z","steps":["trace[1545274937] 'agreement among raft nodes before linearized reading' (duration: 390.11547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:22.552935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.162558Z","time spent":"390.364672ms","remote":"127.0.0.1:52890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":11346,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-09T02:24:23.095853Z","caller":"traceutil/trace.go:171","msg":"trace[446816430] linearizableReadLoop","detail":"{readStateIndex:5460; appliedIndex:5459; }","duration":"451.121073ms","start":"2026-10-09T02:24:22.64471Z","end":"2026-10-09T02:24:23.095831Z","steps":["trace[446816430] 'read index received' (duration: 451.061432ms)","trace[446816430] 'applied index is now lower than readState.Index' (duration: 58.551µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:24:23.095911Z","caller":"traceutil/trace.go:171","msg":"trace[1270173488] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"452.732581ms","start":"2026-10-09T02:24:22.643117Z","end":"2026-10-09T02:24:23.095849Z","steps":["trace[1270173488] 'process raft request' (duration: 452.583009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.096045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.301265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:24:23.096143Z","caller":"traceutil/trace.go:171","msg":"trace[2060568654] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5264; }","duration":"451.447576ms","start":"2026-10-09T02:24:22.644677Z","end":"2026-10-09T02:24:23.096124Z","steps":["trace[2060568654] 'agreement among raft nodes before linearized reading' (duration: 451.255154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.096265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.644662Z","time spent":"451.521067ms","remote":"127.0.0.1:53208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-09T02:24:23.09632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.643092Z","time spent":"452.898642ms","remote":"127.0.0.1:52784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T02:24:23.270443Z","caller":"traceutil/trace.go:171","msg":"trace[265458643] linearizableReadLoop","detail":"{readStateIndex:5462; appliedIndex:5460; }","duration":"174.509013ms","start":"2026-10-09T02:24:23.095915Z","end":"2026-10-09T02:24:23.270424Z","steps":["trace[265458643] 'read index received' (duration: 120.289927ms)","trace[265458643] 'applied index is now lower than readState.Index' (duration: 54.218026ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:24:23.270764Z","caller":"traceutil/trace.go:171","msg":"trace[29135516] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"504.316638ms","start":"2026-10-09T02:24:22.766432Z","end":"2026-10-09T02:24:23.270748Z","steps":["trace[29135516] 'process raft request' (duration: 503.932094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.270765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.98595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:24:23.270823Z","caller":"traceutil/trace.go:171","msg":"trace[935217514] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5266; }","duration":"351.07931ms","start":"2026-10-09T02:24:22.91973Z","end":"2026-10-09T02:24:23.270809Z","steps":["trace[935217514] 'agreement among raft nodes before linearized reading' (duration: 350.980509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.270852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.919719Z","time spent":"351.122841ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-09T02:24:23.270853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.766403Z","time spent":"504.396868ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:24:23.270946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.775964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-09T02:24:23.270984Z","caller":"traceutil/trace.go:171","msg":"trace[1878346159] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5266; }","duration":"371.876134ms","start":"2026-10-09T02:24:22.899092Z","end":"2026-10-09T02:24:23.270968Z","steps":["trace[1878346159] 'agreement among raft nodes before linearized reading' (duration: 371.668532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.271013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.899013Z","time spent":"371.990686ms","remote":"127.0.0.1:52990","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-10-09T02:24:23.271267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.77264ms","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-10-09T02:24:23.271308Z","caller":"traceutil/trace.go:171","msg":"trace[489005143] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5266; }","duration":"611.837412ms","start":"2026-10-09T02:24:22.659457Z","end":"2026-10-09T02:24:23.271295Z","steps":["trace[489005143] 'agreement among raft nodes before linearized reading' (duration: 611.738461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.271334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.659445Z","time spent":"611.881332ms","remote":"127.0.0.1:52880","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-10-09T02:24:23.271501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.724849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-09T02:24:23.271531Z","caller":"traceutil/trace.go:171","msg":"trace[598016400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5266; }","duration":"477.79464ms","start":"2026-10-09T02:24:22.793728Z","end":"2026-10-09T02:24:23.271523Z","steps":["trace[598016400] 'agreement among raft nodes before linearized reading' (duration: 477.713889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.271553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.79371Z","time spent":"477.83743ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T02:24:23.271196Z","caller":"traceutil/trace.go:171","msg":"trace[158387846] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"617.871339ms","start":"2026-10-09T02:24:22.653287Z","end":"2026-10-09T02:24:23.271159Z","steps":["trace[158387846] 'process raft request' (duration: 562.904284ms)","trace[158387846] 'compare' (duration: 53.989525ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:24:23.273501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:22.653257Z","time spent":"619.957842ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:24:23.338982Z","caller":"traceutil/trace.go:171","msg":"trace[1732029960] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"156.829816ms","start":"2026-10-09T02:24:23.182132Z","end":"2026-10-09T02:24:23.338962Z","steps":["trace[1732029960] 'process raft request' (duration: 156.720334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.339291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.396871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:24:23.339327Z","caller":"traceutil/trace.go:171","msg":"trace[1508032158] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5267; }","duration":"239.497262ms","start":"2026-10-09T02:24:23.099821Z","end":"2026-10-09T02:24:23.339318Z","steps":["trace[1508032158] 'agreement among raft nodes before linearized reading' (duration: 239.415691ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:23.612882Z","caller":"traceutil/trace.go:171","msg":"trace[1358348346] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"325.909679ms","start":"2026-10-09T02:24:23.286959Z","end":"2026-10-09T02:24:23.612868Z","steps":["trace[1358348346] 'process raft request' (duration: 325.855238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.612979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:23.286934Z","time spent":"325.995039ms","remote":"127.0.0.1:52888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:24:23.613037Z","caller":"traceutil/trace.go:171","msg":"trace[668971376] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"326.779468ms","start":"2026-10-09T02:24:23.28621Z","end":"2026-10-09T02:24:23.61299Z","steps":["trace[668971376] 'process raft request' (duration: 326.583196ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:23.613071Z","caller":"traceutil/trace.go:171","msg":"trace[40264134] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"335.689457ms","start":"2026-10-09T02:24:23.277352Z","end":"2026-10-09T02:24:23.613042Z","steps":["trace[40264134] 'process raft request' (duration: 335.376234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.613144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:23.286196Z","time spent":"326.898839ms","remote":"127.0.0.1:52992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:24:23.613205Z","caller":"traceutil/trace.go:171","msg":"trace[2001553573] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"335.973921ms","start":"2026-10-09T02:24:23.27722Z","end":"2026-10-09T02:24:23.613194Z","steps":["trace[2001553573] 'process raft request' (duration: 242.600936ms)","trace[2001553573] 'compare' (duration: 92.805579ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:24:23.613244Z","caller":"traceutil/trace.go:171","msg":"trace[1344768876] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"327.191262ms","start":"2026-10-09T02:24:23.286023Z","end":"2026-10-09T02:24:23.613215Z","steps":["trace[1344768876] 'process raft request' (duration: 326.732847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.613273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:23.277187Z","time spent":"336.041172ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:24:23.613262Z","caller":"traceutil/trace.go:171","msg":"trace[350685201] linearizableReadLoop","detail":"{readStateIndex:5469; appliedIndex:5463; }","duration":"274.020147ms","start":"2026-10-09T02:24:23.33922Z","end":"2026-10-09T02:24:23.613241Z","steps":["trace[350685201] 'read index received' (duration: 180.53787ms)","trace[350685201] 'applied index is now lower than readState.Index' (duration: 93.480577ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:24:23.613329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:23.286001Z","time spent":"327.277003ms","remote":"127.0.0.1:52992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:24:23.613377Z","caller":"traceutil/trace.go:171","msg":"trace[867049913] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"325.936069ms","start":"2026-10-09T02:24:23.287426Z","end":"2026-10-09T02:24:23.613362Z","steps":["trace[867049913] 'process raft request' (duration: 325.421443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.613148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:23.27734Z","time spent":"335.779728ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:24:23.61345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.505519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-09T02:24:23.613479Z","caller":"traceutil/trace.go:171","msg":"trace[1715808397] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5273; }","duration":"271.560149ms","start":"2026-10-09T02:24:23.341912Z","end":"2026-10-09T02:24:23.613472Z","steps":["trace[1715808397] 'agreement among raft nodes before linearized reading' (duration: 271.463948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.613471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:23.287417Z","time spent":"325.998229ms","remote":"127.0.0.1:52888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:24:23.613642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.778495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2482e52d-f49f-4997-82ef-d62ab072390b\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-09T02:24:23.613714Z","caller":"traceutil/trace.go:171","msg":"trace[766693769] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2482e52d-f49f-4997-82ef-d62ab072390b; range_end:; response_count:1; response_revision:5273; }","duration":"339.886175ms","start":"2026-10-09T02:24:23.273809Z","end":"2026-10-09T02:24:23.613695Z","steps":["trace[766693769] 'agreement among raft nodes before linearized reading' (duration: 339.686903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:23.613768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:24:23.273779Z","time spent":"339.963837ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-2482e52d-f49f-4997-82ef-d62ab072390b\" "} {"level":"info","ts":"2026-10-09T02:24:36.047095Z","caller":"traceutil/trace.go:171","msg":"trace[1534733932] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"106.797258ms","start":"2026-10-09T02:24:35.940268Z","end":"2026-10-09T02:24:36.047065Z","steps":["trace[1534733932] 'process raft request' (duration: 93.390189ms)","trace[1534733932] 'compare' (duration: 13.234767ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:24:38.842087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2872} {"level":"info","ts":"2026-10-09T02:24:38.919464Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2872,"took":"75.197706ms","hash":2974150319,"current-db-size-bytes":52199424,"current-db-size":"52 MB","current-db-size-in-use-bytes":27521024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T02:24:38.919537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2974150319,"revision":2872,"compact-revision":-1} {"level":"info","ts":"2026-10-09T02:25:07.639751Z","caller":"traceutil/trace.go:171","msg":"trace[144844869] transaction","detail":"{read_only:false; response_revision:5640; number_of_response:1; }","duration":"134.010888ms","start":"2026-10-09T02:25:07.505697Z","end":"2026-10-09T02:25:07.639708Z","steps":["trace[144844869] 'process raft request' (duration: 133.845084ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:29:38.849258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5377} {"level":"info","ts":"2026-10-09T02:29:38.933101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5377,"took":"82.345783ms","hash":3184421489,"current-db-size-bytes":52199424,"current-db-size":"52 MB","current-db-size-in-use-bytes":19025920,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-09T02:29:38.933159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3184421489,"revision":5377,"compact-revision":2872}