{"level":"warn","ts":"2026-09-18T05:17:18.281822Z","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-18T05:17:18.281982Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.86: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.86:2380","--initial-cluster=instance=https://162.253.55.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.86: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-18T05:17:18.282076Z","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-18T05:17:18.282116Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-18T05:17:18.282154Z","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-18T05:17:18.282999Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"]} {"level":"info","ts":"2026-09-18T05:17:18.283137Z","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":16,"max-cpu-available":16,"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.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.86: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-18T05:17:18.28589Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.426183ms"} {"level":"info","ts":"2026-09-18T05:17:18.291993Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d01cde7b2aa18f95","cluster-id":"9e5d9cb67aed7785"} {"level":"info","ts":"2026-09-18T05:17:18.292106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:17:18.292145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 0"} {"level":"info","ts":"2026-09-18T05:17:18.292176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d01cde7b2aa18f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:17:18.292224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 1"} {"level":"info","ts":"2026-09-18T05:17:18.29232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"warn","ts":"2026-09-18T05:17:18.306706Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:17:18.308681Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:17:18.310321Z","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-18T05:17:18.313831Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d01cde7b2aa18f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:17:18.314101Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d01cde7b2aa18f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:17:18.314275Z","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-18T05:17:18.314445Z","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-18T05:17:18.314467Z","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-18T05:17:18.317138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"info","ts":"2026-09-18T05:17:18.317854Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","added-peer-id":"d01cde7b2aa18f95","added-peer-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-18T05:17:18.320996Z","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-18T05:17:18.321068Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-18T05:17:18.321128Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-18T05:17:18.321605Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:17:18.321552Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d01cde7b2aa18f95","initial-advertise-peer-urls":["https://162.253.55.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:17:18.694691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:17:18.69478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:17:18.694809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgPreVoteResp from d01cde7b2aa18f95 at term 1"} {"level":"info","ts":"2026-09-18T05:17:18.694834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:17:18.694869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgVoteResp from d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-18T05:17:18.694887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became leader at term 2"} {"level":"info","ts":"2026-09-18T05:17:18.694909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d01cde7b2aa18f95 elected leader d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-18T05:17:18.696251Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:17:18.698256Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:17:18.698398Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:17:18.698397Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d01cde7b2aa18f95","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.86:2379]}","request-path":"/0/members/d01cde7b2aa18f95/attributes","cluster-id":"9e5d9cb67aed7785","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:17:18.698412Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:17:18.698515Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:17:18.698481Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:17:18.698823Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:17:18.698883Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:17:18.702316Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.86:2379"} {"level":"info","ts":"2026-09-18T05:17:18.702724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:17:22.072603Z","caller":"traceutil/trace.go:171","msg":"trace[673228769] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"103.413627ms","start":"2026-09-18T05:17:21.969162Z","end":"2026-09-18T05:17:22.072575Z","steps":["trace[673228769] 'process raft request' (duration: 102.675408ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:35.265874Z","caller":"traceutil/trace.go:171","msg":"trace[404352666] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"191.22337ms","start":"2026-09-18T05:17:35.074613Z","end":"2026-09-18T05:17:35.265836Z","steps":["trace[404352666] 'read index received' (duration: 190.937383ms)","trace[404352666] 'applied index is now lower than readState.Index' (duration: 284.677µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:35.265955Z","caller":"traceutil/trace.go:171","msg":"trace[2114860685] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"299.987119ms","start":"2026-09-18T05:17:34.965939Z","end":"2026-09-18T05:17:35.265926Z","steps":["trace[2114860685] 'process raft request' (duration: 299.739992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:35.266071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.427716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-18T05:17:35.26617Z","caller":"traceutil/trace.go:171","msg":"trace[1551857829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"191.57652ms","start":"2026-09-18T05:17:35.074575Z","end":"2026-09-18T05:17:35.266152Z","steps":["trace[1551857829] 'agreement among raft nodes before linearized reading' (duration: 191.422946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:35.2666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:34.965917Z","time spent":"300.126033ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:17:59.495314Z","caller":"traceutil/trace.go:171","msg":"trace[1212089976] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"106.975841ms","start":"2026-09-18T05:17:59.388311Z","end":"2026-09-18T05:17:59.495286Z","steps":["trace[1212089976] 'read index received' (duration: 106.724524ms)","trace[1212089976] 'applied index is now lower than readState.Index' (duration: 250.237µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:59.49537Z","caller":"traceutil/trace.go:171","msg":"trace[1943587767] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"109.245031ms","start":"2026-09-18T05:17:59.386088Z","end":"2026-09-18T05:17:59.495333Z","steps":["trace[1943587767] 'process raft request' (duration: 108.998165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:59.495532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.187327ms","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-18T05:17:59.495638Z","caller":"traceutil/trace.go:171","msg":"trace[1263559788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"107.322521ms","start":"2026-09-18T05:17:59.388288Z","end":"2026-09-18T05:17:59.495611Z","steps":["trace[1263559788] 'agreement among raft nodes before linearized reading' (duration: 107.104715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:06.953419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10346352409388275982,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T05:18:06.997359Z","caller":"traceutil/trace.go:171","msg":"trace[1773047393] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"544.239063ms","start":"2026-09-18T05:18:06.453096Z","end":"2026-09-18T05:18:06.997335Z","steps":["trace[1773047393] 'read index received' (duration: 543.372561ms)","trace[1773047393] 'applied index is now lower than readState.Index' (duration: 865.582µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:06.997443Z","caller":"traceutil/trace.go:171","msg":"trace[909842361] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"608.572672ms","start":"2026-09-18T05:18:06.388844Z","end":"2026-09-18T05:18:06.997416Z","steps":["trace[909842361] 'process raft request' (duration: 607.933245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:06.99758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.464719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T05:18:06.997618Z","caller":"traceutil/trace.go:171","msg":"trace[990538651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"544.549731ms","start":"2026-09-18T05:18:06.453058Z","end":"2026-09-18T05:18:06.997607Z","steps":["trace[990538651] 'agreement among raft nodes before linearized reading' (duration: 544.395187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:06.997642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.921689ms","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-09-18T05:18:06.997663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:06.388811Z","time spent":"608.734947ms","remote":"127.0.0.1:52366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:18:06.997704Z","caller":"traceutil/trace.go:171","msg":"trace[768201922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"543.044441ms","start":"2026-09-18T05:18:06.454642Z","end":"2026-09-18T05:18:06.997687Z","steps":["trace[768201922] 'agreement among raft nodes before linearized reading' (duration: 542.870277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:06.997653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:06.45304Z","time spent":"544.606022ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T05:18:06.997763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:06.454622Z","time spent":"543.118255ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T05:18:06.997767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.698408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rj4n8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-18T05:18:06.997887Z","caller":"traceutil/trace.go:171","msg":"trace[1239117266] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rj4n8; range_end:; response_count:1; response_revision:598; }","duration":"474.972106ms","start":"2026-09-18T05:18:06.522892Z","end":"2026-09-18T05:18:06.997864Z","steps":["trace[1239117266] 'agreement among raft nodes before linearized reading' (duration: 474.653547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:06.997942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:06.522871Z","time spent":"475.055378ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-rj4n8\" "} {"level":"info","ts":"2026-09-18T05:18:07.175734Z","caller":"traceutil/trace.go:171","msg":"trace[779657005] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"173.213019ms","start":"2026-09-18T05:18:07.002495Z","end":"2026-09-18T05:18:07.175708Z","steps":["trace[779657005] 'process raft request' (duration: 113.424559ms)","trace[779657005] 'compare' (duration: 59.682128ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:07.262084Z","caller":"traceutil/trace.go:171","msg":"trace[950433607] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"259.546853ms","start":"2026-09-18T05:18:07.002505Z","end":"2026-09-18T05:18:07.262052Z","steps":["trace[950433607] 'process raft request' (duration: 259.235784ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:07.262432Z","caller":"traceutil/trace.go:171","msg":"trace[1414122299] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"189.568197ms","start":"2026-09-18T05:18:07.072827Z","end":"2026-09-18T05:18:07.262395Z","steps":["trace[1414122299] 'process raft request' (duration: 189.175137ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:07.26255Z","caller":"traceutil/trace.go:171","msg":"trace[354334014] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"242.479158ms","start":"2026-09-18T05:18:07.02004Z","end":"2026-09-18T05:18:07.262519Z","steps":["trace[354334014] 'process raft request' (duration: 241.873542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:07.820176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.567541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-18T05:18:07.820212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.288683ms","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-18T05:18:07.820293Z","caller":"traceutil/trace.go:171","msg":"trace[1155371377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"288.736316ms","start":"2026-09-18T05:18:07.531529Z","end":"2026-09-18T05:18:07.820266Z","steps":["trace[1155371377] 'range keys from in-memory index tree' (duration: 288.18341ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:07.820336Z","caller":"traceutil/trace.go:171","msg":"trace[1837070348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"206.447258ms","start":"2026-09-18T05:18:07.613859Z","end":"2026-09-18T05:18:07.820306Z","steps":["trace[1837070348] 'range keys from in-memory index tree' (duration: 205.750919ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:07.820797Z","caller":"traceutil/trace.go:171","msg":"trace[1354947953] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"116.671675ms","start":"2026-09-18T05:18:07.704101Z","end":"2026-09-18T05:18:07.820772Z","steps":["trace[1354947953] 'process raft request' (duration: 111.50503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:40.119014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.586936ms","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-18T05:19:40.119126Z","caller":"traceutil/trace.go:171","msg":"trace[513533243] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1833; }","duration":"101.761471ms","start":"2026-09-18T05:19:40.01734Z","end":"2026-09-18T05:19:40.119101Z","steps":["trace[513533243] 'range keys from in-memory index tree' (duration: 101.374572ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:40.27245Z","caller":"traceutil/trace.go:171","msg":"trace[430968011] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"173.892983ms","start":"2026-09-18T05:19:40.098534Z","end":"2026-09-18T05:19:40.272427Z","steps":["trace[430968011] 'process raft request' (duration: 173.750029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:40.664389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.643881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:19:40.664623Z","caller":"traceutil/trace.go:171","msg":"trace[1895183196] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1907; }","duration":"291.252389ms","start":"2026-09-18T05:19:40.373347Z","end":"2026-09-18T05:19:40.6646Z","steps":["trace[1895183196] 'read index received' (duration: 132.985842ms)","trace[1895183196] 'applied index is now lower than readState.Index' (duration: 158.265407ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:40.66468Z","caller":"traceutil/trace.go:171","msg":"trace[1605808725] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"537.616483ms","start":"2026-09-18T05:19:40.127004Z","end":"2026-09-18T05:19:40.66462Z","steps":["trace[1605808725] 'process raft request' (duration: 379.401897ms)","trace[1605808725] 'compare' (duration: 157.438735ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:40.664703Z","caller":"traceutil/trace.go:171","msg":"trace[1498549713] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"515.11775ms","start":"2026-09-18T05:19:40.14954Z","end":"2026-09-18T05:19:40.664658Z","steps":["trace[1498549713] 'process raft request' (duration: 514.963076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:40.664816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.448885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e39db90-881c-43d0-8c89-edabba58a838\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-09-18T05:19:40.664912Z","caller":"traceutil/trace.go:171","msg":"trace[1964221004] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e39db90-881c-43d0-8c89-edabba58a838; range_end:; response_count:1; response_revision:1836; }","duration":"291.621148ms","start":"2026-09-18T05:19:40.373269Z","end":"2026-09-18T05:19:40.66489Z","steps":["trace[1964221004] 'agreement among raft nodes before linearized reading' (duration: 291.424053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:40.664932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:40.149501Z","time spent":"515.302694ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:19:40.665027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:40.126967Z","time spent":"537.802407ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:19:40.834606Z","caller":"traceutil/trace.go:171","msg":"trace[1692526856] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"169.901959ms","start":"2026-09-18T05:19:40.664664Z","end":"2026-09-18T05:19:40.834566Z","steps":["trace[1692526856] 'read index received' (duration: 139.996243ms)","trace[1692526856] 'applied index is now lower than readState.Index' (duration: 29.904526ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:40.83471Z","caller":"traceutil/trace.go:171","msg":"trace[49751466] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"176.615804ms","start":"2026-09-18T05:19:40.658036Z","end":"2026-09-18T05:19:40.834652Z","steps":["trace[49751466] 'process raft request' (duration: 146.734338ms)","trace[49751466] 'compare' (duration: 29.679451ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:40.834866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.435762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T05:19:40.834912Z","caller":"traceutil/trace.go:171","msg":"trace[953030382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1837; }","duration":"437.531396ms","start":"2026-09-18T05:19:40.397369Z","end":"2026-09-18T05:19:40.8349Z","steps":["trace[953030382] 'agreement among raft nodes before linearized reading' (duration: 437.311809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:40.834952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:40.397351Z","time spent":"437.591748ms","remote":"127.0.0.1:52514","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":"warn","ts":"2026-09-18T05:19:40.835157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.453469ms","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-09-18T05:19:40.835299Z","caller":"traceutil/trace.go:171","msg":"trace[1269533507] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1837; }","duration":"369.725626ms","start":"2026-09-18T05:19:40.465527Z","end":"2026-09-18T05:19:40.835253Z","steps":["trace[1269533507] 'agreement among raft nodes before linearized reading' (duration: 369.314815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:40.835374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:40.465501Z","time spent":"369.858209ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T05:19:40.83501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.151624ms","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-18T05:19:40.835634Z","caller":"traceutil/trace.go:171","msg":"trace[1270408936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1837; }","duration":"361.822041ms","start":"2026-09-18T05:19:40.473796Z","end":"2026-09-18T05:19:40.835618Z","steps":["trace[1270408936] 'agreement among raft nodes before linearized reading' (duration: 360.985109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:40.83593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:40.473743Z","time spent":"362.147409ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T05:20:25.273459Z","caller":"traceutil/trace.go:171","msg":"trace[1242369843] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"204.094111ms","start":"2026-09-18T05:20:25.069337Z","end":"2026-09-18T05:20:25.273431Z","steps":["trace[1242369843] 'process raft request' (duration: 203.940417ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:25.345671Z","caller":"traceutil/trace.go:171","msg":"trace[1786893298] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2217; }","duration":"262.186698ms","start":"2026-09-18T05:20:25.083442Z","end":"2026-09-18T05:20:25.345628Z","steps":["trace[1786893298] 'read index received' (duration: 189.915965ms)","trace[1786893298] 'applied index is now lower than readState.Index' (duration: 72.269043ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:20:25.345761Z","caller":"traceutil/trace.go:171","msg":"trace[1227374066] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"276.071267ms","start":"2026-09-18T05:20:25.069645Z","end":"2026-09-18T05:20:25.345716Z","steps":["trace[1227374066] 'process raft request' (duration: 275.83231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:20:25.345892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.436646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-18T05:20:25.345942Z","caller":"traceutil/trace.go:171","msg":"trace[1389344795] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2136; }","duration":"262.521367ms","start":"2026-09-18T05:20:25.083403Z","end":"2026-09-18T05:20:25.345924Z","steps":["trace[1389344795] 'agreement among raft nodes before linearized reading' (duration: 262.361483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:25.486522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.235825ms","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-18T05:21:25.486666Z","caller":"traceutil/trace.go:171","msg":"trace[894899692] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2634; }","duration":"126.438601ms","start":"2026-09-18T05:21:25.360197Z","end":"2026-09-18T05:21:25.486635Z","steps":["trace[894899692] 'count revisions from in-memory index tree' (duration: 126.127492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:00.264615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.204009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:22:00.264712Z","caller":"traceutil/trace.go:171","msg":"trace[532131904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2842; }","duration":"320.348362ms","start":"2026-09-18T05:21:59.944341Z","end":"2026-09-18T05:22:00.264689Z","steps":["trace[532131904] 'range keys from in-memory index tree' (duration: 320.114747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:00.264762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:59.944311Z","time spent":"320.435006ms","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T05:22:00.265198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.63356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:22:00.2653Z","caller":"traceutil/trace.go:171","msg":"trace[1878581047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2842; }","duration":"242.744563ms","start":"2026-09-18T05:22:00.02251Z","end":"2026-09-18T05:22:00.265255Z","steps":["trace[1878581047] 'range keys from in-memory index tree' (duration: 242.489817ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:00.270374Z","caller":"traceutil/trace.go:171","msg":"trace[1438491060] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"136.349253ms","start":"2026-09-18T05:22:00.134005Z","end":"2026-09-18T05:22:00.270354Z","steps":["trace[1438491060] 'process raft request' (duration: 136.183668ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:00.271138Z","caller":"traceutil/trace.go:171","msg":"trace[102649450] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"103.474057ms","start":"2026-09-18T05:22:00.167644Z","end":"2026-09-18T05:22:00.271118Z","steps":["trace[102649450] 'process raft request' (duration: 103.378724ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:41.230139Z","caller":"traceutil/trace.go:171","msg":"trace[1569062336] transaction","detail":"{read_only:false; response_revision:3823; number_of_response:1; }","duration":"116.406635ms","start":"2026-09-18T05:24:41.113706Z","end":"2026-09-18T05:24:41.230112Z","steps":["trace[1569062336] 'process raft request' (duration: 111.52545ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:41.231468Z","caller":"traceutil/trace.go:171","msg":"trace[1744043064] transaction","detail":"{read_only:false; response_revision:3824; number_of_response:1; }","duration":"115.147062ms","start":"2026-09-18T05:24:41.116293Z","end":"2026-09-18T05:24:41.23144Z","steps":["trace[1744043064] 'process raft request' (duration: 114.836075ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:17.119141Z","caller":"traceutil/trace.go:171","msg":"trace[1374843959] transaction","detail":"{read_only:false; response_revision:4075; number_of_response:1; }","duration":"250.804233ms","start":"2026-09-18T05:25:16.868314Z","end":"2026-09-18T05:25:17.119118Z","steps":["trace[1374843959] 'process raft request' (duration: 250.645559ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:17.119691Z","caller":"traceutil/trace.go:171","msg":"trace[444799725] linearizableReadLoop","detail":"{readStateIndex:4232; appliedIndex:4232; }","duration":"243.612078ms","start":"2026-09-18T05:25:16.876027Z","end":"2026-09-18T05:25:17.119639Z","steps":["trace[444799725] 'read index received' (duration: 243.578057ms)","trace[444799725] 'applied index is now lower than readState.Index' (duration: 29.54µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:17.119878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.843124ms","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-09-18T05:25:17.119964Z","caller":"traceutil/trace.go:171","msg":"trace[1970904173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4075; }","duration":"243.966968ms","start":"2026-09-18T05:25:16.875961Z","end":"2026-09-18T05:25:17.119928Z","steps":["trace[1970904173] 'agreement among raft nodes before linearized reading' (duration: 243.779882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:17.196154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.738783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:25:17.196223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.273593ms","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-18T05:25:17.196258Z","caller":"traceutil/trace.go:171","msg":"trace[693437292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4076; }","duration":"173.894667ms","start":"2026-09-18T05:25:17.022344Z","end":"2026-09-18T05:25:17.196238Z","steps":["trace[693437292] 'agreement among raft nodes before linearized reading' (duration: 173.734983ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:17.196278Z","caller":"traceutil/trace.go:171","msg":"trace[1501760100] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4076; }","duration":"105.382687ms","start":"2026-09-18T05:25:17.090882Z","end":"2026-09-18T05:25:17.196264Z","steps":["trace[1501760100] 'agreement among raft nodes before linearized reading' (duration: 105.174431ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:17.19652Z","caller":"traceutil/trace.go:171","msg":"trace[548819355] transaction","detail":"{read_only:false; response_revision:4076; number_of_response:1; }","duration":"244.777488ms","start":"2026-09-18T05:25:16.951725Z","end":"2026-09-18T05:25:17.196502Z","steps":["trace[548819355] 'process raft request' (duration: 223.188154ms)","trace[548819355] 'compare' (duration: 20.776053ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:17.196632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.345338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-18T05:25:17.196766Z","caller":"traceutil/trace.go:171","msg":"trace[1345455819] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4076; }","duration":"320.550813ms","start":"2026-09-18T05:25:16.876179Z","end":"2026-09-18T05:25:17.19673Z","steps":["trace[1345455819] 'agreement among raft nodes before linearized reading' (duration: 320.219945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:17.196848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:16.876173Z","time spent":"320.655135ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-18T05:26:42.460174Z","caller":"traceutil/trace.go:171","msg":"trace[1197021060] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"102.730979ms","start":"2026-09-18T05:26:42.357412Z","end":"2026-09-18T05:26:42.460143Z","steps":["trace[1197021060] 'process raft request' (duration: 102.624286ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:42.460239Z","caller":"traceutil/trace.go:171","msg":"trace[856776201] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"111.276798ms","start":"2026-09-18T05:26:42.348906Z","end":"2026-09-18T05:26:42.460183Z","steps":["trace[856776201] 'process raft request' (duration: 110.433097ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:46.129638Z","caller":"traceutil/trace.go:171","msg":"trace[1832735659] linearizableReadLoop","detail":"{readStateIndex:5338; appliedIndex:5337; }","duration":"107.878632ms","start":"2026-09-18T05:26:46.021739Z","end":"2026-09-18T05:26:46.129618Z","steps":["trace[1832735659] 'read index received' (duration: 107.679637ms)","trace[1832735659] 'applied index is now lower than readState.Index' (duration: 198.215µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:26:46.12968Z","caller":"traceutil/trace.go:171","msg":"trace[1432922733] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"115.928168ms","start":"2026-09-18T05:26:46.013726Z","end":"2026-09-18T05:26:46.129654Z","steps":["trace[1432922733] 'process raft request' (duration: 115.773284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:46.129771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.016395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:26:46.129798Z","caller":"traceutil/trace.go:171","msg":"trace[2039768955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5139; }","duration":"108.087116ms","start":"2026-09-18T05:26:46.021704Z","end":"2026-09-18T05:26:46.129791Z","steps":["trace[2039768955] 'agreement among raft nodes before linearized reading' (duration: 108.022504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:50.812652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10346352409388286980,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T05:26:50.877415Z","caller":"traceutil/trace.go:171","msg":"trace[1965386969] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"589.699456ms","start":"2026-09-18T05:26:50.287671Z","end":"2026-09-18T05:26:50.877371Z","steps":["trace[1965386969] 'process raft request' (duration: 564.733536ms)","trace[1965386969] 'compare' (duration: 24.602871ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:26:50.877593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:26:50.287641Z","time spent":"589.85359ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3702,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:26:50.901644Z","caller":"traceutil/trace.go:171","msg":"trace[1431014339] linearizableReadLoop","detail":"{readStateIndex:5487; appliedIndex:5485; }","duration":"589.04089ms","start":"2026-09-18T05:26:50.312575Z","end":"2026-09-18T05:26:50.901616Z","steps":["trace[1431014339] 'read index received' (duration: 539.837777ms)","trace[1431014339] 'applied index is now lower than readState.Index' (duration: 49.201873ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:26:50.901798Z","caller":"traceutil/trace.go:171","msg":"trace[219441856] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"383.403388ms","start":"2026-09-18T05:26:50.518369Z","end":"2026-09-18T05:26:50.901773Z","steps":["trace[219441856] 'process raft request' (duration: 383.058779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:50.901822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.228816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6bba84a6-cc25-48f4-ae0d-393675aa936d\" ","response":"range_response_count:1 size:3717"} {"level":"info","ts":"2026-09-18T05:26:50.901864Z","caller":"traceutil/trace.go:171","msg":"trace[1574805541] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6bba84a6-cc25-48f4-ae0d-393675aa936d; range_end:; response_count:1; response_revision:5284; }","duration":"589.342969ms","start":"2026-09-18T05:26:50.312509Z","end":"2026-09-18T05:26:50.901852Z","steps":["trace[1574805541] 'agreement among raft nodes before linearized reading' (duration: 589.212734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:50.901899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:26:50.312483Z","time spent":"589.406831ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3741,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6bba84a6-cc25-48f4-ae0d-393675aa936d\" "} {"level":"warn","ts":"2026-09-18T05:26:50.901929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:26:50.518342Z","time spent":"383.513881ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:26:51.44291Z","caller":"traceutil/trace.go:171","msg":"trace[1018769270] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5284; }","duration":"358.845827ms","start":"2026-09-18T05:26:51.084035Z","end":"2026-09-18T05:26:51.44288Z","steps":["trace[1018769270] 'process raft request' (duration: 358.695894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:51.443324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:26:51.084018Z","time spent":"359.240488ms","remote":"127.0.0.1:52384","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:27:18.907142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2950} {"level":"info","ts":"2026-09-18T05:27:18.983181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2950,"took":"74.37657ms","hash":2581657326,"current-db-size-bytes":55857152,"current-db-size":"56 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-18T05:27:18.983275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2581657326,"revision":2950,"compact-revision":-1} {"level":"info","ts":"2026-09-18T05:27:35.202549Z","caller":"traceutil/trace.go:171","msg":"trace[1891846740] linearizableReadLoop","detail":"{readStateIndex:5978; appliedIndex:5977; }","duration":"130.547269ms","start":"2026-09-18T05:27:35.071977Z","end":"2026-09-18T05:27:35.202524Z","steps":["trace[1891846740] 'read index received' (duration: 130.267383ms)","trace[1891846740] 'applied index is now lower than readState.Index' (duration: 278.996µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:27:35.202626Z","caller":"traceutil/trace.go:171","msg":"trace[1445389465] transaction","detail":"{read_only:false; response_revision:5760; number_of_response:1; }","duration":"131.7042ms","start":"2026-09-18T05:27:35.070895Z","end":"2026-09-18T05:27:35.202599Z","steps":["trace[1445389465] 'process raft request' (duration: 131.425882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:35.202721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.89975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:27:35.202757Z","caller":"traceutil/trace.go:171","msg":"trace[1407579938] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5760; }","duration":"118.986093ms","start":"2026-09-18T05:27:35.08376Z","end":"2026-09-18T05:27:35.202746Z","steps":["trace[1407579938] 'agreement among raft nodes before linearized reading' (duration: 118.893461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:35.20283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.824878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-09-18T05:27:35.202915Z","caller":"traceutil/trace.go:171","msg":"trace[1311623847] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:5760; }","duration":"130.963641ms","start":"2026-09-18T05:27:35.071931Z","end":"2026-09-18T05:27:35.202895Z","steps":["trace[1311623847] 'agreement among raft nodes before linearized reading' (duration: 130.720105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:35.498892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.308561ms","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-18T05:27:35.499021Z","caller":"traceutil/trace.go:171","msg":"trace[1627278999] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5760; }","duration":"174.500456ms","start":"2026-09-18T05:27:35.324496Z","end":"2026-09-18T05:27:35.498996Z","steps":["trace[1627278999] 'range keys from in-memory index tree' (duration: 174.083646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:35.499224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.615182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-09-18T05:27:35.49964Z","caller":"traceutil/trace.go:171","msg":"trace[1863535421] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:5760; }","duration":"172.076194ms","start":"2026-09-18T05:27:35.3275Z","end":"2026-09-18T05:27:35.499577Z","steps":["trace[1863535421] 'range keys from in-memory index tree' (duration: 171.319734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:35.499649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.271733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/\" range_end:\"/registry/services/endpoints/kube-system0\" limit:500 ","response":"range_response_count:7 size:7891"} {"level":"warn","ts":"2026-09-18T05:27:35.499425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.9431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:912"} {"level":"info","ts":"2026-09-18T05:27:35.499744Z","caller":"traceutil/trace.go:171","msg":"trace[1591506778] range","detail":"{range_begin:/registry/services/endpoints/kube-system/; range_end:/registry/services/endpoints/kube-system0; response_count:7; response_revision:5760; }","duration":"166.387086ms","start":"2026-09-18T05:27:35.333333Z","end":"2026-09-18T05:27:35.49972Z","steps":["trace[1591506778] 'range keys from in-memory index tree' (duration: 166.040098ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:27:35.499776Z","caller":"traceutil/trace.go:171","msg":"trace[1421743260] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:5760; }","duration":"184.365081ms","start":"2026-09-18T05:27:35.315387Z","end":"2026-09-18T05:27:35.499753Z","steps":["trace[1421743260] 'range keys from in-memory index tree' (duration: 183.714383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:35.499415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.776903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:27:35.499974Z","caller":"traceutil/trace.go:171","msg":"trace[460398831] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:5760; }","duration":"182.42744ms","start":"2026-09-18T05:27:35.317536Z","end":"2026-09-18T05:27:35.499963Z","steps":["trace[460398831] 'range keys from in-memory index tree' (duration: 181.65868ms)"],"step_count":1}