{"level":"warn","ts":"2026-07-07T07:35:14.700614Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T07:35:14.700748Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-07-07T07:35:14.700844Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T07:35:14.700864Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-07-07T07:35:14.700891Z","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-07-07T07:35:14.701821Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-07-07T07:35:14.701925Z","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://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-07-07T07:35:14.704486Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.283785ms"} {"level":"info","ts":"2026-07-07T07:35:14.711021Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-07-07T07:35:14.711155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T07:35:14.711189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-07-07T07:35:14.711209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T07:35:14.71122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-07-07T07:35:14.711271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-07-07T07:35:14.716386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T07:35:14.718205Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T07:35:14.721018Z","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-07-07T07:35:14.724856Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T07:35:14.725507Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T07:35:14.725627Z","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-07-07T07:35:14.72574Z","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-07-07T07:35:14.72575Z","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-07-07T07:35:14.727799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-07-07T07:35:14.728075Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-07-07T07:35:14.732502Z","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-07-07T07:35:14.732907Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T07:35:14.732984Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T07:35:14.73332Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-07-07T07:35:14.733363Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-07-07T07:35:15.414937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T07:35:15.415013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T07:35:15.415038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-07-07T07:35:15.415056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-07-07T07:35:15.415065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-07-07T07:35:15.41508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-07-07T07:35:15.415091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-07-07T07:35:15.420614Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T07:35:15.420668Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:35:15.420842Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T07:35:15.420909Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T07:35:15.421755Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:35:15.421866Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:35:15.421903Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:35:15.424042Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-07-07T07:35:15.42474Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T07:35:15.424787Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T07:35:15.424965Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T07:35:18.64646Z","caller":"traceutil/trace.go:171","msg":"trace[1782971196] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"205.754137ms","start":"2026-07-07T07:35:18.440681Z","end":"2026-07-07T07:35:18.646435Z","steps":["trace[1782971196] 'process raft request' (duration: 200.92023ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:25.26868Z","caller":"traceutil/trace.go:171","msg":"trace[1398240497] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"125.776764ms","start":"2026-07-07T07:35:25.142876Z","end":"2026-07-07T07:35:25.268653Z","steps":["trace[1398240497] 'process raft request' (duration: 64.794531ms)","trace[1398240497] 'compare' (duration: 60.85391ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:35:25.274463Z","caller":"traceutil/trace.go:171","msg":"trace[1561521487] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"129.163241ms","start":"2026-07-07T07:35:25.145277Z","end":"2026-07-07T07:35:25.27444Z","steps":["trace[1561521487] 'process raft request' (duration: 129.039298ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:29.016385Z","caller":"traceutil/trace.go:171","msg":"trace[114632387] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"139.612408ms","start":"2026-07-07T07:35:28.876747Z","end":"2026-07-07T07:35:29.01636Z","steps":["trace[114632387] 'process raft request' (duration: 139.484254ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:29.016554Z","caller":"traceutil/trace.go:171","msg":"trace[983524489] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"130.89537ms","start":"2026-07-07T07:35:28.885435Z","end":"2026-07-07T07:35:29.016331Z","steps":["trace[983524489] 'read index received' (duration: 130.716305ms)","trace[983524489] 'applied index is now lower than readState.Index' (duration: 178.565µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:35:29.016591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.125856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-07T07:35:29.016911Z","caller":"traceutil/trace.go:171","msg":"trace[1492209324] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:399; }","duration":"131.463616ms","start":"2026-07-07T07:35:28.885399Z","end":"2026-07-07T07:35:29.016862Z","steps":["trace[1492209324] 'agreement among raft nodes before linearized reading' (duration: 131.086835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:29.997117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.870964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:35:29.997213Z","caller":"traceutil/trace.go:171","msg":"trace[1252883947] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"881.281767ms","start":"2026-07-07T07:35:29.115919Z","end":"2026-07-07T07:35:29.997201Z","steps":["trace[1252883947] 'read index received' (duration: 302.780646ms)","trace[1252883947] 'applied index is now lower than readState.Index' (duration: 578.499331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:35:29.997333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.41814ms","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-07-07T07:35:29.997351Z","caller":"traceutil/trace.go:171","msg":"trace[696549930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"881.500773ms","start":"2026-07-07T07:35:29.115845Z","end":"2026-07-07T07:35:29.997345Z","steps":["trace[696549930] 'agreement among raft nodes before linearized reading' (duration: 881.41766ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:29.997306Z","caller":"traceutil/trace.go:171","msg":"trace[508292841] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"948.398884ms","start":"2026-07-07T07:35:29.048871Z","end":"2026-07-07T07:35:29.99727Z","steps":["trace[508292841] 'process raft request' (duration: 369.834001ms)","trace[508292841] 'compare' (duration: 576.640768ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:35:29.997373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:29.115826Z","time spent":"881.542243ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T07:35:29.99744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.744288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-07-07T07:35:29.99746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.489158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:35:29.997523Z","caller":"traceutil/trace.go:171","msg":"trace[1985823786] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:400; }","duration":"680.86494ms","start":"2026-07-07T07:35:29.316637Z","end":"2026-07-07T07:35:29.997502Z","steps":["trace[1985823786] 'agreement among raft nodes before linearized reading' (duration: 680.682885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:29.997533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:29.048832Z","time spent":"948.606979ms","remote":"127.0.0.1:49052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:35:29.997547Z","caller":"traceutil/trace.go:171","msg":"trace[754828882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"257.585361ms","start":"2026-07-07T07:35:29.739943Z","end":"2026-07-07T07:35:29.997529Z","steps":["trace[754828882] 'agreement among raft nodes before linearized reading' (duration: 257.448597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:29.99757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:29.316618Z","time spent":"680.942353ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-07T07:35:29.997747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.527227ms","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-07-07T07:35:29.997774Z","caller":"traceutil/trace.go:171","msg":"trace[1628066830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"581.575349ms","start":"2026-07-07T07:35:29.416189Z","end":"2026-07-07T07:35:29.997764Z","steps":["trace[1628066830] 'agreement among raft nodes before linearized reading' (duration: 581.531127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:29.997795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:29.416176Z","time spent":"581.614249ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T07:35:30.323527Z","caller":"traceutil/trace.go:171","msg":"trace[223981552] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"313.603733ms","start":"2026-07-07T07:35:30.009897Z","end":"2026-07-07T07:35:30.3235Z","steps":["trace[223981552] 'process raft request' (duration: 313.462869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:30.323653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:30.009878Z","time spent":"313.711406ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:35:30.326176Z","caller":"traceutil/trace.go:171","msg":"trace[12663448] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:415; }","duration":"315.96786ms","start":"2026-07-07T07:35:30.010169Z","end":"2026-07-07T07:35:30.326137Z","steps":["trace[12663448] 'read index received' (duration: 313.268633ms)","trace[12663448] 'applied index is now lower than readState.Index' (duration: 2.698407ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:35:30.326355Z","caller":"traceutil/trace.go:171","msg":"trace[1191055067] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"301.806447ms","start":"2026-07-07T07:35:30.024537Z","end":"2026-07-07T07:35:30.326344Z","steps":["trace[1191055067] 'process raft request' (duration: 301.5602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:30.32646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:30.02452Z","time spent":"301.864229ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:35:30.32646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.277749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-07T07:35:30.326512Z","caller":"traceutil/trace.go:171","msg":"trace[956574373] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:403; }","duration":"316.355812ms","start":"2026-07-07T07:35:30.010145Z","end":"2026-07-07T07:35:30.326501Z","steps":["trace[956574373] 'agreement among raft nodes before linearized reading' (duration: 316.253549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:30.32654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:30.010137Z","time spent":"316.397143ms","remote":"127.0.0.1:49288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-07T07:35:30.326649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.068188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-07T07:35:30.326779Z","caller":"traceutil/trace.go:171","msg":"trace[607197985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"256.278154ms","start":"2026-07-07T07:35:30.070484Z","end":"2026-07-07T07:35:30.326762Z","steps":["trace[607197985] 'agreement among raft nodes before linearized reading' (duration: 255.989606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:30.32684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.129354ms","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-07-07T07:35:30.32688Z","caller":"traceutil/trace.go:171","msg":"trace[1541006401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"272.183546ms","start":"2026-07-07T07:35:30.05468Z","end":"2026-07-07T07:35:30.326864Z","steps":["trace[1541006401] 'agreement among raft nodes before linearized reading' (duration: 272.124744ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:30.326347Z","caller":"traceutil/trace.go:171","msg":"trace[199034468] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"316.226527ms","start":"2026-07-07T07:35:30.010048Z","end":"2026-07-07T07:35:30.326275Z","steps":["trace[199034468] 'process raft request' (duration: 315.931549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:30.327273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:30.009992Z","time spent":"317.124253ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:35:56.859451Z","caller":"traceutil/trace.go:171","msg":"trace[981451479] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"433.450859ms","start":"2026-07-07T07:35:56.425976Z","end":"2026-07-07T07:35:56.859427Z","steps":["trace[981451479] 'process raft request' (duration: 394.43478ms)","trace[981451479] 'compare' (duration: 38.660799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:35:56.859563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.425959Z","time spent":"433.554442ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:35:56.860385Z","caller":"traceutil/trace.go:171","msg":"trace[1319863845] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"429.001713ms","start":"2026-07-07T07:35:56.431358Z","end":"2026-07-07T07:35:56.86036Z","steps":["trace[1319863845] 'process raft request' (duration: 428.935661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.860452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.431348Z","time spent":"429.077335ms","remote":"127.0.0.1:49550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:35:56.860453Z","caller":"traceutil/trace.go:171","msg":"trace[1263571530] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"428.318674ms","start":"2026-07-07T07:35:56.432101Z","end":"2026-07-07T07:35:56.86042Z","steps":["trace[1263571530] 'process raft request' (duration: 428.245522ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:56.860429Z","caller":"traceutil/trace.go:171","msg":"trace[94935747] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"428.958641ms","start":"2026-07-07T07:35:56.431444Z","end":"2026-07-07T07:35:56.860402Z","steps":["trace[94935747] 'process raft request' (duration: 428.876489ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:56.860617Z","caller":"traceutil/trace.go:171","msg":"trace[1390886390] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"421.623663ms","start":"2026-07-07T07:35:56.438978Z","end":"2026-07-07T07:35:56.860602Z","steps":["trace[1390886390] 'process raft request' (duration: 421.54026ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:56.860464Z","caller":"traceutil/trace.go:171","msg":"trace[1753225176] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"432.147782ms","start":"2026-07-07T07:35:56.428288Z","end":"2026-07-07T07:35:56.860436Z","steps":["trace[1753225176] 'process raft request' (duration: 431.854134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.861781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.428272Z","time spent":"433.451009ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T07:35:56.861904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.438963Z","time spent":"422.784505ms","remote":"127.0.0.1:49550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T07:35:56.862853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.432071Z","time spent":"428.453207ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:35:56.863119Z","caller":"traceutil/trace.go:171","msg":"trace[1536478634] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"424.812973ms","start":"2026-07-07T07:35:56.438199Z","end":"2026-07-07T07:35:56.863012Z","steps":["trace[1536478634] 'process raft request' (duration: 422.185829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.863206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.438167Z","time spent":"424.990048ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:35:56.863574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.431439Z","time spent":"429.133756ms","remote":"127.0.0.1:49550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:35:56.863763Z","caller":"traceutil/trace.go:171","msg":"trace[1260626869] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"414.649875ms","start":"2026-07-07T07:35:56.446067Z","end":"2026-07-07T07:35:56.860717Z","steps":["trace[1260626869] 'process raft request' (duration: 414.500961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.86384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.446047Z","time spent":"417.752263ms","remote":"127.0.0.1:49550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:35:56.864036Z","caller":"traceutil/trace.go:171","msg":"trace[1868146273] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"416.704153ms","start":"2026-07-07T07:35:56.447034Z","end":"2026-07-07T07:35:56.863738Z","steps":["trace[1868146273] 'process raft request' (duration: 414.918462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.864292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.447027Z","time spent":"417.181147ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:35:56.865641Z","caller":"traceutil/trace.go:171","msg":"trace[860533457] transaction","detail":"{read_only:false; number_of_response:1; response_revision:546; }","duration":"413.08764ms","start":"2026-07-07T07:35:56.452538Z","end":"2026-07-07T07:35:56.865626Z","steps":["trace[860533457] 'process raft request' (duration: 410.640341ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:56.865865Z","caller":"traceutil/trace.go:171","msg":"trace[589696794] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:556; }","duration":"405.636228ms","start":"2026-07-07T07:35:56.460188Z","end":"2026-07-07T07:35:56.865824Z","steps":["trace[589696794] 'read index received' (duration: 360.32365ms)","trace[589696794] 'applied index is now lower than readState.Index' (duration: 45.311628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:35:56.866008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.45253Z","time spent":"413.42829ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7733,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:35:56.866521Z","caller":"traceutil/trace.go:171","msg":"trace[1660037183] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"384.827787ms","start":"2026-07-07T07:35:56.481157Z","end":"2026-07-07T07:35:56.865984Z","steps":["trace[1660037183] 'process raft request' (duration: 383.801467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.866645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.481132Z","time spent":"385.452285ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:35:56.866718Z","caller":"traceutil/trace.go:171","msg":"trace[493743018] transaction","detail":"{read_only:false; number_of_response:1; response_revision:546; }","duration":"413.298316ms","start":"2026-07-07T07:35:56.453403Z","end":"2026-07-07T07:35:56.866702Z","steps":["trace[493743018] 'process raft request' (duration: 409.951121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.86683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.453395Z","time spent":"413.388219ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10528,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:35:56.8697Z","caller":"traceutil/trace.go:171","msg":"trace[2004219466] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"245.795206ms","start":"2026-07-07T07:35:56.620197Z","end":"2026-07-07T07:35:56.865992Z","steps":["trace[2004219466] 'process raft request' (duration: 245.548079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.870068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.142097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-07T07:35:56.870101Z","caller":"traceutil/trace.go:171","msg":"trace[788967246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"403.215869ms","start":"2026-07-07T07:35:56.466874Z","end":"2026-07-07T07:35:56.87009Z","steps":["trace[788967246] 'agreement among raft nodes before linearized reading' (duration: 400.052249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.870123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.466862Z","time spent":"403.255261ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T07:35:56.870186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.401765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:35:56.870214Z","caller":"traceutil/trace.go:171","msg":"trace[602540746] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"409.390785ms","start":"2026-07-07T07:35:56.457517Z","end":"2026-07-07T07:35:56.866907Z","steps":["trace[602540746] 'process raft request' (duration: 406.177464ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:56.870256Z","caller":"traceutil/trace.go:171","msg":"trace[1109830308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"137.508428ms","start":"2026-07-07T07:35:56.732731Z","end":"2026-07-07T07:35:56.870239Z","steps":["trace[1109830308] 'agreement among raft nodes before linearized reading' (duration: 134.201884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.87026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.023039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T07:35:56.870308Z","caller":"traceutil/trace.go:171","msg":"trace[1657826279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"400.08316ms","start":"2026-07-07T07:35:56.470218Z","end":"2026-07-07T07:35:56.870301Z","steps":["trace[1657826279] 'agreement among raft nodes before linearized reading' (duration: 396.729225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.870327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.47021Z","time spent":"400.113412ms","remote":"127.0.0.1:49284","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-07-07T07:35:56.870337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.457498Z","time spent":"412.772041ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:35:56.870385Z","caller":"traceutil/trace.go:171","msg":"trace[518804806] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"419.196074ms","start":"2026-07-07T07:35:56.446954Z","end":"2026-07-07T07:35:56.86615Z","steps":["trace[518804806] 'process raft request' (duration: 414.548652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.870482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.446946Z","time spent":"423.490556ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T07:35:56.870533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.319762ms","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-07-07T07:35:56.870583Z","caller":"traceutil/trace.go:171","msg":"trace[2040981375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"410.428205ms","start":"2026-07-07T07:35:56.460144Z","end":"2026-07-07T07:35:56.870572Z","steps":["trace[2040981375] 'agreement among raft nodes before linearized reading' (duration: 405.852175ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:56.870613Z","caller":"traceutil/trace.go:171","msg":"trace[1673415412] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"418.450613ms","start":"2026-07-07T07:35:56.448501Z","end":"2026-07-07T07:35:56.866952Z","steps":["trace[1673415412] 'process raft request' (duration: 413.935545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.870622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.460129Z","time spent":"410.486146ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T07:35:56.870661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.448494Z","time spent":"422.142528ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:35:56.870547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.269959ms","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-07-07T07:35:56.872769Z","caller":"traceutil/trace.go:171","msg":"trace[1677799739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"400.492452ms","start":"2026-07-07T07:35:56.472233Z","end":"2026-07-07T07:35:56.872725Z","steps":["trace[1677799739] 'agreement among raft nodes before linearized reading' (duration: 394.701818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:35:56.872838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:35:56.47221Z","time spent":"400.596596ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T07:36:04.447491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.944312ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T07:36:05.01593Z","caller":"traceutil/trace.go:171","msg":"trace[1170128305] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"101.575951ms","start":"2026-07-07T07:36:04.914316Z","end":"2026-07-07T07:36:05.015892Z","steps":["trace[1170128305] 'process raft request' (duration: 101.303023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:05.334451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.401751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:36:05.334999Z","caller":"traceutil/trace.go:171","msg":"trace[1160143598] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"408.516977ms","start":"2026-07-07T07:36:04.926464Z","end":"2026-07-07T07:36:05.334981Z","steps":["trace[1160143598] 'read index received' (duration: 89.20094ms)","trace[1160143598] 'applied index is now lower than readState.Index' (duration: 319.314617ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:36:05.33506Z","caller":"traceutil/trace.go:171","msg":"trace[618585871] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"419.895009ms","start":"2026-07-07T07:36:04.915139Z","end":"2026-07-07T07:36:05.335034Z","steps":["trace[618585871] 'process raft request' (duration: 228.81823ms)","trace[618585871] 'compare' (duration: 190.263376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:36:05.335482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:04.915106Z","time spent":"420.322221ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:36:05.335333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.858076ms","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-07-07T07:36:05.335662Z","caller":"traceutil/trace.go:171","msg":"trace[25986836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"409.211686ms","start":"2026-07-07T07:36:04.926413Z","end":"2026-07-07T07:36:05.335624Z","steps":["trace[25986836] 'agreement among raft nodes before linearized reading' (duration: 408.687051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:05.335776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.72883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qw58j\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-07T07:36:05.335741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:04.92639Z","time spent":"409.33748ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-07T07:36:05.335865Z","caller":"traceutil/trace.go:171","msg":"trace[536211056] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qw58j; range_end:; response_count:1; response_revision:649; }","duration":"373.881094ms","start":"2026-07-07T07:36:04.961963Z","end":"2026-07-07T07:36:05.335845Z","steps":["trace[536211056] 'agreement among raft nodes before linearized reading' (duration: 373.693079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:05.336211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:04.961934Z","time spent":"374.266435ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-qw58j\" "} {"level":"warn","ts":"2026-07-07T07:36:05.336666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.005569ms","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-07-07T07:36:05.336775Z","caller":"traceutil/trace.go:171","msg":"trace[73970317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"410.177814ms","start":"2026-07-07T07:36:04.926583Z","end":"2026-07-07T07:36:05.336761Z","steps":["trace[73970317] 'agreement among raft nodes before linearized reading' (duration: 408.920389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:05.336877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:04.92655Z","time spent":"410.315708ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T07:36:05.576961Z","caller":"traceutil/trace.go:171","msg":"trace[516119162] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"237.210358ms","start":"2026-07-07T07:36:05.339734Z","end":"2026-07-07T07:36:05.576945Z","steps":["trace[516119162] 'process raft request' (duration: 237.060733ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:36:05.577089Z","caller":"traceutil/trace.go:171","msg":"trace[2095439953] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"228.278665ms","start":"2026-07-07T07:36:05.348806Z","end":"2026-07-07T07:36:05.577085Z","steps":["trace[2095439953] 'process raft request' (duration: 228.1027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:06.55169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.294296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:36:06.551763Z","caller":"traceutil/trace.go:171","msg":"trace[813468852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"819.40462ms","start":"2026-07-07T07:36:05.732341Z","end":"2026-07-07T07:36:06.551746Z","steps":["trace[813468852] 'range keys from in-memory index tree' (duration: 819.221094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:06.551798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:05.732324Z","time spent":"819.465592ms","remote":"127.0.0.1:49024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T07:36:22.206397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.570991ms","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-07-07T07:36:22.20647Z","caller":"traceutil/trace.go:171","msg":"trace[2078235712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"767.688284ms","start":"2026-07-07T07:36:21.438767Z","end":"2026-07-07T07:36:22.206455Z","steps":["trace[2078235712] 'range keys from in-memory index tree' (duration: 767.391715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:22.206506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.826133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:36:22.206623Z","caller":"traceutil/trace.go:171","msg":"trace[1526104809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"472.043999ms","start":"2026-07-07T07:36:21.734543Z","end":"2026-07-07T07:36:22.206587Z","steps":["trace[1526104809] 'range keys from in-memory index tree' (duration: 471.704089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:22.206674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:21.734524Z","time spent":"472.138741ms","remote":"127.0.0.1:49024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T07:36:22.206764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.515035ms","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-07-07T07:36:22.206778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.81151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-07T07:36:22.206819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:21.438751Z","time spent":"767.794227ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-07T07:36:22.206827Z","caller":"traceutil/trace.go:171","msg":"trace[631424164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"356.904753ms","start":"2026-07-07T07:36:21.849912Z","end":"2026-07-07T07:36:22.206817Z","steps":["trace[631424164] 'range keys from in-memory index tree' (duration: 356.662006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:22.206757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.051615ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T07:36:22.206877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:21.849889Z","time spent":"356.964564ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-07T07:36:22.206886Z","caller":"traceutil/trace.go:171","msg":"trace[623166064] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:809; }","duration":"224.191909ms","start":"2026-07-07T07:36:21.982672Z","end":"2026-07-07T07:36:22.206864Z","steps":["trace[623166064] 'range keys from in-memory index tree' (duration: 224.034894ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:36:22.206825Z","caller":"traceutil/trace.go:171","msg":"trace[575855808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"549.608768ms","start":"2026-07-07T07:36:21.657199Z","end":"2026-07-07T07:36:22.206808Z","steps":["trace[575855808] 'range keys from in-memory index tree' (duration: 549.357051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:22.207254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:21.657179Z","time spent":"550.059521ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T07:36:22.210846Z","caller":"traceutil/trace.go:171","msg":"trace[248592846] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"133.763854ms","start":"2026-07-07T07:36:22.077056Z","end":"2026-07-07T07:36:22.21082Z","steps":["trace[248592846] 'process raft request' (duration: 133.61906ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:36:22.219255Z","caller":"traceutil/trace.go:171","msg":"trace[398349051] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"123.901144ms","start":"2026-07-07T07:36:22.09534Z","end":"2026-07-07T07:36:22.219241Z","steps":["trace[398349051] 'process raft request' (duration: 123.704358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.366846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.241769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:36:58.366942Z","caller":"traceutil/trace.go:171","msg":"trace[527634578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"634.386293ms","start":"2026-07-07T07:36:57.732533Z","end":"2026-07-07T07:36:58.36692Z","steps":["trace[527634578] 'range keys from in-memory index tree' (duration: 634.164986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.366978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.437863ms","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-07-07T07:36:58.36699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:57.732517Z","time spent":"634.462105ms","remote":"127.0.0.1:49024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T07:36:58.367021Z","caller":"traceutil/trace.go:171","msg":"trace[361797235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"794.520815ms","start":"2026-07-07T07:36:57.57249Z","end":"2026-07-07T07:36:58.367011Z","steps":["trace[361797235] 'range keys from in-memory index tree' (duration: 794.281679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.367046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:57.572469Z","time spent":"794.570787ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-07T07:36:58.747943Z","caller":"traceutil/trace.go:171","msg":"trace[2060045952] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"353.026883ms","start":"2026-07-07T07:36:58.394883Z","end":"2026-07-07T07:36:58.74791Z","steps":["trace[2060045952] 'read index received' (duration: 352.815437ms)","trace[2060045952] 'applied index is now lower than readState.Index' (duration: 210.516µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:36:58.748012Z","caller":"traceutil/trace.go:171","msg":"trace[1541136312] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"377.500107ms","start":"2026-07-07T07:36:58.370481Z","end":"2026-07-07T07:36:58.747981Z","steps":["trace[1541136312] 'process raft request' (duration: 377.276871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.748147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.370463Z","time spent":"377.61096ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:36:58.748205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.29814ms","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-07-07T07:36:58.748246Z","caller":"traceutil/trace.go:171","msg":"trace[1700512673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"353.390383ms","start":"2026-07-07T07:36:58.394842Z","end":"2026-07-07T07:36:58.748233Z","steps":["trace[1700512673] 'agreement among raft nodes before linearized reading' (duration: 353.206238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.748282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.394827Z","time spent":"353.446815ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T07:36:58.748272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.94615ms","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-07-07T07:36:58.748338Z","caller":"traceutil/trace.go:171","msg":"trace[1335093497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"353.021443ms","start":"2026-07-07T07:36:58.395302Z","end":"2026-07-07T07:36:58.748323Z","steps":["trace[1335093497] 'agreement among raft nodes before linearized reading' (duration: 352.853448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.748366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.395274Z","time spent":"353.086135ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T07:36:58.748521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.265077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-07T07:36:58.748562Z","caller":"traceutil/trace.go:171","msg":"trace[1445241908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"349.324338ms","start":"2026-07-07T07:36:58.399227Z","end":"2026-07-07T07:36:58.748551Z","steps":["trace[1445241908] 'agreement among raft nodes before linearized reading' (duration: 349.213855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.748593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.399181Z","time spent":"349.405011ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-07T07:37:19.822975Z","caller":"traceutil/trace.go:171","msg":"trace[1871218163] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"408.275433ms","start":"2026-07-07T07:37:19.414673Z","end":"2026-07-07T07:37:19.822949Z","steps":["trace[1871218163] 'process raft request' (duration: 408.130428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:19.823118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:19.414659Z","time spent":"408.389446ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:37:19.834396Z","caller":"traceutil/trace.go:171","msg":"trace[2019236560] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"414.818979ms","start":"2026-07-07T07:37:19.419557Z","end":"2026-07-07T07:37:19.834376Z","steps":["trace[2019236560] 'process raft request' (duration: 414.665154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:19.834523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:19.419543Z","time spent":"414.913731ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:37:19.834558Z","caller":"traceutil/trace.go:171","msg":"trace[1129156711] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1142; }","duration":"100.887842ms","start":"2026-07-07T07:37:19.733652Z","end":"2026-07-07T07:37:19.83454Z","steps":["trace[1129156711] 'read index received' (duration: 89.202731ms)","trace[1129156711] 'applied index is now lower than readState.Index' (duration: 11.684271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:19.834651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.000695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:37:19.834678Z","caller":"traceutil/trace.go:171","msg":"trace[339123785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1100; }","duration":"101.098018ms","start":"2026-07-07T07:37:19.733572Z","end":"2026-07-07T07:37:19.83467Z","steps":["trace[339123785] 'agreement among raft nodes before linearized reading' (duration: 101.029966ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:19.83482Z","caller":"traceutil/trace.go:171","msg":"trace[1431230637] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"292.806537ms","start":"2026-07-07T07:37:19.542007Z","end":"2026-07-07T07:37:19.834813Z","steps":["trace[1431230637] 'process raft request' (duration: 292.338644ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:21.124401Z","caller":"traceutil/trace.go:171","msg":"trace[448441961] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"275.186627ms","start":"2026-07-07T07:37:20.849187Z","end":"2026-07-07T07:37:21.124373Z","steps":["trace[448441961] 'read index received' (duration: 274.917279ms)","trace[448441961] 'applied index is now lower than readState.Index' (duration: 268.338µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:21.124859Z","caller":"traceutil/trace.go:171","msg":"trace[1746683048] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"276.957727ms","start":"2026-07-07T07:37:20.847889Z","end":"2026-07-07T07:37:21.124847Z","steps":["trace[1746683048] 'process raft request' (duration: 276.33505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:21.125085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.901549ms","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-07-07T07:37:21.125162Z","caller":"traceutil/trace.go:171","msg":"trace[1776643743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"269.027083ms","start":"2026-07-07T07:37:20.856117Z","end":"2026-07-07T07:37:21.125144Z","steps":["trace[1776643743] 'agreement among raft nodes before linearized reading' (duration: 268.781046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:21.125206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.357889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-07T07:37:21.125356Z","caller":"traceutil/trace.go:171","msg":"trace[2144301854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"271.570665ms","start":"2026-07-07T07:37:20.853753Z","end":"2026-07-07T07:37:21.125324Z","steps":["trace[2144301854] 'agreement among raft nodes before linearized reading' (duration: 271.136282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:21.126118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.921597ms","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-07-07T07:37:21.126198Z","caller":"traceutil/trace.go:171","msg":"trace[322284903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"277.04401ms","start":"2026-07-07T07:37:20.849136Z","end":"2026-07-07T07:37:21.12618Z","steps":["trace[322284903] 'agreement among raft nodes before linearized reading' (duration: 276.841054ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:21.244085Z","caller":"traceutil/trace.go:171","msg":"trace[797062697] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"113.207282ms","start":"2026-07-07T07:37:21.130857Z","end":"2026-07-07T07:37:21.244064Z","steps":["trace[797062697] 'process raft request' (duration: 113.16825ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:21.244229Z","caller":"traceutil/trace.go:171","msg":"trace[2046791599] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"113.881631ms","start":"2026-07-07T07:37:21.130334Z","end":"2026-07-07T07:37:21.244215Z","steps":["trace[2046791599] 'process raft request' (duration: 113.601143ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:23.346142Z","caller":"traceutil/trace.go:171","msg":"trace[1321745747] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"120.262772ms","start":"2026-07-07T07:37:23.225853Z","end":"2026-07-07T07:37:23.346116Z","steps":["trace[1321745747] 'read index received' (duration: 120.070006ms)","trace[1321745747] 'applied index is now lower than readState.Index' (duration: 191.926µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:23.346355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.490928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-07T07:37:23.346439Z","caller":"traceutil/trace.go:171","msg":"trace[1497876269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"120.612072ms","start":"2026-07-07T07:37:23.225814Z","end":"2026-07-07T07:37:23.346426Z","steps":["trace[1497876269] 'agreement among raft nodes before linearized reading' (duration: 120.414626ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:23.346275Z","caller":"traceutil/trace.go:171","msg":"trace[1420807140] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"212.866ms","start":"2026-07-07T07:37:23.133346Z","end":"2026-07-07T07:37:23.346212Z","steps":["trace[1420807140] 'process raft request' (duration: 212.634783ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:23.701463Z","caller":"traceutil/trace.go:171","msg":"trace[1186659331] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"351.155862ms","start":"2026-07-07T07:37:23.35028Z","end":"2026-07-07T07:37:23.701436Z","steps":["trace[1186659331] 'process raft request' (duration: 350.978037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:23.701652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:23.350246Z","time spent":"351.290706ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:37:23.730743Z","caller":"traceutil/trace.go:171","msg":"trace[1428930767] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1152; }","duration":"376.368647ms","start":"2026-07-07T07:37:23.354354Z","end":"2026-07-07T07:37:23.730723Z","steps":["trace[1428930767] 'read index received' (duration: 346.931802ms)","trace[1428930767] 'applied index is now lower than readState.Index' (duration: 29.435625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:23.730869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.494831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:37:23.730954Z","caller":"traceutil/trace.go:171","msg":"trace[2094414684] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1111; }","duration":"376.624134ms","start":"2026-07-07T07:37:23.354314Z","end":"2026-07-07T07:37:23.730938Z","steps":["trace[2094414684] 'agreement among raft nodes before linearized reading' (duration: 376.493421ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:23.730978Z","caller":"traceutil/trace.go:171","msg":"trace[488684795] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"380.264508ms","start":"2026-07-07T07:37:23.350667Z","end":"2026-07-07T07:37:23.730931Z","steps":["trace[488684795] 'process raft request' (duration: 379.97286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:23.731009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:23.354298Z","time spent":"376.694657ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-07-07T07:37:23.731083Z","caller":"traceutil/trace.go:171","msg":"trace[1592023403] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"380.452963ms","start":"2026-07-07T07:37:23.350589Z","end":"2026-07-07T07:37:23.731042Z","steps":["trace[1592023403] 'process raft request' (duration: 379.935979ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:23.731218Z","caller":"traceutil/trace.go:171","msg":"trace[1168732150] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"272.676316ms","start":"2026-07-07T07:37:23.458501Z","end":"2026-07-07T07:37:23.731177Z","steps":["trace[1168732150] 'process raft request' (duration: 272.190132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:23.731153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:23.350646Z","time spent":"380.359751ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:37:23.731317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:23.350579Z","time spent":"380.649368ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:37:37.18128Z","caller":"traceutil/trace.go:171","msg":"trace[1776613108] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"103.066754ms","start":"2026-07-07T07:37:37.07819Z","end":"2026-07-07T07:37:37.181257Z","steps":["trace[1776613108] 'process raft request' (duration: 103.007722ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.181374Z","caller":"traceutil/trace.go:171","msg":"trace[1975666123] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"104.838364ms","start":"2026-07-07T07:37:37.076508Z","end":"2026-07-07T07:37:37.181347Z","steps":["trace[1975666123] 'process raft request' (duration: 104.562136ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.329929Z","caller":"traceutil/trace.go:171","msg":"trace[1177918050] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"128.65282ms","start":"2026-07-07T07:37:37.201245Z","end":"2026-07-07T07:37:37.329897Z","steps":["trace[1177918050] 'process raft request' (duration: 128.562567ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.330375Z","caller":"traceutil/trace.go:171","msg":"trace[131245949] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"136.909724ms","start":"2026-07-07T07:37:37.193453Z","end":"2026-07-07T07:37:37.330362Z","steps":["trace[131245949] 'process raft request' (duration: 136.254975ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.330367Z","caller":"traceutil/trace.go:171","msg":"trace[1843594084] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"139.085636ms","start":"2026-07-07T07:37:37.191238Z","end":"2026-07-07T07:37:37.330323Z","steps":["trace[1843594084] 'process raft request' (duration: 138.248812ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.3861Z","caller":"traceutil/trace.go:171","msg":"trace[1101797390] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"143.235043ms","start":"2026-07-07T07:37:37.242838Z","end":"2026-07-07T07:37:37.386073Z","steps":["trace[1101797390] 'process raft request' (duration: 143.031808ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.386108Z","caller":"traceutil/trace.go:171","msg":"trace[167367476] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"138.901251ms","start":"2026-07-07T07:37:37.247187Z","end":"2026-07-07T07:37:37.386088Z","steps":["trace[167367476] 'process raft request' (duration: 138.821059ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.386352Z","caller":"traceutil/trace.go:171","msg":"trace[1075031160] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"143.45252ms","start":"2026-07-07T07:37:37.242879Z","end":"2026-07-07T07:37:37.386332Z","steps":["trace[1075031160] 'process raft request' (duration: 143.10029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:37.386497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.164453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-jmlhf\" ","response":"range_response_count:1 size:9695"} {"level":"info","ts":"2026-07-07T07:37:37.386643Z","caller":"traceutil/trace.go:171","msg":"trace[82594941] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1232; }","duration":"135.742312ms","start":"2026-07-07T07:37:37.250301Z","end":"2026-07-07T07:37:37.386043Z","steps":["trace[82594941] 'read index received' (duration: 79.237489ms)","trace[82594941] 'applied index is now lower than readState.Index' (duration: 56.503773ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:37.38664Z","caller":"traceutil/trace.go:171","msg":"trace[1242384893] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-jmlhf; range_end:; response_count:1; response_revision:1188; }","duration":"136.355219ms","start":"2026-07-07T07:37:37.250256Z","end":"2026-07-07T07:37:37.386611Z","steps":["trace[1242384893] 'agreement among raft nodes before linearized reading' (duration: 135.982668ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.440444Z","caller":"traceutil/trace.go:171","msg":"trace[1917838887] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"104.534576ms","start":"2026-07-07T07:37:37.335893Z","end":"2026-07-07T07:37:37.440428Z","steps":["trace[1917838887] 'process raft request' (duration: 103.135836ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.440712Z","caller":"traceutil/trace.go:171","msg":"trace[542598286] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"103.707612ms","start":"2026-07-07T07:37:37.336995Z","end":"2026-07-07T07:37:37.440702Z","steps":["trace[542598286] 'process raft request' (duration: 103.402504ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:37.440852Z","caller":"traceutil/trace.go:171","msg":"trace[2025713595] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"100.285705ms","start":"2026-07-07T07:37:37.340551Z","end":"2026-07-07T07:37:37.440837Z","steps":["trace[2025713595] 'process raft request' (duration: 100.09307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:39.448652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.256311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:37:39.448734Z","caller":"traceutil/trace.go:171","msg":"trace[355043542] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"434.494157ms","start":"2026-07-07T07:37:39.01422Z","end":"2026-07-07T07:37:39.448714Z","steps":["trace[355043542] 'process raft request' (duration: 320.121082ms)","trace[355043542] 'compare' (duration: 114.137958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:39.448769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:39.014199Z","time spent":"434.555268ms","remote":"127.0.0.1:49052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:37:41.016497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.539695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:37:41.016685Z","caller":"traceutil/trace.go:171","msg":"trace[561619269] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1263; }","duration":"1.013917355s","start":"2026-07-07T07:37:40.002755Z","end":"2026-07-07T07:37:41.016673Z","steps":["trace[561619269] 'read index received' (duration: 448.112103ms)","trace[561619269] 'applied index is now lower than readState.Index' (duration: 565.804622ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:41.016695Z","caller":"traceutil/trace.go:171","msg":"trace[505962776] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.204809081s","start":"2026-07-07T07:37:39.811856Z","end":"2026-07-07T07:37:41.016665Z","steps":["trace[505962776] 'process raft request' (duration: 639.0223ms)","trace[505962776] 'compare' (duration: 565.40282ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:41.01684Z","caller":"traceutil/trace.go:171","msg":"trace[938922114] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.204103511s","start":"2026-07-07T07:37:39.812729Z","end":"2026-07-07T07:37:41.016832Z","steps":["trace[938922114] 'process raft request' (duration: 1.203853114s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:41.016838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:39.811842Z","time spent":"1.204911214s","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:37:41.016885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:39.812706Z","time spent":"1.204155202s","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:37:41.016963Z","caller":"traceutil/trace.go:171","msg":"trace[2008350801] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.040284013s","start":"2026-07-07T07:37:39.976673Z","end":"2026-07-07T07:37:41.016957Z","steps":["trace[2008350801] 'process raft request' (duration: 1.039974105s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:41.016995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:39.976655Z","time spent":"1.040321364s","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:37:41.0171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014357737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T07:37:41.017118Z","caller":"traceutil/trace.go:171","msg":"trace[897497250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"1.01442702s","start":"2026-07-07T07:37:40.002685Z","end":"2026-07-07T07:37:41.017112Z","steps":["trace[897497250] 'agreement among raft nodes before linearized reading' (duration: 1.014349468s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:41.017138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:40.002665Z","time spent":"1.01446864s","remote":"127.0.0.1:49284","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-07-07T07:37:41.038781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.792537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:37:41.038846Z","caller":"traceutil/trace.go:171","msg":"trace[193736943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"304.91384ms","start":"2026-07-07T07:37:40.73392Z","end":"2026-07-07T07:37:41.038834Z","steps":["trace[193736943] 'agreement among raft nodes before linearized reading' (duration: 304.792557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:41.038875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:40.733898Z","time spent":"304.968242ms","remote":"127.0.0.1:49024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T07:37:41.038789Z","caller":"traceutil/trace.go:171","msg":"trace[127005992] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"551.205718ms","start":"2026-07-07T07:37:40.48756Z","end":"2026-07-07T07:37:41.038766Z","steps":["trace[127005992] 'process raft request' (duration: 551.042373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:41.039014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:40.487535Z","time spent":"551.408153ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:37:55.352594Z","caller":"traceutil/trace.go:171","msg":"trace[141444883] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"145.149138ms","start":"2026-07-07T07:37:55.20741Z","end":"2026-07-07T07:37:55.352559Z","steps":["trace[141444883] 'process raft request' (duration: 145.031825ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:55.681802Z","caller":"traceutil/trace.go:171","msg":"trace[86135776] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"116.267528ms","start":"2026-07-07T07:37:55.565511Z","end":"2026-07-07T07:37:55.681779Z","steps":["trace[86135776] 'process raft request' (duration: 116.126214ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:59.190446Z","caller":"traceutil/trace.go:171","msg":"trace[1641977284] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"146.030993ms","start":"2026-07-07T07:37:59.044393Z","end":"2026-07-07T07:37:59.190424Z","steps":["trace[1641977284] 'process raft request' (duration: 99.353499ms)","trace[1641977284] 'compare' (duration: 46.588542ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:59.19971Z","caller":"traceutil/trace.go:171","msg":"trace[1431194946] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"100.073149ms","start":"2026-07-07T07:37:59.099612Z","end":"2026-07-07T07:37:59.199685Z","steps":["trace[1431194946] 'read index received' (duration: 44.146722ms)","trace[1431194946] 'applied index is now lower than readState.Index' (duration: 55.925327ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:59.19995Z","caller":"traceutil/trace.go:171","msg":"trace[130769850] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"104.902526ms","start":"2026-07-07T07:37:59.095021Z","end":"2026-07-07T07:37:59.199924Z","steps":["trace[130769850] 'process raft request' (duration: 104.558766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:59.200106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.47462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-07T07:37:59.200345Z","caller":"traceutil/trace.go:171","msg":"trace[157847883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"100.768589ms","start":"2026-07-07T07:37:59.099559Z","end":"2026-07-07T07:37:59.200327Z","steps":["trace[157847883] 'agreement among raft nodes before linearized reading' (duration: 100.378978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:59.948765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.459983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:37:59.948867Z","caller":"traceutil/trace.go:171","msg":"trace[2039670508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"215.619757ms","start":"2026-07-07T07:37:59.733225Z","end":"2026-07-07T07:37:59.948845Z","steps":["trace[2039670508] 'range keys from in-memory index tree' (duration: 215.302728ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:03.430948Z","caller":"traceutil/trace.go:171","msg":"trace[1293127753] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"220.473035ms","start":"2026-07-07T07:38:03.21045Z","end":"2026-07-07T07:38:03.430923Z","steps":["trace[1293127753] 'process raft request' (duration: 220.32547ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:03.4315Z","caller":"traceutil/trace.go:171","msg":"trace[2088231192] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1375; }","duration":"203.529495ms","start":"2026-07-07T07:38:03.227959Z","end":"2026-07-07T07:38:03.431489Z","steps":["trace[2088231192] 'read index received' (duration: 203.525615ms)","trace[2088231192] 'applied index is now lower than readState.Index' (duration: 3.22µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:38:03.431643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.670108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-07T07:38:03.431674Z","caller":"traceutil/trace.go:171","msg":"trace[960965892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"203.741021ms","start":"2026-07-07T07:38:03.227924Z","end":"2026-07-07T07:38:03.431666Z","steps":["trace[960965892] 'agreement among raft nodes before linearized reading' (duration: 203.629277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:03.431797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.604564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-07T07:38:03.431836Z","caller":"traceutil/trace.go:171","msg":"trace[1604438664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1320; }","duration":"186.676976ms","start":"2026-07-07T07:38:03.245148Z","end":"2026-07-07T07:38:03.431825Z","steps":["trace[1604438664] 'agreement among raft nodes before linearized reading' (duration: 186.573693ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:04.394799Z","caller":"traceutil/trace.go:171","msg":"trace[1573322517] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"426.879641ms","start":"2026-07-07T07:38:03.967889Z","end":"2026-07-07T07:38:04.394769Z","steps":["trace[1573322517] 'read index received' (duration: 340.522141ms)","trace[1573322517] 'applied index is now lower than readState.Index' (duration: 86.35603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:38:04.395488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.396046ms","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-07-07T07:38:04.395549Z","caller":"traceutil/trace.go:171","msg":"trace[2000801988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1325; }","duration":"427.707254ms","start":"2026-07-07T07:38:03.967828Z","end":"2026-07-07T07:38:04.395535Z","steps":["trace[2000801988] 'agreement among raft nodes before linearized reading' (duration: 427.049765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:04.395801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:03.967794Z","time spent":"427.784706ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-07T07:38:04.666626Z","caller":"traceutil/trace.go:171","msg":"trace[438881394] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"267.143007ms","start":"2026-07-07T07:38:04.399465Z","end":"2026-07-07T07:38:04.666608Z","steps":["trace[438881394] 'process raft request' (duration: 267.016344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:04.806599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.506189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:38:04.806892Z","caller":"traceutil/trace.go:171","msg":"trace[429336190] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"195.833496ms","start":"2026-07-07T07:38:04.611039Z","end":"2026-07-07T07:38:04.806872Z","steps":["trace[429336190] 'process raft request' (duration: 86.984538ms)","trace[429336190] 'compare' (duration: 108.359854ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:38:05.56462Z","caller":"traceutil/trace.go:171","msg":"trace[259798627] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"346.301455ms","start":"2026-07-07T07:38:05.218299Z","end":"2026-07-07T07:38:05.564601Z","steps":["trace[259798627] 'process raft request' (duration: 346.188002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:05.564755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.218273Z","time spent":"346.428918ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:38:05.710722Z","caller":"traceutil/trace.go:171","msg":"trace[2042297686] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"489.429145ms","start":"2026-07-07T07:38:05.221259Z","end":"2026-07-07T07:38:05.710688Z","steps":["trace[2042297686] 'process raft request' (duration: 426.623034ms)","trace[2042297686] 'compare' (duration: 62.565975ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:38:05.710786Z","caller":"traceutil/trace.go:171","msg":"trace[649279203] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"275.115545ms","start":"2026-07-07T07:38:05.435644Z","end":"2026-07-07T07:38:05.710759Z","steps":["trace[649279203] 'process raft request' (duration: 274.959881ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:05.710805Z","caller":"traceutil/trace.go:171","msg":"trace[1409122859] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"366.726494ms","start":"2026-07-07T07:38:05.344048Z","end":"2026-07-07T07:38:05.710774Z","steps":["trace[1409122859] 'read index received' (duration: 220.575597ms)","trace[1409122859] 'applied index is now lower than readState.Index' (duration: 146.149857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:38:05.710833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.221239Z","time spent":"489.539618ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:38:05.710881Z","caller":"traceutil/trace.go:171","msg":"trace[1985075118] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"141.606428ms","start":"2026-07-07T07:38:05.569266Z","end":"2026-07-07T07:38:05.710872Z","steps":["trace[1985075118] 'process raft request' (duration: 141.466384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:05.711002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.634495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-07T07:38:05.711004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.94333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-07T07:38:05.711048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.505175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:38:05.711055Z","caller":"traceutil/trace.go:171","msg":"trace[1506412629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"266.728967ms","start":"2026-07-07T07:38:05.444311Z","end":"2026-07-07T07:38:05.71104Z","steps":["trace[1506412629] 'agreement among raft nodes before linearized reading' (duration: 266.591393ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:05.711073Z","caller":"traceutil/trace.go:171","msg":"trace[1454363653] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1332; }","duration":"116.561267ms","start":"2026-07-07T07:38:05.594504Z","end":"2026-07-07T07:38:05.711065Z","steps":["trace[1454363653] 'agreement among raft nodes before linearized reading' (duration: 116.526706ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:05.711071Z","caller":"traceutil/trace.go:171","msg":"trace[679559051] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1332; }","duration":"367.037093ms","start":"2026-07-07T07:38:05.344016Z","end":"2026-07-07T07:38:05.711053Z","steps":["trace[679559051] 'agreement among raft nodes before linearized reading' (duration: 366.829807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:05.711118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.344001Z","time spent":"367.102494ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-07T07:38:05.711129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.105484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-07T07:38:05.711162Z","caller":"traceutil/trace.go:171","msg":"trace[1823269601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"251.28921ms","start":"2026-07-07T07:38:05.459866Z","end":"2026-07-07T07:38:05.711155Z","steps":["trace[1823269601] 'agreement among raft nodes before linearized reading' (duration: 251.218968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:05.71101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.392954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T07:38:05.711235Z","caller":"traceutil/trace.go:171","msg":"trace[1130006627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"250.656842ms","start":"2026-07-07T07:38:05.460572Z","end":"2026-07-07T07:38:05.711228Z","steps":["trace[1130006627] 'agreement among raft nodes before linearized reading' (duration: 250.365203ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:06.231732Z","caller":"traceutil/trace.go:171","msg":"trace[133567941] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"513.22186ms","start":"2026-07-07T07:38:05.718492Z","end":"2026-07-07T07:38:06.231714Z","steps":["trace[133567941] 'process raft request' (duration: 513.021775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:06.23184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.718478Z","time spent":"513.306613ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:38:06.232Z","caller":"traceutil/trace.go:171","msg":"trace[1151758260] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"513.459157ms","start":"2026-07-07T07:38:05.718534Z","end":"2026-07-07T07:38:06.231993Z","steps":["trace[1151758260] 'process raft request' (duration: 513.108377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:06.232041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.718523Z","time spent":"513.498278ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:38:06.232162Z","caller":"traceutil/trace.go:171","msg":"trace[1127226230] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"512.802459ms","start":"2026-07-07T07:38:05.719354Z","end":"2026-07-07T07:38:06.232156Z","steps":["trace[1127226230] 'process raft request' (duration: 512.327465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:06.2322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.719343Z","time spent":"512.84011ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:38:06.232435Z","caller":"traceutil/trace.go:171","msg":"trace[964310891] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1389; }","duration":"472.697431ms","start":"2026-07-07T07:38:05.759728Z","end":"2026-07-07T07:38:06.232426Z","steps":["trace[964310891] 'read index received' (duration: 471.719233ms)","trace[964310891] 'applied index is now lower than readState.Index' (duration: 977.428µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:38:06.2325Z","caller":"traceutil/trace.go:171","msg":"trace[890667079] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"511.072819ms","start":"2026-07-07T07:38:05.721416Z","end":"2026-07-07T07:38:06.232489Z","steps":["trace[890667079] 'process raft request' (duration: 510.548385ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:06.232533Z","caller":"traceutil/trace.go:171","msg":"trace[447190682] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"510.431711ms","start":"2026-07-07T07:38:05.722095Z","end":"2026-07-07T07:38:06.232527Z","steps":["trace[447190682] 'process raft request' (duration: 510.02397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:06.233232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.722088Z","time spent":"511.08419ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:38:06.232587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.850915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:38:06.233459Z","caller":"traceutil/trace.go:171","msg":"trace[1589819129] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1338; }","duration":"473.75145ms","start":"2026-07-07T07:38:05.759688Z","end":"2026-07-07T07:38:06.233439Z","steps":["trace[1589819129] 'agreement among raft nodes before linearized reading' (duration: 472.867035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:06.233519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.759671Z","time spent":"473.828243ms","remote":"127.0.0.1:54022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:38:06.234176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.340924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-07T07:38:06.234209Z","caller":"traceutil/trace.go:171","msg":"trace[1624929675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"470.402876ms","start":"2026-07-07T07:38:05.763797Z","end":"2026-07-07T07:38:06.2342Z","steps":["trace[1624929675] 'agreement among raft nodes before linearized reading' (duration: 468.936684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:06.234235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.763783Z","time spent":"470.445077ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T07:38:06.234503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:05.721406Z","time spent":"511.255165ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:38:14.206859Z","caller":"traceutil/trace.go:171","msg":"trace[1358662707] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"466.57205ms","start":"2026-07-07T07:38:13.740259Z","end":"2026-07-07T07:38:14.206831Z","steps":["trace[1358662707] 'process raft request' (duration: 466.421956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:14.207027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:13.740225Z","time spent":"466.720435ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:38:14.20719Z","caller":"traceutil/trace.go:171","msg":"trace[1059929766] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1444; }","duration":"433.781351ms","start":"2026-07-07T07:38:13.773388Z","end":"2026-07-07T07:38:14.207169Z","steps":["trace[1059929766] 'read index received' (duration: 433.770541ms)","trace[1059929766] 'applied index is now lower than readState.Index' (duration: 8.71µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:38:14.20741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.009577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-07T07:38:14.207466Z","caller":"traceutil/trace.go:171","msg":"trace[1673404777] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1387; }","duration":"434.10268ms","start":"2026-07-07T07:38:13.773349Z","end":"2026-07-07T07:38:14.207452Z","steps":["trace[1673404777] 'agreement among raft nodes before linearized reading' (duration: 433.919875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:14.207512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:13.773332Z","time spent":"434.167601ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-07T07:38:14.840893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.609511ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T07:38:14.841089Z","caller":"traceutil/trace.go:171","msg":"trace[163274197] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"629.323876ms","start":"2026-07-07T07:38:14.211752Z","end":"2026-07-07T07:38:14.841076Z","steps":["trace[163274197] 'process raft request' (duration: 629.224263ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:14.841124Z","caller":"traceutil/trace.go:171","msg":"trace[1799677100] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"633.856974ms","start":"2026-07-07T07:38:14.20724Z","end":"2026-07-07T07:38:14.841097Z","steps":["trace[1799677100] 'read index received' (duration: 483.060808ms)","trace[1799677100] 'applied index is now lower than readState.Index' (duration: 150.792056ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:38:14.841163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:14.211734Z","time spent":"629.378718ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:38:14.84142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063151298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T07:38:14.841465Z","caller":"traceutil/trace.go:171","msg":"trace[741383455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1388; }","duration":"1.063237781s","start":"2026-07-07T07:38:13.778216Z","end":"2026-07-07T07:38:14.841454Z","steps":["trace[741383455] 'agreement among raft nodes before linearized reading' (duration: 1.062997664s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:14.841496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:13.778196Z","time spent":"1.063290862s","remote":"127.0.0.1:49284","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-07-07T07:38:14.841546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.045715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-07T07:38:14.841572Z","caller":"traceutil/trace.go:171","msg":"trace[273038064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1388; }","duration":"564.106317ms","start":"2026-07-07T07:38:14.277459Z","end":"2026-07-07T07:38:14.841565Z","steps":["trace[273038064] 'agreement among raft nodes before linearized reading' (duration: 564.021044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:14.841612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:14.27744Z","time spent":"564.165598ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-07T07:38:14.84168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.322823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-07T07:38:14.841702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.894938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-07T07:38:14.841706Z","caller":"traceutil/trace.go:171","msg":"trace[188238788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1388; }","duration":"138.382255ms","start":"2026-07-07T07:38:14.703318Z","end":"2026-07-07T07:38:14.8417Z","steps":["trace[188238788] 'agreement among raft nodes before linearized reading' (duration: 138.318083ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:14.841718Z","caller":"traceutil/trace.go:171","msg":"trace[78060288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1388; }","duration":"516.938959ms","start":"2026-07-07T07:38:14.324774Z","end":"2026-07-07T07:38:14.841713Z","steps":["trace[78060288] 'agreement among raft nodes before linearized reading' (duration: 516.886107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:14.841761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:14.324758Z","time spent":"516.99551ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T07:38:14.841768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.045974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:38:14.841792Z","caller":"traceutil/trace.go:171","msg":"trace[1469705350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"108.227849ms","start":"2026-07-07T07:38:14.733559Z","end":"2026-07-07T07:38:14.841787Z","steps":["trace[1469705350] 'agreement among raft nodes before linearized reading' (duration: 108.195128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:14.841881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.464819ms","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-07-07T07:38:14.84191Z","caller":"traceutil/trace.go:171","msg":"trace[336137574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1388; }","duration":"551.525909ms","start":"2026-07-07T07:38:14.290378Z","end":"2026-07-07T07:38:14.841904Z","steps":["trace[336137574] 'agreement among raft nodes before linearized reading' (duration: 551.432597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:14.841928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:38:14.290359Z","time spent":"551.564581ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T07:39:36.540899Z","caller":"traceutil/trace.go:171","msg":"trace[2112012091] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"116.61684ms","start":"2026-07-07T07:39:36.424248Z","end":"2026-07-07T07:39:36.540865Z","steps":["trace[2112012091] 'process raft request' (duration: 116.321292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:51.522674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432441245702171063,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T07:40:53.953096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.295117623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T07:40:53.953192Z","caller":"traceutil/trace.go:171","msg":"trace[492451006] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"3.295240636s","start":"2026-07-07T07:40:50.657939Z","end":"2026-07-07T07:40:53.95318Z","steps":["trace[492451006] 'agreement among raft nodes before linearized reading' (duration: 3.295133243s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:53.953228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.657933Z","time spent":"3.295286887s","remote":"127.0.0.1:49024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 07:40:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T07:40:53.954631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432441245702171063,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T07:40:53.955327Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.307354068s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-07T07:40:53.956058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.647886Z","time spent":"3.308168201s","remote":"127.0.0.1:49052","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-07T07:40:54.046193Z","caller":"traceutil/trace.go:171","msg":"trace[2029975778] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"3.391103614s","start":"2026-07-07T07:40:50.65504Z","end":"2026-07-07T07:40:54.046144Z","steps":["trace[2029975778] 'process raft request' (duration: 3.388900092s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.046471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.655024Z","time spent":"3.391312789s","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:40:54.208355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.24209ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T07:40:54.208614Z","caller":"traceutil/trace.go:171","msg":"trace[479787236] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2099; }","duration":"246.186625ms","start":"2026-07-07T07:40:53.962397Z","end":"2026-07-07T07:40:54.208584Z","steps":["trace[479787236] 'process raft request' (duration: 246.117593ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:40:54.209001Z","caller":"traceutil/trace.go:171","msg":"trace[1746195555] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2185; }","duration":"3.560748257s","start":"2026-07-07T07:40:50.648241Z","end":"2026-07-07T07:40:54.208989Z","steps":["trace[1746195555] 'read index received' (duration: 3.307685517s)","trace[1746195555] 'applied index is now lower than readState.Index' (duration: 253.06102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:40:54.209234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.560969973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-07T07:40:54.209252Z","caller":"traceutil/trace.go:171","msg":"trace[161675720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2099; }","duration":"3.561017414s","start":"2026-07-07T07:40:50.648229Z","end":"2026-07-07T07:40:54.209246Z","steps":["trace[161675720] 'agreement among raft nodes before linearized reading' (duration: 3.560851469s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.209281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.648224Z","time spent":"3.561050975s","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-07T07:40:54.209474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.561164888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T07:40:54.209561Z","caller":"traceutil/trace.go:171","msg":"trace[637139148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2099; }","duration":"3.561275781s","start":"2026-07-07T07:40:50.648264Z","end":"2026-07-07T07:40:54.20954Z","steps":["trace[637139148] 'agreement among raft nodes before linearized reading' (duration: 3.561001093s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.209647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.648262Z","time spent":"3.561374514s","remote":"127.0.0.1:49284","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-07-07T07:40:54.209686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.221449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:40:54.209732Z","caller":"traceutil/trace.go:171","msg":"trace[75967749] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2099; }","duration":"256.28362ms","start":"2026-07-07T07:40:53.953439Z","end":"2026-07-07T07:40:54.209722Z","steps":["trace[75967749] 'agreement among raft nodes before linearized reading' (duration: 256.205768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.209935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.556428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:40:54.209968Z","caller":"traceutil/trace.go:171","msg":"trace[1433423260] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2099; }","duration":"256.599869ms","start":"2026-07-07T07:40:53.953356Z","end":"2026-07-07T07:40:54.209956Z","steps":["trace[1433423260] 'agreement among raft nodes before linearized reading' (duration: 256.482596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.21002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.4491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-07T07:40:54.210051Z","caller":"traceutil/trace.go:171","msg":"trace[1458387113] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2099; }","duration":"248.50748ms","start":"2026-07-07T07:40:53.961536Z","end":"2026-07-07T07:40:54.210043Z","steps":["trace[1458387113] 'agreement among raft nodes before linearized reading' (duration: 248.417708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.757044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:40:54.210131Z","caller":"traceutil/trace.go:171","msg":"trace[782127863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2099; }","duration":"256.806256ms","start":"2026-07-07T07:40:53.953319Z","end":"2026-07-07T07:40:54.210125Z","steps":["trace[782127863] 'agreement among raft nodes before linearized reading' (duration: 256.764155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.371333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-07T07:40:54.210248Z","caller":"traceutil/trace.go:171","msg":"trace[31761889] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2099; }","duration":"256.401184ms","start":"2026-07-07T07:40:53.95384Z","end":"2026-07-07T07:40:54.210241Z","steps":["trace[31761889] 'agreement among raft nodes before linearized reading' (duration: 256.333102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.561872378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:40:54.210303Z","caller":"traceutil/trace.go:171","msg":"trace[1544959794] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2099; }","duration":"3.56191202s","start":"2026-07-07T07:40:50.648383Z","end":"2026-07-07T07:40:54.210295Z","steps":["trace[1544959794] 'agreement among raft nodes before linearized reading' (duration: 3.561853738s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.64838Z","time spent":"3.56193817s","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:40:54.210429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.590599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-07T07:40:54.210453Z","caller":"traceutil/trace.go:171","msg":"trace[886595375] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2099; }","duration":"256.62342ms","start":"2026-07-07T07:40:53.953824Z","end":"2026-07-07T07:40:54.210447Z","steps":["trace[886595375] 'agreement among raft nodes before linearized reading' (duration: 256.517117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.562180887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-07T07:40:54.210525Z","caller":"traceutil/trace.go:171","msg":"trace[1246959903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2099; }","duration":"3.562214178s","start":"2026-07-07T07:40:50.648304Z","end":"2026-07-07T07:40:54.210518Z","steps":["trace[1246959903] 'agreement among raft nodes before linearized reading' (duration: 3.562131675s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.21055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.648302Z","time spent":"3.562242109s","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-07T07:40:54.210623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.799785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-07T07:40:54.21065Z","caller":"traceutil/trace.go:171","msg":"trace[1307279826] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2099; }","duration":"256.836126ms","start":"2026-07-07T07:40:53.953807Z","end":"2026-07-07T07:40:54.210643Z","steps":["trace[1307279826] 'agreement among raft nodes before linearized reading' (duration: 256.743064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.562391263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-07T07:40:54.210718Z","caller":"traceutil/trace.go:171","msg":"trace[878551101] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2099; }","duration":"3.562419773s","start":"2026-07-07T07:40:50.648292Z","end":"2026-07-07T07:40:54.210711Z","steps":["trace[878551101] 'agreement among raft nodes before linearized reading' (duration: 3.562355831s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.648289Z","time spent":"3.562441174s","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-07T07:40:54.210822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.033601ms","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-07-07T07:40:54.210841Z","caller":"traceutil/trace.go:171","msg":"trace[1091582822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2099; }","duration":"257.070103ms","start":"2026-07-07T07:40:53.953766Z","end":"2026-07-07T07:40:54.210836Z","steps":["trace[1091582822] 'agreement among raft nodes before linearized reading' (duration: 257.000581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.562576338s","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-07-07T07:40:54.210889Z","caller":"traceutil/trace.go:171","msg":"trace[946133217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2099; }","duration":"3.562603869s","start":"2026-07-07T07:40:50.648278Z","end":"2026-07-07T07:40:54.210882Z","steps":["trace[946133217] 'agreement among raft nodes before linearized reading' (duration: 3.562544367s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.210903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:50.648276Z","time spent":"3.56262426s","remote":"127.0.0.1:49284","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\" "}