{"level":"warn","ts":"2026-09-22T03:31:33.274774Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:31:33.274859Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.136: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.136:2380","--initial-cluster=instance=https://162.253.55.136:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.136:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.136:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-22T03:31:33.274914Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:31:33.274923Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.136:2380"]} {"level":"info","ts":"2026-09-22T03:31:33.274938Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T03:31:33.275509Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"]} {"level":"info","ts":"2026-09-22T03:31:33.27559Z","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.136:2380"],"listen-peer-urls":["https://162.253.55.136:2380"],"advertise-client-urls":["https://162.253.55.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.136:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-22T03:31:33.279805Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.022834ms"} {"level":"info","ts":"2026-09-22T03:31:33.285417Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"308ce60d3e55f7ff","cluster-id":"dd7f8b36a12acab1"} {"level":"info","ts":"2026-09-22T03:31:33.285485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T03:31:33.285507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became follower at term 0"} {"level":"info","ts":"2026-09-22T03:31:33.285536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 308ce60d3e55f7ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T03:31:33.285543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became follower at term 1"} {"level":"info","ts":"2026-09-22T03:31:33.285584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=(3498423955114817535)"} {"level":"warn","ts":"2026-09-22T03:31:33.291635Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T03:31:33.292945Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T03:31:33.295584Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-22T03:31:33.299158Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"308ce60d3e55f7ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T03:31:33.300995Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T03:31:33.3029Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T03:31:33.302918Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T03:31:33.303445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=(3498423955114817535)"} {"level":"info","ts":"2026-09-22T03:31:33.303438Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"308ce60d3e55f7ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T03:31:33.303552Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd7f8b36a12acab1","local-member-id":"308ce60d3e55f7ff","added-peer-id":"308ce60d3e55f7ff","added-peer-peer-urls":["https://162.253.55.136:2380"]} {"level":"info","ts":"2026-09-22T03:31:33.304328Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T03:31:33.304474Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.136:2380"} {"level":"info","ts":"2026-09-22T03:31:33.304507Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.136:2380"} {"level":"info","ts":"2026-09-22T03:31:33.304633Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"308ce60d3e55f7ff","initial-advertise-peer-urls":["https://162.253.55.136:2380"],"listen-peer-urls":["https://162.253.55.136:2380"],"advertise-client-urls":["https://162.253.55.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T03:31:33.304673Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T03:31:33.585894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T03:31:33.585974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T03:31:33.586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff received MsgPreVoteResp from 308ce60d3e55f7ff at term 1"} {"level":"info","ts":"2026-09-22T03:31:33.586038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became candidate at term 2"} {"level":"info","ts":"2026-09-22T03:31:33.586056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff received MsgVoteResp from 308ce60d3e55f7ff at term 2"} {"level":"info","ts":"2026-09-22T03:31:33.586074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became leader at term 2"} {"level":"info","ts":"2026-09-22T03:31:33.586094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 308ce60d3e55f7ff elected leader 308ce60d3e55f7ff at term 2"} {"level":"info","ts":"2026-09-22T03:31:33.587051Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:31:33.587946Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:31:33.58794Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"308ce60d3e55f7ff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.136:2379]}","request-path":"/0/members/308ce60d3e55f7ff/attributes","cluster-id":"dd7f8b36a12acab1","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T03:31:33.58806Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:31:33.588245Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T03:31:33.588284Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T03:31:33.588633Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd7f8b36a12acab1","local-member-id":"308ce60d3e55f7ff","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:31:33.588777Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:31:33.588821Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:31:33.591301Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T03:31:33.591978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.136:2379"} {"level":"info","ts":"2026-09-22T03:31:36.565783Z","caller":"traceutil/trace.go:171","msg":"trace[998786156] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"110.521872ms","start":"2026-09-22T03:31:36.455223Z","end":"2026-09-22T03:31:36.565745Z","steps":["trace[998786156] 'process raft request' (duration: 55.454549ms)","trace[998786156] 'compare' (duration: 54.924107ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:31:37.014854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.746713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-22T03:31:37.015065Z","caller":"traceutil/trace.go:171","msg":"trace[858107967] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"349.368428ms","start":"2026-09-22T03:31:36.665627Z","end":"2026-09-22T03:31:37.014996Z","steps":["trace[858107967] 'process raft request' (duration: 11.824514ms)","trace[858107967] 'compare' (duration: 336.583597ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:31:37.015179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:36.665612Z","time spent":"349.511404ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":662,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T03:31:40.682716Z","caller":"traceutil/trace.go:171","msg":"trace[29140597] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"168.799153ms","start":"2026-09-22T03:31:40.513897Z","end":"2026-09-22T03:31:40.682696Z","steps":["trace[29140597] 'process raft request' (duration: 168.656978ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:31:40.682806Z","caller":"traceutil/trace.go:171","msg":"trace[1974529553] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"168.287354ms","start":"2026-09-22T03:31:40.514508Z","end":"2026-09-22T03:31:40.682796Z","steps":["trace[1974529553] 'process raft request' (duration: 168.069136ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:31:40.682698Z","caller":"traceutil/trace.go:171","msg":"trace[886715017] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"169.00428ms","start":"2026-09-22T03:31:40.513662Z","end":"2026-09-22T03:31:40.682666Z","steps":["trace[886715017] 'process raft request' (duration: 168.672908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:40.682863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.85333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-22T03:31:40.682907Z","caller":"traceutil/trace.go:171","msg":"trace[17870811] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:267; }","duration":"158.977195ms","start":"2026-09-22T03:31:40.523922Z","end":"2026-09-22T03:31:40.682899Z","steps":["trace[17870811] 'agreement among raft nodes before linearized reading' (duration: 158.872411ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:31:40.682722Z","caller":"traceutil/trace.go:171","msg":"trace[2111522892] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:276; }","duration":"158.699974ms","start":"2026-09-22T03:31:40.523996Z","end":"2026-09-22T03:31:40.682696Z","steps":["trace[2111522892] 'read index received' (duration: 33.893992ms)","trace[2111522892] 'applied index is now lower than readState.Index' (duration: 124.805252ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:31:40.682973Z","caller":"traceutil/trace.go:171","msg":"trace[1667277408] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"145.825981ms","start":"2026-09-22T03:31:40.537126Z","end":"2026-09-22T03:31:40.682952Z","steps":["trace[1667277408] 'process raft request' (duration: 145.477458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:40.6831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.023456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-09-22T03:31:40.683143Z","caller":"traceutil/trace.go:171","msg":"trace[1769502284] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:267; }","duration":"123.125259ms","start":"2026-09-22T03:31:40.560006Z","end":"2026-09-22T03:31:40.683132Z","steps":["trace[1769502284] 'agreement among raft nodes before linearized reading' (duration: 123.018105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.62192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.441538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:31:44.622182Z","caller":"traceutil/trace.go:171","msg":"trace[827755622] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:316; }","duration":"411.830578ms","start":"2026-09-22T03:31:44.210326Z","end":"2026-09-22T03:31:44.622156Z","steps":["trace[827755622] 'read index received' (duration: 59.056038ms)","trace[827755622] 'applied index is now lower than readState.Index' (duration: 352.77319ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:31:44.622399Z","caller":"traceutil/trace.go:171","msg":"trace[659772600] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"434.432098ms","start":"2026-09-22T03:31:44.187949Z","end":"2026-09-22T03:31:44.622381Z","steps":["trace[659772600] 'process raft request' (duration: 434.112087ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:31:44.622458Z","caller":"traceutil/trace.go:171","msg":"trace[1220945945] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"436.920917ms","start":"2026-09-22T03:31:44.185492Z","end":"2026-09-22T03:31:44.622413Z","steps":["trace[1220945945] 'process raft request' (duration: 83.886388ms)","trace[1220945945] 'compare' (duration: 352.171058ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:31:44.622554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.789335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-09-22T03:31:44.622623Z","caller":"traceutil/trace.go:171","msg":"trace[1238551304] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:305; }","duration":"411.870669ms","start":"2026-09-22T03:31:44.210728Z","end":"2026-09-22T03:31:44.622598Z","steps":["trace[1238551304] 'agreement among raft nodes before linearized reading' (duration: 411.753315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.622635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.185466Z","time spent":"437.083493ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T03:31:44.622663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.210715Z","time spent":"411.937892ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4686,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-09-22T03:31:44.622842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.187931Z","time spent":"434.503661ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:31:44.622968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.637727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-09-22T03:31:44.623003Z","caller":"traceutil/trace.go:171","msg":"trace[731383719] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:305; }","duration":"412.7219ms","start":"2026-09-22T03:31:44.21027Z","end":"2026-09-22T03:31:44.622992Z","steps":["trace[731383719] 'agreement among raft nodes before linearized reading' (duration: 412.653827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.210248Z","time spent":"412.787211ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5359,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-09-22T03:31:44.623054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.709358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-09-22T03:31:44.623096Z","caller":"traceutil/trace.go:171","msg":"trace[1573030983] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:305; }","duration":"347.810732ms","start":"2026-09-22T03:31:44.275274Z","end":"2026-09-22T03:31:44.623084Z","steps":["trace[1573030983] 'agreement among raft nodes before linearized reading' (duration: 347.700248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.275259Z","time spent":"347.862573ms","remote":"127.0.0.1:34408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-09-22T03:31:44.623208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.100571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-09-22T03:31:44.623247Z","caller":"traceutil/trace.go:171","msg":"trace[855211122] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:305; }","duration":"399.164643ms","start":"2026-09-22T03:31:44.224069Z","end":"2026-09-22T03:31:44.623234Z","steps":["trace[855211122] 'agreement among raft nodes before linearized reading' (duration: 399.091451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.224051Z","time spent":"399.220446ms","remote":"127.0.0.1:34408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-09-22T03:31:44.62331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.850545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-22T03:31:44.623361Z","caller":"traceutil/trace.go:171","msg":"trace[1467186875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:305; }","duration":"197.925717ms","start":"2026-09-22T03:31:44.425417Z","end":"2026-09-22T03:31:44.623342Z","steps":["trace[1467186875] 'agreement among raft nodes before linearized reading' (duration: 197.835844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.187193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-22T03:31:44.623413Z","caller":"traceutil/trace.go:171","msg":"trace[41677417] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:305; }","duration":"247.261457ms","start":"2026-09-22T03:31:44.376141Z","end":"2026-09-22T03:31:44.623402Z","steps":["trace[41677417] 'agreement among raft nodes before linearized reading' (duration: 247.207125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.661927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-09-22T03:31:44.623489Z","caller":"traceutil/trace.go:171","msg":"trace[807029476] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:305; }","duration":"412.74247ms","start":"2026-09-22T03:31:44.210735Z","end":"2026-09-22T03:31:44.623477Z","steps":["trace[807029476] 'agreement among raft nodes before linearized reading' (duration: 412.682158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.210436Z","time spent":"413.078293ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-09-22T03:31:44.623512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.680828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-09-22T03:31:44.62356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.23476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-09-22T03:31:44.623584Z","caller":"traceutil/trace.go:171","msg":"trace[2089107023] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:305; }","duration":"412.768961ms","start":"2026-09-22T03:31:44.210797Z","end":"2026-09-22T03:31:44.623566Z","steps":["trace[2089107023] 'agreement among raft nodes before linearized reading' (duration: 412.568674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.210664Z","time spent":"412.979708ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7326,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-09-22T03:31:44.623712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.80248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-22T03:31:44.623763Z","caller":"traceutil/trace.go:171","msg":"trace[1082904534] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:305; }","duration":"147.900544ms","start":"2026-09-22T03:31:44.475847Z","end":"2026-09-22T03:31:44.623748Z","steps":["trace[1082904534] 'agreement among raft nodes before linearized reading' (duration: 147.79036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.603625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-22T03:31:44.62388Z","caller":"traceutil/trace.go:171","msg":"trace[1063725543] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:305; }","duration":"412.655037ms","start":"2026-09-22T03:31:44.211214Z","end":"2026-09-22T03:31:44.623869Z","steps":["trace[1063725543] 'agreement among raft nodes before linearized reading' (duration: 412.595745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.623904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.211205Z","time spent":"412.693278ms","remote":"127.0.0.1:34620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-22T03:31:44.624072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.990009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-09-22T03:31:44.624099Z","caller":"traceutil/trace.go:171","msg":"trace[979379130] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:305; }","duration":"413.0177ms","start":"2026-09-22T03:31:44.211072Z","end":"2026-09-22T03:31:44.62409Z","steps":["trace[979379130] 'agreement among raft nodes before linearized reading' (duration: 412.962998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.624122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.210979Z","time spent":"413.136634ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-09-22T03:31:44.623603Z","caller":"traceutil/trace.go:171","msg":"trace[1791723623] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:305; }","duration":"299.328923ms","start":"2026-09-22T03:31:44.324265Z","end":"2026-09-22T03:31:44.623594Z","steps":["trace[1791723623] 'agreement among raft nodes before linearized reading' (duration: 299.260191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:31:44.62455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:31:44.324237Z","time spent":"300.298548ms","remote":"127.0.0.1:34408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-09-22T03:32:12.698679Z","caller":"traceutil/trace.go:171","msg":"trace[583708490] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"208.018708ms","start":"2026-09-22T03:32:12.490625Z","end":"2026-09-22T03:32:12.698644Z","steps":["trace[583708490] 'process raft request' (duration: 207.863824ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.815436Z","caller":"traceutil/trace.go:171","msg":"trace[1544863426] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"315.908976ms","start":"2026-09-22T03:32:12.499488Z","end":"2026-09-22T03:32:12.815397Z","steps":["trace[1544863426] 'process raft request' (duration: 288.817751ms)","trace[1544863426] 'compare' (duration: 26.862259ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:32:12.815484Z","caller":"traceutil/trace.go:171","msg":"trace[908109272] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"315.877225ms","start":"2026-09-22T03:32:12.49957Z","end":"2026-09-22T03:32:12.815447Z","steps":["trace[908109272] 'process raft request' (duration: 315.723101ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.815581Z","caller":"traceutil/trace.go:171","msg":"trace[1876012950] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"296.459035ms","start":"2026-09-22T03:32:12.51909Z","end":"2026-09-22T03:32:12.815549Z","steps":["trace[1876012950] 'process raft request' (duration: 296.29611ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.81566Z","caller":"traceutil/trace.go:171","msg":"trace[172555329] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"300.975586ms","start":"2026-09-22T03:32:12.514621Z","end":"2026-09-22T03:32:12.815596Z","steps":["trace[172555329] 'process raft request' (duration: 300.717739ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.815729Z","caller":"traceutil/trace.go:171","msg":"trace[463947454] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"296.402253ms","start":"2026-09-22T03:32:12.519316Z","end":"2026-09-22T03:32:12.815719Z","steps":["trace[463947454] 'process raft request' (duration: 296.216228ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.815678Z","caller":"traceutil/trace.go:171","msg":"trace[2054728917] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"300.536244ms","start":"2026-09-22T03:32:12.515058Z","end":"2026-09-22T03:32:12.815594Z","steps":["trace[2054728917] 'process raft request' (duration: 300.3807ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.815723Z","caller":"traceutil/trace.go:171","msg":"trace[1559311438] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"296.440464ms","start":"2026-09-22T03:32:12.519222Z","end":"2026-09-22T03:32:12.815662Z","steps":["trace[1559311438] 'process raft request' (duration: 296.264559ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.815802Z","caller":"traceutil/trace.go:171","msg":"trace[1258853477] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"294.514232ms","start":"2026-09-22T03:32:12.521278Z","end":"2026-09-22T03:32:12.815792Z","steps":["trace[1258853477] 'process raft request' (duration: 294.306427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:32:12.815691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:32:12.499544Z","time spent":"316.020268ms","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T03:32:12.815764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:32:12.499462Z","time spent":"316.061849ms","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T03:32:12.81587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:32:12.514593Z","time spent":"301.146811ms","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T03:32:12.815987Z","caller":"traceutil/trace.go:171","msg":"trace[769192882] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"292.749185ms","start":"2026-09-22T03:32:12.523228Z","end":"2026-09-22T03:32:12.815978Z","steps":["trace[769192882] 'process raft request' (duration: 292.461598ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.816096Z","caller":"traceutil/trace.go:171","msg":"trace[876858783] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"257.177763ms","start":"2026-09-22T03:32:12.558905Z","end":"2026-09-22T03:32:12.816083Z","steps":["trace[876858783] 'process raft request' (duration: 256.859265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:32:12.816104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:32:12.513513Z","time spent":"302.373624ms","remote":"127.0.0.1:34284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T03:32:12.816277Z","caller":"traceutil/trace.go:171","msg":"trace[1853779411] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"125.946011ms","start":"2026-09-22T03:32:12.69031Z","end":"2026-09-22T03:32:12.816256Z","steps":["trace[1853779411] 'process raft request' (duration: 125.837778ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.852713Z","caller":"traceutil/trace.go:171","msg":"trace[1031285397] transaction","detail":"{read_only:false; number_of_response:1; response_revision:499; }","duration":"147.320413ms","start":"2026-09-22T03:32:12.705375Z","end":"2026-09-22T03:32:12.852695Z","steps":["trace[1031285397] 'process raft request' (duration: 147.290482ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.853004Z","caller":"traceutil/trace.go:171","msg":"trace[1990634955] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"149.045129ms","start":"2026-09-22T03:32:12.703903Z","end":"2026-09-22T03:32:12.852949Z","steps":["trace[1990634955] 'process raft request' (duration: 148.630707ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:12.85329Z","caller":"traceutil/trace.go:171","msg":"trace[1830064011] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"148.630618ms","start":"2026-09-22T03:32:12.704649Z","end":"2026-09-22T03:32:12.85328Z","steps":["trace[1830064011] 'process raft request' (duration: 147.975251ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:18.397479Z","caller":"traceutil/trace.go:171","msg":"trace[1722904398] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"153.911842ms","start":"2026-09-22T03:32:18.24354Z","end":"2026-09-22T03:32:18.397452Z","steps":["trace[1722904398] 'process raft request' (duration: 103.125978ms)","trace[1722904398] 'compare' (duration: 50.655591ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:32:18.398667Z","caller":"traceutil/trace.go:171","msg":"trace[956299145] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"155.02952ms","start":"2026-09-22T03:32:18.243619Z","end":"2026-09-22T03:32:18.398648Z","steps":["trace[956299145] 'process raft request' (duration: 154.941708ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:19.359882Z","caller":"traceutil/trace.go:171","msg":"trace[1154038130] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"180.600435ms","start":"2026-09-22T03:32:19.179187Z","end":"2026-09-22T03:32:19.359788Z","steps":["trace[1154038130] 'process raft request' (duration: 178.946943ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:32:19.987039Z","caller":"traceutil/trace.go:171","msg":"trace[1763418022] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"375.910698ms","start":"2026-09-22T03:32:19.611105Z","end":"2026-09-22T03:32:19.987016Z","steps":["trace[1763418022] 'read index received' (duration: 361.863205ms)","trace[1763418022] 'applied index is now lower than readState.Index' (duration: 14.046372ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:32:19.987077Z","caller":"traceutil/trace.go:171","msg":"trace[810609733] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"557.6357ms","start":"2026-09-22T03:32:19.429402Z","end":"2026-09-22T03:32:19.987038Z","steps":["trace[810609733] 'process raft request' (duration: 543.625609ms)","trace[810609733] 'compare' (duration: 13.56734ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:32:19.987228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:32:19.429373Z","time spent":"557.785574ms","remote":"127.0.0.1:34408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T03:32:19.987317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.202185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nmsst\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-22T03:32:19.987373Z","caller":"traceutil/trace.go:171","msg":"trace[1049273783] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nmsst; range_end:; response_count:1; response_revision:585; }","duration":"376.331068ms","start":"2026-09-22T03:32:19.611023Z","end":"2026-09-22T03:32:19.987355Z","steps":["trace[1049273783] 'agreement among raft nodes before linearized reading' (duration: 376.105422ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:32:19.987411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:32:19.610985Z","time spent":"376.4167ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-nmsst\" "} {"level":"warn","ts":"2026-09-22T03:32:19.987366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.625704ms","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-09-22T03:32:19.987497Z","caller":"traceutil/trace.go:171","msg":"trace[1219559167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"344.807359ms","start":"2026-09-22T03:32:19.64265Z","end":"2026-09-22T03:32:19.987457Z","steps":["trace[1219559167] 'agreement among raft nodes before linearized reading' (duration: 344.534382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:32:19.987564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:32:19.642623Z","time spent":"344.929461ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T03:32:19.987581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.915153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T03:32:19.987637Z","caller":"traceutil/trace.go:171","msg":"trace[1068886101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"193.020706ms","start":"2026-09-22T03:32:19.794603Z","end":"2026-09-22T03:32:19.987624Z","steps":["trace[1068886101] 'agreement among raft nodes before linearized reading' (duration: 192.903943ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:33:55.864178Z","caller":"traceutil/trace.go:171","msg":"trace[1705962787] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"177.464123ms","start":"2026-09-22T03:33:55.686682Z","end":"2026-09-22T03:33:55.864146Z","steps":["trace[1705962787] 'process raft request' (duration: 177.26261ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:33:56.021197Z","caller":"traceutil/trace.go:171","msg":"trace[1831598550] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1918; }","duration":"242.218213ms","start":"2026-09-22T03:33:55.778936Z","end":"2026-09-22T03:33:56.021154Z","steps":["trace[1831598550] 'read index received' (duration: 85.063167ms)","trace[1831598550] 'applied index is now lower than readState.Index' (duration: 157.153646ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:33:56.021263Z","caller":"traceutil/trace.go:171","msg":"trace[1302445207] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"327.911017ms","start":"2026-09-22T03:33:55.693285Z","end":"2026-09-22T03:33:56.021196Z","steps":["trace[1302445207] 'process raft request' (duration: 317.622768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:33:56.021888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.918482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-22T03:33:56.021949Z","caller":"traceutil/trace.go:171","msg":"trace[880377543] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1846; }","duration":"243.053644ms","start":"2026-09-22T03:33:55.778875Z","end":"2026-09-22T03:33:56.021929Z","steps":["trace[880377543] 'agreement among raft nodes before linearized reading' (duration: 242.488716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:33:56.021887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:33:55.693268Z","time spent":"328.08732ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:33:56.189655Z","caller":"traceutil/trace.go:171","msg":"trace[993354887] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"137.431162ms","start":"2026-09-22T03:33:56.052199Z","end":"2026-09-22T03:33:56.18963Z","steps":["trace[993354887] 'process raft request' (duration: 74.870644ms)","trace[993354887] 'compare' (duration: 62.106982ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:34:45.752713Z","caller":"traceutil/trace.go:171","msg":"trace[864014159] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"152.383533ms","start":"2026-09-22T03:34:45.600304Z","end":"2026-09-22T03:34:45.752687Z","steps":["trace[864014159] 'process raft request' (duration: 152.19523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:34:46.042701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.964883ms","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-09-22T03:34:46.042833Z","caller":"traceutil/trace.go:171","msg":"trace[2136676576] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2167; }","duration":"111.119254ms","start":"2026-09-22T03:34:45.931684Z","end":"2026-09-22T03:34:46.042803Z","steps":["trace[2136676576] 'count revisions from in-memory index tree' (duration: 110.834521ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:35:27.422993Z","caller":"traceutil/trace.go:171","msg":"trace[2043356255] linearizableReadLoop","detail":"{readStateIndex:2592; appliedIndex:2591; }","duration":"111.679952ms","start":"2026-09-22T03:35:27.31129Z","end":"2026-09-22T03:35:27.42297Z","steps":["trace[2043356255] 'read index received' (duration: 111.484229ms)","trace[2043356255] 'applied index is now lower than readState.Index' (duration: 194.933µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:35:27.42318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.868233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T03:35:27.42322Z","caller":"traceutil/trace.go:171","msg":"trace[562060921] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2498; }","duration":"111.969905ms","start":"2026-09-22T03:35:27.311239Z","end":"2026-09-22T03:35:27.423209Z","steps":["trace[562060921] 'agreement among raft nodes before linearized reading' (duration: 111.827684ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:35:27.423617Z","caller":"traceutil/trace.go:171","msg":"trace[128134459] transaction","detail":"{read_only:false; response_revision:2498; number_of_response:1; }","duration":"132.885348ms","start":"2026-09-22T03:35:27.290721Z","end":"2026-09-22T03:35:27.423607Z","steps":["trace[128134459] 'process raft request' (duration: 132.116059ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:35:59.089956Z","caller":"traceutil/trace.go:171","msg":"trace[1803692689] linearizableReadLoop","detail":"{readStateIndex:2773; appliedIndex:2772; }","duration":"154.033933ms","start":"2026-09-22T03:35:58.935899Z","end":"2026-09-22T03:35:59.089933Z","steps":["trace[1803692689] 'read index received' (duration: 153.835525ms)","trace[1803692689] 'applied index is now lower than readState.Index' (duration: 197.448µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:35:59.090092Z","caller":"traceutil/trace.go:171","msg":"trace[569758062] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"207.80343ms","start":"2026-09-22T03:35:58.882263Z","end":"2026-09-22T03:35:59.090067Z","steps":["trace[569758062] 'process raft request' (duration: 207.520248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:35:59.090134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.226461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:35:59.09018Z","caller":"traceutil/trace.go:171","msg":"trace[831068037] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2671; }","duration":"154.316165ms","start":"2026-09-22T03:35:58.935852Z","end":"2026-09-22T03:35:59.090168Z","steps":["trace[831068037] 'agreement among raft nodes before linearized reading' (duration: 154.206901ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:35:59.090626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.92133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:35:59.090748Z","caller":"traceutil/trace.go:171","msg":"trace[550195041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2671; }","duration":"138.564928ms","start":"2026-09-22T03:35:58.952163Z","end":"2026-09-22T03:35:59.090728Z","steps":["trace[550195041] 'agreement among raft nodes before linearized reading' (duration: 137.91667ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:35:59.250913Z","caller":"traceutil/trace.go:171","msg":"trace[1814016425] linearizableReadLoop","detail":"{readStateIndex:2774; appliedIndex:2773; }","duration":"153.722495ms","start":"2026-09-22T03:35:59.09716Z","end":"2026-09-22T03:35:59.250883Z","steps":["trace[1814016425] 'read index received' (duration: 149.440104ms)","trace[1814016425] 'applied index is now lower than readState.Index' (duration: 4.281151ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:35:59.251065Z","caller":"traceutil/trace.go:171","msg":"trace[2004531017] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"155.534502ms","start":"2026-09-22T03:35:59.095489Z","end":"2026-09-22T03:35:59.251024Z","steps":["trace[2004531017] 'process raft request' (duration: 151.098575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:35:59.251246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.069989ms","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-09-22T03:35:59.251299Z","caller":"traceutil/trace.go:171","msg":"trace[246705533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2672; }","duration":"154.162294ms","start":"2026-09-22T03:35:59.097126Z","end":"2026-09-22T03:35:59.251288Z","steps":["trace[246705533] 'agreement among raft nodes before linearized reading' (duration: 153.995677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:35:59.257986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.665897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-09-22T03:35:59.258037Z","caller":"traceutil/trace.go:171","msg":"trace[785938973] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2674; }","duration":"160.73402ms","start":"2026-09-22T03:35:59.097289Z","end":"2026-09-22T03:35:59.258023Z","steps":["trace[785938973] 'agreement among raft nodes before linearized reading' (duration: 160.607075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:35:59.258358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.060735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T03:35:59.258432Z","caller":"traceutil/trace.go:171","msg":"trace[2144361356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2674; }","duration":"105.199061ms","start":"2026-09-22T03:35:59.153216Z","end":"2026-09-22T03:35:59.258415Z","steps":["trace[2144361356] 'agreement among raft nodes before linearized reading' (duration: 104.885388ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:36:04.84303Z","caller":"traceutil/trace.go:171","msg":"trace[1079327414] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"144.732852ms","start":"2026-09-22T03:36:04.698273Z","end":"2026-09-22T03:36:04.843006Z","steps":["trace[1079327414] 'process raft request' (duration: 144.582056ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:28.433407Z","caller":"traceutil/trace.go:171","msg":"trace[598101926] linearizableReadLoop","detail":"{readStateIndex:4971; appliedIndex:4970; }","duration":"161.481482ms","start":"2026-09-22T03:40:28.271903Z","end":"2026-09-22T03:40:28.433384Z","steps":["trace[598101926] 'read index received' (duration: 161.433161ms)","trace[598101926] 'applied index is now lower than readState.Index' (duration: 47.321µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:40:28.433507Z","caller":"traceutil/trace.go:171","msg":"trace[882401586] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"188.699028ms","start":"2026-09-22T03:40:28.244787Z","end":"2026-09-22T03:40:28.433486Z","steps":["trace[882401586] 'process raft request' (duration: 188.449813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:28.434122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.197134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-09-22T03:40:28.434192Z","caller":"traceutil/trace.go:171","msg":"trace[1965853310] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4784; }","duration":"162.310217ms","start":"2026-09-22T03:40:28.271862Z","end":"2026-09-22T03:40:28.434172Z","steps":["trace[1965853310] 'agreement among raft nodes before linearized reading' (duration: 161.652205ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:28.501019Z","caller":"traceutil/trace.go:171","msg":"trace[2136189064] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"227.791314ms","start":"2026-09-22T03:40:28.273207Z","end":"2026-09-22T03:40:28.500998Z","steps":["trace[2136189064] 'process raft request' (duration: 227.58913ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:28.501276Z","caller":"traceutil/trace.go:171","msg":"trace[1011070269] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"204.181293ms","start":"2026-09-22T03:40:28.297057Z","end":"2026-09-22T03:40:28.501239Z","steps":["trace[1011070269] 'process raft request' (duration: 203.868308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:28.501393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.936857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T03:40:28.501437Z","caller":"traceutil/trace.go:171","msg":"trace[84705348] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4786; }","duration":"102.989668ms","start":"2026-09-22T03:40:28.398435Z","end":"2026-09-22T03:40:28.501425Z","steps":["trace[84705348] 'agreement among raft nodes before linearized reading' (duration: 102.866266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:34.559779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.955367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-22T03:40:34.559996Z","caller":"traceutil/trace.go:171","msg":"trace[1912094004] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4851; }","duration":"157.234081ms","start":"2026-09-22T03:40:34.402734Z","end":"2026-09-22T03:40:34.559968Z","steps":["trace[1912094004] 'range keys from in-memory index tree' (duration: 156.706432ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:34.578353Z","caller":"traceutil/trace.go:171","msg":"trace[486399522] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"124.490822ms","start":"2026-09-22T03:40:34.453836Z","end":"2026-09-22T03:40:34.578327Z","steps":["trace[486399522] 'process raft request' (duration: 124.319449ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:34.909555Z","caller":"traceutil/trace.go:171","msg":"trace[1493885414] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"124.038064ms","start":"2026-09-22T03:40:34.785406Z","end":"2026-09-22T03:40:34.909444Z","steps":["trace[1493885414] 'process raft request' (duration: 105.554237ms)","trace[1493885414] 'compare' (duration: 18.232093ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:40:47.874166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:40:47.874327Z","caller":"traceutil/trace.go:171","msg":"trace[1370914756] linearizableReadLoop","detail":"{readStateIndex:5264; appliedIndex:5263; }","duration":"104.982705ms","start":"2026-09-22T03:40:47.769319Z","end":"2026-09-22T03:40:47.874302Z","steps":["trace[1370914756] 'read index received' (duration: 56.811µs)","trace[1370914756] 'applied index is now lower than readState.Index' (duration: 104.923104ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:40:47.87436Z","caller":"traceutil/trace.go:171","msg":"trace[1812628337] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"259.294576ms","start":"2026-09-22T03:40:47.615031Z","end":"2026-09-22T03:40:47.874326Z","steps":["trace[1812628337] 'process raft request' (duration: 133.025702ms)","trace[1812628337] 'compare' (duration: 125.668854ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:40:47.8745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.169638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:40:47.874551Z","caller":"traceutil/trace.go:171","msg":"trace[1520335285] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5070; }","duration":"105.26686ms","start":"2026-09-22T03:40:47.769267Z","end":"2026-09-22T03:40:47.874534Z","steps":["trace[1520335285] 'agreement among raft nodes before linearized reading' (duration: 105.099357ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:48.080666Z","caller":"traceutil/trace.go:171","msg":"trace[164320059] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5264; }","duration":"145.927554ms","start":"2026-09-22T03:40:47.934708Z","end":"2026-09-22T03:40:48.080636Z","steps":["trace[164320059] 'read index received' (duration: 49.232915ms)","trace[164320059] 'applied index is now lower than readState.Index' (duration: 96.693449ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:40:48.08087Z","caller":"traceutil/trace.go:171","msg":"trace[556552773] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"163.441659ms","start":"2026-09-22T03:40:47.917399Z","end":"2026-09-22T03:40:48.080841Z","steps":["trace[556552773] 'process raft request' (duration: 66.585047ms)","trace[556552773] 'compare' (duration: 96.474435ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:40:48.080892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.154558ms","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-09-22T03:40:48.080987Z","caller":"traceutil/trace.go:171","msg":"trace[1702217048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5071; }","duration":"146.293781ms","start":"2026-09-22T03:40:47.934682Z","end":"2026-09-22T03:40:48.080976Z","steps":["trace[1702217048] 'agreement among raft nodes before linearized reading' (duration: 146.099488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:48.081253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.610844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:40:48.081346Z","caller":"traceutil/trace.go:171","msg":"trace[790823840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5071; }","duration":"128.748956ms","start":"2026-09-22T03:40:47.952572Z","end":"2026-09-22T03:40:48.081321Z","steps":["trace[790823840] 'agreement among raft nodes before linearized reading' (duration: 128.605894ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:48.36288Z","caller":"traceutil/trace.go:171","msg":"trace[340019179] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"141.964404ms","start":"2026-09-22T03:40:48.220888Z","end":"2026-09-22T03:40:48.362852Z","steps":["trace[340019179] 'process raft request' (duration: 110.474117ms)","trace[340019179] 'compare' (duration: 30.911837ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:40:48.363512Z","caller":"traceutil/trace.go:171","msg":"trace[1186799924] linearizableReadLoop","detail":"{readStateIndex:5270; appliedIndex:5267; }","duration":"122.73697ms","start":"2026-09-22T03:40:48.240756Z","end":"2026-09-22T03:40:48.363493Z","steps":["trace[1186799924] 'read index received' (duration: 90.558281ms)","trace[1186799924] 'applied index is now lower than readState.Index' (duration: 32.177709ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:40:48.363771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.001935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-22T03:40:48.363744Z","caller":"traceutil/trace.go:171","msg":"trace[1082138962] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"142.500874ms","start":"2026-09-22T03:40:48.221204Z","end":"2026-09-22T03:40:48.363705Z","steps":["trace[1082138962] 'process raft request' (duration: 142.178298ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:48.363858Z","caller":"traceutil/trace.go:171","msg":"trace[813637937] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5076; }","duration":"123.132227ms","start":"2026-09-22T03:40:48.240702Z","end":"2026-09-22T03:40:48.363834Z","steps":["trace[813637937] 'agreement among raft nodes before linearized reading' (duration: 122.863432ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:48.363913Z","caller":"traceutil/trace.go:171","msg":"trace[451155383] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"142.933991ms","start":"2026-09-22T03:40:48.220945Z","end":"2026-09-22T03:40:48.363879Z","steps":["trace[451155383] 'process raft request' (duration: 141.570107ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:41:33.634772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-09-22T03:41:33.707728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"71.383588ms","hash":3299104770,"current-db-size-bytes":53219328,"current-db-size":"53 MB","current-db-size-in-use-bytes":28545024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T03:41:33.707806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3299104770,"revision":2855,"compact-revision":-1} {"level":"info","ts":"2026-09-22T03:44:29.655924Z","caller":"traceutil/trace.go:171","msg":"trace[921345908] transaction","detail":"{read_only:false; response_revision:6694; number_of_response:1; }","duration":"132.095846ms","start":"2026-09-22T03:44:29.523799Z","end":"2026-09-22T03:44:29.655895Z","steps":["trace[921345908] 'process raft request' (duration: 73.148498ms)","trace[921345908] 'compare' (duration: 58.437022ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:44:29.985701Z","caller":"traceutil/trace.go:171","msg":"trace[1183263633] transaction","detail":"{read_only:false; response_revision:6697; number_of_response:1; }","duration":"135.847258ms","start":"2026-09-22T03:44:29.849823Z","end":"2026-09-22T03:44:29.98567Z","steps":["trace[1183263633] 'process raft request' (duration: 66.037218ms)","trace[1183263633] 'compare' (duration: 69.528491ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:46:33.643202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5475} {"level":"info","ts":"2026-09-22T03:46:33.728211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5475,"took":"83.326923ms","hash":1524255252,"current-db-size-bytes":53219328,"current-db-size":"53 MB","current-db-size-in-use-bytes":18558976,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-22T03:46:33.728355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1524255252,"revision":5475,"compact-revision":2855}