{"level":"warn","ts":"2026-04-10T05:08:42.246636Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-10T05:08:42.246765Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-04-10T05:08:42.24691Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-10T05:08:42.24693Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-10T05:08:42.246979Z","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-04-10T05:08:42.247913Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-04-10T05:08:42.249011Z","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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-04-10T05:08:42.253131Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.731085ms"} {"level":"info","ts":"2026-04-10T05:08:42.258928Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-04-10T05:08:42.259026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T05:08:42.259054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-04-10T05:08:42.259069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T05:08:42.259112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-04-10T05:08:42.259166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-04-10T05:08:42.264897Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T05:08:42.266203Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T05:08:42.267307Z","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-04-10T05:08:42.269977Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T05:08:42.270109Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T05:08:42.27032Z","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-04-10T05:08:42.270626Z","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-04-10T05:08:42.270636Z","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-04-10T05:08:42.275224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-04-10T05:08:42.27545Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-10T05:08:42.276081Z","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-04-10T05:08:42.27627Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-10T05:08:42.276488Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T05:08:42.276553Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T05:08:42.276319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-10T05:08:42.459905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T05:08:42.460075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T05:08:42.460182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-04-10T05:08:42.460263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-04-10T05:08:42.460306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-10T05:08:42.460402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-04-10T05:08:42.460464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-10T05:08:42.461363Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T05:08:42.461477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T05:08:42.461543Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T05:08:42.465447Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T05:08:42.46557Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T05:08:42.461498Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T05:08:42.464751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-04-10T05:08:42.466104Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T05:08:42.466327Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T05:08:42.46697Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T05:08:42.468087Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-10T05:08:44.074523Z","caller":"traceutil/trace.go:171","msg":"trace[1805926241] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"161.549924ms","start":"2026-04-10T05:08:43.912944Z","end":"2026-04-10T05:08:44.074494Z","steps":["trace[1805926241] 'read index received' (duration: 161.499304ms)","trace[1805926241] 'applied index is now lower than readState.Index' (duration: 49.65µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T05:08:44.074699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.731197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T05:08:44.074761Z","caller":"traceutil/trace.go:171","msg":"trace[1802797749] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"161.834018ms","start":"2026-04-10T05:08:43.912918Z","end":"2026-04-10T05:08:44.074752Z","steps":["trace[1802797749] 'agreement among raft nodes before linearized reading' (duration: 161.654776ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.074533Z","caller":"traceutil/trace.go:171","msg":"trace[1218861528] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"162.399782ms","start":"2026-04-10T05:08:43.912113Z","end":"2026-04-10T05:08:44.074513Z","steps":["trace[1218861528] 'process raft request' (duration: 162.17052ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.275918Z","caller":"traceutil/trace.go:171","msg":"trace[1533319886] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"272.972314ms","start":"2026-04-10T05:08:44.002917Z","end":"2026-04-10T05:08:44.27589Z","steps":["trace[1533319886] 'process raft request' (duration: 272.855624ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.276079Z","caller":"traceutil/trace.go:171","msg":"trace[590967554] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"201.386455ms","start":"2026-04-10T05:08:44.074656Z","end":"2026-04-10T05:08:44.276042Z","steps":["trace[590967554] 'read index received' (duration: 200.732571ms)","trace[590967554] 'applied index is now lower than readState.Index' (duration: 652.704µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T05:08:44.27609Z","caller":"traceutil/trace.go:171","msg":"trace[868370270] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"269.140008ms","start":"2026-04-10T05:08:44.006849Z","end":"2026-04-10T05:08:44.275989Z","steps":["trace[868370270] 'process raft request' (duration: 268.984137ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.276183Z","caller":"traceutil/trace.go:171","msg":"trace[739036545] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"264.512557ms","start":"2026-04-10T05:08:44.01155Z","end":"2026-04-10T05:08:44.276062Z","steps":["trace[739036545] 'process raft request' (duration: 264.360166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.276415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.371758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T05:08:44.276426Z","caller":"traceutil/trace.go:171","msg":"trace[1429988207] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"265.231632ms","start":"2026-04-10T05:08:44.011156Z","end":"2026-04-10T05:08:44.276388Z","steps":["trace[1429988207] 'process raft request' (duration: 264.725268ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.276483Z","caller":"traceutil/trace.go:171","msg":"trace[91607228] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"264.78908ms","start":"2026-04-10T05:08:44.011675Z","end":"2026-04-10T05:08:44.276464Z","steps":["trace[91607228] 'process raft request' (duration: 264.269946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.27758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.218602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-10T05:08:44.276553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.79586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T05:08:44.277638Z","caller":"traceutil/trace.go:171","msg":"trace[778285070] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"314.284523ms","start":"2026-04-10T05:08:43.963327Z","end":"2026-04-10T05:08:44.277611Z","steps":["trace[778285070] 'agreement among raft nodes before linearized reading' (duration: 314.206373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.27768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T05:08:43.963313Z","time spent":"314.357944ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-10T05:08:44.27796Z","caller":"traceutil/trace.go:171","msg":"trace[634064048] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"284.153949ms","start":"2026-04-10T05:08:43.993732Z","end":"2026-04-10T05:08:44.277886Z","steps":["trace[634064048] 'agreement among raft nodes before linearized reading' (duration: 282.74522ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.278278Z","caller":"traceutil/trace.go:171","msg":"trace[1662045504] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"265.527314ms","start":"2026-04-10T05:08:44.011649Z","end":"2026-04-10T05:08:44.277177Z","steps":["trace[1662045504] 'process raft request' (duration: 264.321256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.27841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.523344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T05:08:44.278451Z","caller":"traceutil/trace.go:171","msg":"trace[1933400393] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"354.593184ms","start":"2026-04-10T05:08:43.923842Z","end":"2026-04-10T05:08:44.278435Z","steps":["trace[1933400393] 'agreement among raft nodes before linearized reading' (duration: 354.373633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.278494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T05:08:43.923827Z","time spent":"354.657215ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-10T05:08:44.277461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.025776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T05:08:44.278587Z","caller":"traceutil/trace.go:171","msg":"trace[190869873] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:0; response_revision:10; }","duration":"286.154303ms","start":"2026-04-10T05:08:43.992403Z","end":"2026-04-10T05:08:44.278557Z","steps":["trace[190869873] 'agreement among raft nodes before linearized reading' (duration: 284.758324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.276413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.509796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T05:08:44.279075Z","caller":"traceutil/trace.go:171","msg":"trace[1500364084] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"266.698952ms","start":"2026-04-10T05:08:44.01206Z","end":"2026-04-10T05:08:44.278759Z","steps":["trace[1500364084] 'process raft request' (duration: 263.937494ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.279217Z","caller":"traceutil/trace.go:171","msg":"trace[2063885142] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"280.200343ms","start":"2026-04-10T05:08:43.998841Z","end":"2026-04-10T05:08:44.279041Z","steps":["trace[2063885142] 'agreement among raft nodes before linearized reading' (duration: 277.490266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.279302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.278088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T05:08:44.279348Z","caller":"traceutil/trace.go:171","msg":"trace[153406527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"365.328738ms","start":"2026-04-10T05:08:43.914006Z","end":"2026-04-10T05:08:44.279335Z","steps":["trace[153406527] 'agreement among raft nodes before linearized reading' (duration: 365.250858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.279397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T05:08:43.914004Z","time spent":"365.384348ms","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T05:08:44.279517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.531734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T05:08:44.279568Z","caller":"traceutil/trace.go:171","msg":"trace[473824115] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"229.651464ms","start":"2026-04-10T05:08:44.0499Z","end":"2026-04-10T05:08:44.279551Z","steps":["trace[473824115] 'agreement among raft nodes before linearized reading' (duration: 229.551804ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.280003Z","caller":"traceutil/trace.go:171","msg":"trace[1219800357] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"279.217757ms","start":"2026-04-10T05:08:43.997213Z","end":"2026-04-10T05:08:44.276431Z","steps":["trace[1219800357] 'process raft request' (duration: 278.225199ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:44.28023Z","caller":"traceutil/trace.go:171","msg":"trace[1495288740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"366.250834ms","start":"2026-04-10T05:08:43.91396Z","end":"2026-04-10T05:08:44.280211Z","steps":["trace[1495288740] 'agreement among raft nodes before linearized reading' (duration: 362.266636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:08:44.280316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T05:08:43.913941Z","time spent":"366.334264ms","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-10T05:08:44.280823Z","caller":"traceutil/trace.go:171","msg":"trace[1208586562] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"205.08375ms","start":"2026-04-10T05:08:44.075711Z","end":"2026-04-10T05:08:44.280795Z","steps":["trace[1208586562] 'process raft request' (duration: 204.97323ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:08:50.991169Z","caller":"traceutil/trace.go:171","msg":"trace[21591027] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"112.283042ms","start":"2026-04-10T05:08:50.878861Z","end":"2026-04-10T05:08:50.991144Z","steps":["trace[21591027] 'process raft request' (duration: 112.13296ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:09:17.110004Z","caller":"traceutil/trace.go:171","msg":"trace[961352177] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"182.332054ms","start":"2026-04-10T05:09:16.927637Z","end":"2026-04-10T05:09:17.109969Z","steps":["trace[961352177] 'process raft request' (duration: 179.620799ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:09:17.33094Z","caller":"traceutil/trace.go:171","msg":"trace[1126140122] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"148.818322ms","start":"2026-04-10T05:09:17.182088Z","end":"2026-04-10T05:09:17.330907Z","steps":["trace[1126140122] 'read index received' (duration: 141.619104ms)","trace[1126140122] 'applied index is now lower than readState.Index' (duration: 7.198078ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T05:09:17.331061Z","caller":"traceutil/trace.go:171","msg":"trace[2065547020] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"170.624939ms","start":"2026-04-10T05:09:17.160394Z","end":"2026-04-10T05:09:17.331019Z","steps":["trace[2065547020] 'process raft request' (duration: 163.298741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:09:17.331227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.115143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cpmn6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-10T05:09:17.331293Z","caller":"traceutil/trace.go:171","msg":"trace[2001212888] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cpmn6; range_end:; response_count:1; response_revision:618; }","duration":"149.222014ms","start":"2026-04-10T05:09:17.182053Z","end":"2026-04-10T05:09:17.331275Z","steps":["trace[2001212888] 'agreement among raft nodes before linearized reading' (duration: 148.970502ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:09:17.928963Z","caller":"traceutil/trace.go:171","msg":"trace[1951511406] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"126.997725ms","start":"2026-04-10T05:09:17.801941Z","end":"2026-04-10T05:09:17.928939Z","steps":["trace[1951511406] 'read index received' (duration: 114.277936ms)","trace[1951511406] 'applied index is now lower than readState.Index' (duration: 12.718679ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T05:09:17.929152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.195996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T05:09:17.92923Z","caller":"traceutil/trace.go:171","msg":"trace[2093136574] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"206.162681ms","start":"2026-04-10T05:09:17.723051Z","end":"2026-04-10T05:09:17.929214Z","steps":["trace[2093136574] 'process raft request' (duration: 193.415332ms)","trace[2093136574] 'compare' (duration: 11.901964ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T05:09:17.929234Z","caller":"traceutil/trace.go:171","msg":"trace[1282958537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"127.319057ms","start":"2026-04-10T05:09:17.801899Z","end":"2026-04-10T05:09:17.929218Z","steps":["trace[1282958537] 'agreement among raft nodes before linearized reading' (duration: 127.197847ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:09:17.929131Z","caller":"traceutil/trace.go:171","msg":"trace[322607276] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"162.341775ms","start":"2026-04-10T05:09:17.766729Z","end":"2026-04-10T05:09:17.92907Z","steps":["trace[322607276] 'process raft request' (duration: 162.116814ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:10:08.876888Z","caller":"traceutil/trace.go:171","msg":"trace[834140766] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"160.498871ms","start":"2026-04-10T05:10:08.716368Z","end":"2026-04-10T05:10:08.876867Z","steps":["trace[834140766] 'process raft request' (duration: 160.343428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:10:08.918013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.845213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-10T05:10:08.918054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.301804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-10T05:10:08.918094Z","caller":"traceutil/trace.go:171","msg":"trace[1338905553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1201; }","duration":"111.948757ms","start":"2026-04-10T05:10:08.806126Z","end":"2026-04-10T05:10:08.918075Z","steps":["trace[1338905553] 'agreement among raft nodes before linearized reading' (duration: 111.725761ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:10:08.918223Z","caller":"traceutil/trace.go:171","msg":"trace[331615481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"112.439967ms","start":"2026-04-10T05:10:08.805674Z","end":"2026-04-10T05:10:08.918114Z","steps":["trace[331615481] 'agreement among raft nodes before linearized reading' (duration: 112.239733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:10:57.549316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.187603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-561dea09-06ae-4c04-859e-d93e368b663d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-10T05:10:57.549416Z","caller":"traceutil/trace.go:171","msg":"trace[1428847035] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-561dea09-06ae-4c04-859e-d93e368b663d; range_end:; response_count:1; response_revision:1887; }","duration":"162.329186ms","start":"2026-04-10T05:10:57.387061Z","end":"2026-04-10T05:10:57.54939Z","steps":["trace[1428847035] 'range keys from in-memory index tree' (duration: 162.005551ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:10:57.645193Z","caller":"traceutil/trace.go:171","msg":"trace[2132797770] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"114.797878ms","start":"2026-04-10T05:10:57.530369Z","end":"2026-04-10T05:10:57.645167Z","steps":["trace[2132797770] 'read index received' (duration: 114.643395ms)","trace[2132797770] 'applied index is now lower than readState.Index' (duration: 153.393µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T05:10:57.645416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.029341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-10T05:10:57.645464Z","caller":"traceutil/trace.go:171","msg":"trace[1265891092] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1888; }","duration":"115.138252ms","start":"2026-04-10T05:10:57.530312Z","end":"2026-04-10T05:10:57.64545Z","steps":["trace[1265891092] 'agreement among raft nodes before linearized reading' (duration: 114.966719ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:10:57.645278Z","caller":"traceutil/trace.go:171","msg":"trace[695312317] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"135.594594ms","start":"2026-04-10T05:10:57.509621Z","end":"2026-04-10T05:10:57.645215Z","steps":["trace[695312317] 'process raft request' (duration: 135.371571ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:10:57.756969Z","caller":"traceutil/trace.go:171","msg":"trace[1273962118] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"104.794059ms","start":"2026-04-10T05:10:57.652149Z","end":"2026-04-10T05:10:57.756943Z","steps":["trace[1273962118] 'process raft request' (duration: 104.459664ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:11:31.938618Z","caller":"traceutil/trace.go:171","msg":"trace[1014979152] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"104.914875ms","start":"2026-04-10T05:11:31.83367Z","end":"2026-04-10T05:11:31.938585Z","steps":["trace[1014979152] 'process raft request' (duration: 101.737394ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:11:37.998602Z","caller":"traceutil/trace.go:171","msg":"trace[558121856] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"161.390376ms","start":"2026-04-10T05:11:37.837186Z","end":"2026-04-10T05:11:37.998576Z","steps":["trace[558121856] 'read index received' (duration: 153.980164ms)","trace[558121856] 'applied index is now lower than readState.Index' (duration: 7.409272ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T05:11:37.998747Z","caller":"traceutil/trace.go:171","msg":"trace[201543121] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"172.239091ms","start":"2026-04-10T05:11:37.826437Z","end":"2026-04-10T05:11:37.998676Z","steps":["trace[201543121] 'process raft request' (duration: 164.817938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:11:37.998874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.66417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-10T05:11:37.998938Z","caller":"traceutil/trace.go:171","msg":"trace[778881380] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2157; }","duration":"161.75394ms","start":"2026-04-10T05:11:37.837167Z","end":"2026-04-10T05:11:37.998921Z","steps":["trace[778881380] 'agreement among raft nodes before linearized reading' (duration: 161.530408ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:13:05.751519Z","caller":"traceutil/trace.go:171","msg":"trace[1773144843] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"125.656074ms","start":"2026-04-10T05:13:05.625826Z","end":"2026-04-10T05:13:05.751482Z","steps":["trace[1773144843] 'process raft request' (duration: 112.562069ms)","trace[1773144843] 'compare' (duration: 12.53959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T05:16:43.872842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.558344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-10T05:16:43.872931Z","caller":"traceutil/trace.go:171","msg":"trace[1445352823] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4533; }","duration":"146.671275ms","start":"2026-04-10T05:16:43.726243Z","end":"2026-04-10T05:16:43.872914Z","steps":["trace[1445352823] 'range keys from in-memory index tree' (duration: 146.354121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:16:43.872911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.715806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:489395"} {"level":"info","ts":"2026-04-10T05:16:43.873014Z","caller":"traceutil/trace.go:171","msg":"trace[443632177] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4533; }","duration":"157.879618ms","start":"2026-04-10T05:16:43.715113Z","end":"2026-04-10T05:16:43.872993Z","steps":["trace[443632177] 'range keys from in-memory index tree' (duration: 157.119737ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:16:51.707631Z","caller":"traceutil/trace.go:171","msg":"trace[230503152] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"128.751654ms","start":"2026-04-10T05:16:51.578846Z","end":"2026-04-10T05:16:51.707598Z","steps":["trace[230503152] 'process raft request' (duration: 128.534731ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:16:52.209258Z","caller":"traceutil/trace.go:171","msg":"trace[878645603] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"260.516985ms","start":"2026-04-10T05:16:51.948714Z","end":"2026-04-10T05:16:52.209231Z","steps":["trace[878645603] 'process raft request' (duration: 260.330792ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:16:52.274522Z","caller":"traceutil/trace.go:171","msg":"trace[1694427789] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"255.404581ms","start":"2026-04-10T05:16:52.019085Z","end":"2026-04-10T05:16:52.27449Z","steps":["trace[1694427789] 'process raft request' (duration: 245.241787ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:16:52.275056Z","caller":"traceutil/trace.go:171","msg":"trace[2132407448] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"105.70124ms","start":"2026-04-10T05:16:52.169325Z","end":"2026-04-10T05:16:52.275026Z","steps":["trace[2132407448] 'process raft request' (duration: 105.070441ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:16:52.418658Z","caller":"traceutil/trace.go:171","msg":"trace[534776629] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"123.08495ms","start":"2026-04-10T05:16:52.29554Z","end":"2026-04-10T05:16:52.418625Z","steps":["trace[534776629] 'process raft request' (duration: 122.835147ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:16:52.418804Z","caller":"traceutil/trace.go:171","msg":"trace[1987340994] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"120.365371ms","start":"2026-04-10T05:16:52.29841Z","end":"2026-04-10T05:16:52.418775Z","steps":["trace[1987340994] 'process raft request' (duration: 120.198959ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:16:52.419298Z","caller":"traceutil/trace.go:171","msg":"trace[874244584] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"123.706218ms","start":"2026-04-10T05:16:52.295575Z","end":"2026-04-10T05:16:52.419282Z","steps":["trace[874244584] 'process raft request' (duration: 122.937948ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:17:06.086193Z","caller":"traceutil/trace.go:171","msg":"trace[306837757] linearizableReadLoop","detail":"{readStateIndex:5090; appliedIndex:5089; }","duration":"108.024645ms","start":"2026-04-10T05:17:05.978141Z","end":"2026-04-10T05:17:06.086165Z","steps":["trace[306837757] 'read index received' (duration: 107.802991ms)","trace[306837757] 'applied index is now lower than readState.Index' (duration: 220.754µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T05:17:06.086266Z","caller":"traceutil/trace.go:171","msg":"trace[31665377] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"155.332495ms","start":"2026-04-10T05:17:05.930903Z","end":"2026-04-10T05:17:06.086236Z","steps":["trace[31665377] 'process raft request' (duration: 155.123342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:17:06.086427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.268829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T05:17:06.087135Z","caller":"traceutil/trace.go:171","msg":"trace[92572581] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4908; }","duration":"109.035299ms","start":"2026-04-10T05:17:05.978083Z","end":"2026-04-10T05:17:06.087118Z","steps":["trace[92572581] 'agreement among raft nodes before linearized reading' (duration: 108.235638ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:17:06.19781Z","caller":"traceutil/trace.go:171","msg":"trace[2054694100] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"103.969967ms","start":"2026-04-10T05:17:06.093809Z","end":"2026-04-10T05:17:06.197779Z","steps":["trace[2054694100] 'process raft request' (duration: 80.823615ms)","trace[2054694100] 'compare' (duration: 23.04272ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T05:17:06.206413Z","caller":"traceutil/trace.go:171","msg":"trace[107730712] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"112.447775ms","start":"2026-04-10T05:17:06.093955Z","end":"2026-04-10T05:17:06.206403Z","steps":["trace[107730712] 'process raft request' (duration: 112.398894ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:17:06.206479Z","caller":"traceutil/trace.go:171","msg":"trace[1797380693] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"112.543727ms","start":"2026-04-10T05:17:06.093891Z","end":"2026-04-10T05:17:06.206435Z","steps":["trace[1797380693] 'process raft request' (duration: 112.410444ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:17:06.206614Z","caller":"traceutil/trace.go:171","msg":"trace[2070237475] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"112.615308ms","start":"2026-04-10T05:17:06.093968Z","end":"2026-04-10T05:17:06.206584Z","steps":["trace[2070237475] 'process raft request' (duration: 112.413124ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:17:06.941875Z","caller":"traceutil/trace.go:171","msg":"trace[1517172083] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5098; }","duration":"138.851373ms","start":"2026-04-10T05:17:06.802993Z","end":"2026-04-10T05:17:06.941845Z","steps":["trace[1517172083] 'read index received' (duration: 138.746782ms)","trace[1517172083] 'applied index is now lower than readState.Index' (duration: 103.451µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T05:17:06.941964Z","caller":"traceutil/trace.go:171","msg":"trace[529954646] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"221.127108ms","start":"2026-04-10T05:17:06.720802Z","end":"2026-04-10T05:17:06.941929Z","steps":["trace[529954646] 'process raft request' (duration: 220.829894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:17:06.942088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.075647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T05:17:06.94213Z","caller":"traceutil/trace.go:171","msg":"trace[103762879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4917; }","duration":"139.177168ms","start":"2026-04-10T05:17:06.802938Z","end":"2026-04-10T05:17:06.942115Z","steps":["trace[103762879] 'agreement among raft nodes before linearized reading' (duration: 139.092757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T05:17:06.955471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.339056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-10T05:17:06.955547Z","caller":"traceutil/trace.go:171","msg":"trace[565368421] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4918; }","duration":"123.424258ms","start":"2026-04-10T05:17:06.832105Z","end":"2026-04-10T05:17:06.955529Z","steps":["trace[565368421] 'agreement among raft nodes before linearized reading' (duration: 123.210655ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:17:10.469424Z","caller":"traceutil/trace.go:171","msg":"trace[1141045144] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"132.495411ms","start":"2026-04-10T05:17:10.336896Z","end":"2026-04-10T05:17:10.469391Z","steps":["trace[1141045144] 'process raft request' (duration: 132.337349ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:17:10.473601Z","caller":"traceutil/trace.go:171","msg":"trace[1260241400] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"130.624535ms","start":"2026-04-10T05:17:10.342951Z","end":"2026-04-10T05:17:10.473575Z","steps":["trace[1260241400] 'process raft request' (duration: 130.483023ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:18:42.675969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2935} {"level":"info","ts":"2026-04-10T05:18:42.804992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2935,"took":"127.167773ms","hash":395274624,"current-db-size-bytes":53702656,"current-db-size":"54 MB","current-db-size-in-use-bytes":29679616,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-10T05:18:42.805081Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":395274624,"revision":2935,"compact-revision":-1} {"level":"info","ts":"2026-04-10T05:21:09.635831Z","caller":"traceutil/trace.go:171","msg":"trace[149263583] transaction","detail":"{read_only:false; response_revision:6675; number_of_response:1; }","duration":"168.792537ms","start":"2026-04-10T05:21:09.467013Z","end":"2026-04-10T05:21:09.635805Z","steps":["trace[149263583] 'process raft request' (duration: 168.18511ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:21:09.636822Z","caller":"traceutil/trace.go:171","msg":"trace[750626899] transaction","detail":"{read_only:false; response_revision:6674; number_of_response:1; }","duration":"223.438969ms","start":"2026-04-10T05:21:09.413355Z","end":"2026-04-10T05:21:09.636794Z","steps":["trace[750626899] 'process raft request' (duration: 131.486351ms)","trace[750626899] 'compare' (duration: 90.123278ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T05:21:10.064421Z","caller":"traceutil/trace.go:171","msg":"trace[1930113078] transaction","detail":"{read_only:false; response_revision:6677; number_of_response:1; }","duration":"102.930252ms","start":"2026-04-10T05:21:09.961457Z","end":"2026-04-10T05:21:10.064387Z","steps":["trace[1930113078] 'process raft request' (duration: 102.725969ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T05:23:42.683902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5675} {"level":"info","ts":"2026-04-10T05:23:42.787504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5675,"took":"102.556793ms","hash":2357446970,"current-db-size-bytes":53702656,"current-db-size":"54 MB","current-db-size-in-use-bytes":18288640,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-10T05:23:42.787571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2357446970,"revision":5675,"compact-revision":2935}