{"level":"warn","ts":"2026-05-05T09:48:32.816903Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T09:48:32.81701Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.175: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.19.213.175:2380","--initial-cluster=instance=https://199.19.213.175:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.175:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.175: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-05-05T09:48:32.817194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T09:48:32.817208Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-05-05T09:48:32.817224Z","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-05-05T09:48:32.817924Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"]} {"level":"info","ts":"2026-05-05T09:48:32.818049Z","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.19.213.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.175: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-05-05T09:48:32.822891Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.512484ms"} {"level":"info","ts":"2026-05-05T09:48:32.829601Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"223c7693b78843e1","cluster-id":"b697af90308fdc01"} {"level":"info","ts":"2026-05-05T09:48:32.829719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T09:48:32.829742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 0"} {"level":"info","ts":"2026-05-05T09:48:32.829758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 223c7693b78843e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T09:48:32.82977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 1"} {"level":"info","ts":"2026-05-05T09:48:32.829805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"warn","ts":"2026-05-05T09:48:32.838762Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T09:48:32.840305Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T09:48:32.841377Z","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-05-05T09:48:32.843628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"223c7693b78843e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T09:48:32.843876Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"223c7693b78843e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T09:48:32.844048Z","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-05-05T09:48:32.844105Z","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-05-05T09:48:32.844114Z","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-05-05T09:48:32.845318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"info","ts":"2026-05-05T09:48:32.846761Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","added-peer-id":"223c7693b78843e1","added-peer-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-05-05T09:48:32.848543Z","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-05-05T09:48:32.848616Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-05-05T09:48:32.848677Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-05-05T09:48:32.848761Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"223c7693b78843e1","initial-advertise-peer-urls":["https://199.19.213.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T09:48:32.8488Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T09:48:33.73103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T09:48:33.731098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T09:48:33.731144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgPreVoteResp from 223c7693b78843e1 at term 1"} {"level":"info","ts":"2026-05-05T09:48:33.731165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became candidate at term 2"} {"level":"info","ts":"2026-05-05T09:48:33.731176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgVoteResp from 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-05-05T09:48:33.731193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became leader at term 2"} {"level":"info","ts":"2026-05-05T09:48:33.731207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 223c7693b78843e1 elected leader 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-05-05T09:48:33.73242Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:48:33.733649Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:48:33.733648Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"223c7693b78843e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.175:2379]}","request-path":"/0/members/223c7693b78843e1/attributes","cluster-id":"b697af90308fdc01","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T09:48:33.733952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:48:33.734102Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:48:33.734215Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:48:33.734269Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:48:33.734349Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T09:48:33.734381Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T09:48:33.736779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T09:48:33.736876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.175:2379"} {"level":"info","ts":"2026-05-05T09:48:37.423468Z","caller":"traceutil/trace.go:171","msg":"trace[1674219289] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"333.990498ms","start":"2026-05-05T09:48:37.089434Z","end":"2026-05-05T09:48:37.423425Z","steps":["trace[1674219289] 'process raft request' (duration: 333.822404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:48:37.424036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:48:37.089415Z","time spent":"334.154192ms","remote":"127.0.0.1:37868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:48:38.26307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.222635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:48:38.263235Z","caller":"traceutil/trace.go:171","msg":"trace[532128493] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:245; }","duration":"790.146694ms","start":"2026-05-05T09:48:37.473067Z","end":"2026-05-05T09:48:38.263214Z","steps":["trace[532128493] 'read index received' (duration: 282.368576ms)","trace[532128493] 'applied index is now lower than readState.Index' (duration: 507.776468ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:48:38.263299Z","caller":"traceutil/trace.go:171","msg":"trace[353902238] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"833.63553ms","start":"2026-05-05T09:48:37.429603Z","end":"2026-05-05T09:48:38.263238Z","steps":["trace[353902238] 'process raft request' (duration: 326.006136ms)","trace[353902238] 'compare' (duration: 507.074842ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:48:38.263368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.300318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:48:38.263401Z","caller":"traceutil/trace.go:171","msg":"trace[1536113558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:240; }","duration":"790.38493ms","start":"2026-05-05T09:48:37.473006Z","end":"2026-05-05T09:48:38.263391Z","steps":["trace[1536113558] 'agreement among raft nodes before linearized reading' (duration: 790.283878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:48:38.26344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:48:37.472985Z","time spent":"790.447711ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T09:48:38.263476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:48:37.42957Z","time spent":"833.828235ms","remote":"127.0.0.1:47018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:48:38.267031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.050941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:48:38.267126Z","caller":"traceutil/trace.go:171","msg":"trace[1186063167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:241; }","duration":"321.219185ms","start":"2026-05-05T09:48:37.945885Z","end":"2026-05-05T09:48:38.267104Z","steps":["trace[1186063167] 'agreement among raft nodes before linearized reading' (duration: 321.052412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:48:38.267172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:48:37.945859Z","time spent":"321.304497ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T09:48:38.267413Z","caller":"traceutil/trace.go:171","msg":"trace[1188458795] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"738.714235ms","start":"2026-05-05T09:48:37.528687Z","end":"2026-05-05T09:48:38.267402Z","steps":["trace[1188458795] 'process raft request' (duration: 738.137682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:48:38.267514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:48:37.528665Z","time spent":"738.799088ms","remote":"127.0.0.1:37950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:49:22.78521Z","caller":"traceutil/trace.go:171","msg":"trace[195931764] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"142.802941ms","start":"2026-05-05T09:49:22.642379Z","end":"2026-05-05T09:49:22.785181Z","steps":["trace[195931764] 'process raft request' (duration: 142.654917ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.901558Z","caller":"traceutil/trace.go:171","msg":"trace[1845290608] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"252.579834ms","start":"2026-05-05T09:49:22.648916Z","end":"2026-05-05T09:49:22.901496Z","steps":["trace[1845290608] 'process raft request' (duration: 252.217265ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.901718Z","caller":"traceutil/trace.go:171","msg":"trace[1667221336] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"247.405726ms","start":"2026-05-05T09:49:22.654262Z","end":"2026-05-05T09:49:22.901667Z","steps":["trace[1667221336] 'process raft request' (duration: 247.133169ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.901877Z","caller":"traceutil/trace.go:171","msg":"trace[750120962] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"244.956751ms","start":"2026-05-05T09:49:22.656895Z","end":"2026-05-05T09:49:22.901851Z","steps":["trace[750120962] 'process raft request' (duration: 244.691114ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.901899Z","caller":"traceutil/trace.go:171","msg":"trace[131224659] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"236.800913ms","start":"2026-05-05T09:49:22.665087Z","end":"2026-05-05T09:49:22.901888Z","steps":["trace[131224659] 'process raft request' (duration: 236.552327ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.90206Z","caller":"traceutil/trace.go:171","msg":"trace[113435980] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"236.312033ms","start":"2026-05-05T09:49:22.66573Z","end":"2026-05-05T09:49:22.902042Z","steps":["trace[113435980] 'process raft request' (duration: 236.16145ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.902243Z","caller":"traceutil/trace.go:171","msg":"trace[2082802167] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"236.005375ms","start":"2026-05-05T09:49:22.666222Z","end":"2026-05-05T09:49:22.902227Z","steps":["trace[2082802167] 'process raft request' (duration: 235.746879ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.902129Z","caller":"traceutil/trace.go:171","msg":"trace[1415731631] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"236.857014ms","start":"2026-05-05T09:49:22.665163Z","end":"2026-05-05T09:49:22.90202Z","steps":["trace[1415731631] 'process raft request' (duration: 236.682141ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.902356Z","caller":"traceutil/trace.go:171","msg":"trace[1275143639] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"234.322217ms","start":"2026-05-05T09:49:22.667978Z","end":"2026-05-05T09:49:22.9023Z","steps":["trace[1275143639] 'process raft request' (duration: 234.054991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.902486Z","caller":"traceutil/trace.go:171","msg":"trace[728922052] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"227.647884ms","start":"2026-05-05T09:49:22.674823Z","end":"2026-05-05T09:49:22.90247Z","steps":["trace[728922052] 'process raft request' (duration: 227.371257ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.902678Z","caller":"traceutil/trace.go:171","msg":"trace[1949366137] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"186.023031ms","start":"2026-05-05T09:49:22.716624Z","end":"2026-05-05T09:49:22.902647Z","steps":["trace[1949366137] 'process raft request' (duration: 185.960649ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.901786Z","caller":"traceutil/trace.go:171","msg":"trace[1503695535] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"244.952619ms","start":"2026-05-05T09:49:22.65681Z","end":"2026-05-05T09:49:22.901763Z","steps":["trace[1503695535] 'process raft request' (duration: 244.735074ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:22.902718Z","caller":"traceutil/trace.go:171","msg":"trace[1870109088] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"189.726175ms","start":"2026-05-05T09:49:22.712973Z","end":"2026-05-05T09:49:22.902699Z","steps":["trace[1870109088] 'process raft request' (duration: 189.401577ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:23.760558Z","caller":"traceutil/trace.go:171","msg":"trace[1104803530] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"121.058412ms","start":"2026-05-05T09:49:23.639479Z","end":"2026-05-05T09:49:23.760538Z","steps":["trace[1104803530] 'process raft request' (duration: 120.705494ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:23.787752Z","caller":"traceutil/trace.go:171","msg":"trace[130448901] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"145.638146ms","start":"2026-05-05T09:49:23.642094Z","end":"2026-05-05T09:49:23.787732Z","steps":["trace[130448901] 'process raft request' (duration: 145.217066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:24.517413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.660853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:49:24.51753Z","caller":"traceutil/trace.go:171","msg":"trace[1429177576] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"719.040256ms","start":"2026-05-05T09:49:23.798472Z","end":"2026-05-05T09:49:24.517512Z","steps":["trace[1429177576] 'process raft request' (duration: 577.062145ms)","trace[1429177576] 'compare' (duration: 141.49798ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:24.517591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:23.798434Z","time spent":"719.127937ms","remote":"127.0.0.1:46964","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-05-05T09:49:24.529744Z","caller":"traceutil/trace.go:171","msg":"trace[530886056] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"655.45466ms","start":"2026-05-05T09:49:23.874263Z","end":"2026-05-05T09:49:24.529717Z","steps":["trace[530886056] 'process raft request' (duration: 655.149863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:24.529848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:23.874239Z","time spent":"655.563952ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:49:24.530194Z","caller":"traceutil/trace.go:171","msg":"trace[1758120393] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"521.897992ms","start":"2026-05-05T09:49:24.008266Z","end":"2026-05-05T09:49:24.530164Z","steps":["trace[1758120393] 'process raft request' (duration: 521.458302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:24.530356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:24.008237Z","time spent":"522.033305ms","remote":"127.0.0.1:37796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:29.354473Z","caller":"traceutil/trace.go:171","msg":"trace[1229775188] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"289.753106ms","start":"2026-05-05T09:49:29.064696Z","end":"2026-05-05T09:49:29.354449Z","steps":["trace[1229775188] 'read index received' (duration: 256.493114ms)","trace[1229775188] 'applied index is now lower than readState.Index' (duration: 33.259042ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:29.354598Z","caller":"traceutil/trace.go:171","msg":"trace[1874025036] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"295.861985ms","start":"2026-05-05T09:49:29.058681Z","end":"2026-05-05T09:49:29.354543Z","steps":["trace[1874025036] 'process raft request' (duration: 262.622484ms)","trace[1874025036] 'compare' (duration: 33.049757ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:29.354646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.00191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p7dtn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T09:49:29.35497Z","caller":"traceutil/trace.go:171","msg":"trace[350272835] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p7dtn; range_end:; response_count:1; response_revision:658; }","duration":"214.352467ms","start":"2026-05-05T09:49:29.14056Z","end":"2026-05-05T09:49:29.354912Z","steps":["trace[350272835] 'agreement among raft nodes before linearized reading' (duration: 214.02181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:29.354681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.93839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:49:29.355107Z","caller":"traceutil/trace.go:171","msg":"trace[1095118686] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:658; }","duration":"290.438291ms","start":"2026-05-05T09:49:29.06464Z","end":"2026-05-05T09:49:29.355079Z","steps":["trace[1095118686] 'agreement among raft nodes before linearized reading' (duration: 289.885659ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:29.769214Z","caller":"traceutil/trace.go:171","msg":"trace[251216236] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"388.400334ms","start":"2026-05-05T09:49:29.380793Z","end":"2026-05-05T09:49:29.769193Z","steps":["trace[251216236] 'process raft request' (duration: 388.157739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:29.769353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:29.380768Z","time spent":"388.510007ms","remote":"127.0.0.1:37950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:49:30.31798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799644573,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:49:30.430671Z","caller":"traceutil/trace.go:171","msg":"trace[911356151] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"612.781153ms","start":"2026-05-05T09:49:29.81785Z","end":"2026-05-05T09:49:30.430631Z","steps":["trace[911356151] 'read index received' (duration: 584.920395ms)","trace[911356151] 'applied index is now lower than readState.Index' (duration: 27.859088ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:30.430739Z","caller":"traceutil/trace.go:171","msg":"trace[507149595] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"625.954004ms","start":"2026-05-05T09:49:29.804751Z","end":"2026-05-05T09:49:30.430705Z","steps":["trace[507149595] 'process raft request' (duration: 597.990193ms)","trace[507149595] 'compare' (duration: 27.212964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:49:30.43097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.11792ms","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-05-05T09:49:30.430975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:29.804727Z","time spent":"626.102487ms","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:49:30.431041Z","caller":"traceutil/trace.go:171","msg":"trace[1158213902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"613.193182ms","start":"2026-05-05T09:49:29.81783Z","end":"2026-05-05T09:49:30.431023Z","steps":["trace[1158213902] 'agreement among raft nodes before linearized reading' (duration: 612.934706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:30.431086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:29.817778Z","time spent":"613.299724ms","remote":"127.0.0.1:46964","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-05-05T09:49:30.84019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.294589ms","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-05-05T09:49:30.840295Z","caller":"traceutil/trace.go:171","msg":"trace[466133565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"301.464713ms","start":"2026-05-05T09:49:30.538807Z","end":"2026-05-05T09:49:30.840271Z","steps":["trace[466133565] 'range keys from in-memory index tree' (duration: 301.082194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:30.840343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:30.53878Z","time spent":"301.554344ms","remote":"127.0.0.1:46964","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-05-05T09:49:46.8219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.664432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-552h7\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-05T09:49:46.822126Z","caller":"traceutil/trace.go:171","msg":"trace[1194693478] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-552h7; range_end:; response_count:1; response_revision:828; }","duration":"582.954239ms","start":"2026-05-05T09:49:46.239145Z","end":"2026-05-05T09:49:46.822099Z","steps":["trace[1194693478] 'range keys from in-memory index tree' (duration: 582.444087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:46.822301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:46.239104Z","time spent":"583.180614ms","remote":"127.0.0.1:37950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-552h7\" "} {"level":"warn","ts":"2026-05-05T09:49:46.822704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.666525ms","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-05-05T09:49:46.822755Z","caller":"traceutil/trace.go:171","msg":"trace[1399099070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:829; }","duration":"234.774598ms","start":"2026-05-05T09:49:46.587971Z","end":"2026-05-05T09:49:46.822745Z","steps":["trace[1399099070] 'agreement among raft nodes before linearized reading' (duration: 234.569353ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:46.822875Z","caller":"traceutil/trace.go:171","msg":"trace[1223415109] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"322.534185ms","start":"2026-05-05T09:49:46.500331Z","end":"2026-05-05T09:49:46.822865Z","steps":["trace[1223415109] 'process raft request' (duration: 312.094606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:46.822329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.722739ms","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":"warn","ts":"2026-05-05T09:49:46.822972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:46.500301Z","time spent":"322.602777ms","remote":"127.0.0.1:46964","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-05-05T09:49:46.823253Z","caller":"traceutil/trace.go:171","msg":"trace[208585706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"536.422355ms","start":"2026-05-05T09:49:46.286539Z","end":"2026-05-05T09:49:46.822961Z","steps":["trace[208585706] 'range keys from in-memory index tree' (duration: 535.473863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:49:46.823333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:49:46.286531Z","time spent":"536.783463ms","remote":"127.0.0.1:46964","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":"info","ts":"2026-05-05T09:49:46.822486Z","caller":"traceutil/trace.go:171","msg":"trace[65756309] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:863; }","duration":"234.436229ms","start":"2026-05-05T09:49:46.588024Z","end":"2026-05-05T09:49:46.82246Z","steps":["trace[65756309] 'read index received' (duration: 224.419711ms)","trace[65756309] 'applied index is now lower than readState.Index' (duration: 10.013608ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:21.13209Z","caller":"traceutil/trace.go:171","msg":"trace[390397281] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"124.669991ms","start":"2026-05-05T09:50:21.007396Z","end":"2026-05-05T09:50:21.132066Z","steps":["trace[390397281] 'read index received' (duration: 124.383635ms)","trace[390397281] 'applied index is now lower than readState.Index' (duration: 285.306µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:21.13219Z","caller":"traceutil/trace.go:171","msg":"trace[805443581] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"146.448658ms","start":"2026-05-05T09:50:20.985711Z","end":"2026-05-05T09:50:21.13216Z","steps":["trace[805443581] 'process raft request' (duration: 146.160732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:21.132353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.912466ms","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-05-05T09:50:21.132673Z","caller":"traceutil/trace.go:171","msg":"trace[1132110986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"125.295455ms","start":"2026-05-05T09:50:21.007352Z","end":"2026-05-05T09:50:21.132648Z","steps":["trace[1132110986] 'agreement among raft nodes before linearized reading' (duration: 124.845585ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:22.218121Z","caller":"traceutil/trace.go:171","msg":"trace[351947640] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"412.01652ms","start":"2026-05-05T09:50:21.806049Z","end":"2026-05-05T09:50:22.218065Z","steps":["trace[351947640] 'read index received' (duration: 411.844386ms)","trace[351947640] 'applied index is now lower than readState.Index' (duration: 171.014µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:22.218193Z","caller":"traceutil/trace.go:171","msg":"trace[1257332267] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"723.231567ms","start":"2026-05-05T09:50:21.494936Z","end":"2026-05-05T09:50:22.218168Z","steps":["trace[1257332267] 'process raft request' (duration: 722.9808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:22.218333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:21.494892Z","time spent":"723.35819ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:50:22.218331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.272807ms","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-05-05T09:50:22.218376Z","caller":"traceutil/trace.go:171","msg":"trace[2090370554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"412.36704ms","start":"2026-05-05T09:50:21.805996Z","end":"2026-05-05T09:50:22.218363Z","steps":["trace[2090370554] 'agreement among raft nodes before linearized reading' (duration: 412.193356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:22.218409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:21.805967Z","time spent":"412.43386ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T09:50:22.247304Z","caller":"traceutil/trace.go:171","msg":"trace[1004375741] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"212.935939ms","start":"2026-05-05T09:50:22.034344Z","end":"2026-05-05T09:50:22.24728Z","steps":["trace[1004375741] 'process raft request' (duration: 212.745784ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:45.453647Z","caller":"traceutil/trace.go:171","msg":"trace[1066918069] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"142.726034ms","start":"2026-05-05T09:50:45.310889Z","end":"2026-05-05T09:50:45.453615Z","steps":["trace[1066918069] 'process raft request' (duration: 123.493762ms)","trace[1066918069] 'compare' (duration: 19.098519ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:45.457034Z","caller":"traceutil/trace.go:171","msg":"trace[1348369569] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"146.067901ms","start":"2026-05-05T09:50:45.310937Z","end":"2026-05-05T09:50:45.457005Z","steps":["trace[1348369569] 'process raft request' (duration: 145.894927ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:46.750201Z","caller":"traceutil/trace.go:171","msg":"trace[1206058157] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1171; }","duration":"386.271047ms","start":"2026-05-05T09:50:46.363912Z","end":"2026-05-05T09:50:46.750183Z","steps":["trace[1206058157] 'read index received' (duration: 386.107424ms)","trace[1206058157] 'applied index is now lower than readState.Index' (duration: 163.003µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:46.750332Z","caller":"traceutil/trace.go:171","msg":"trace[1944714740] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"405.385208ms","start":"2026-05-05T09:50:46.344912Z","end":"2026-05-05T09:50:46.750298Z","steps":["trace[1944714740] 'process raft request' (duration: 405.149983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:46.750384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.439992ms","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-05-05T09:50:46.750425Z","caller":"traceutil/trace.go:171","msg":"trace[745528285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1124; }","duration":"386.546104ms","start":"2026-05-05T09:50:46.363871Z","end":"2026-05-05T09:50:46.750417Z","steps":["trace[745528285] 'agreement among raft nodes before linearized reading' (duration: 386.38184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:46.750448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:46.363856Z","time spent":"386.586315ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T09:50:46.750518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:46.344889Z","time spent":"405.528081ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:50:46.948972Z","caller":"traceutil/trace.go:171","msg":"trace[262901277] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"193.618135ms","start":"2026-05-05T09:50:46.75532Z","end":"2026-05-05T09:50:46.948938Z","steps":["trace[262901277] 'process raft request' (duration: 129.52423ms)","trace[262901277] 'compare' (duration: 63.660704ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:47.127946Z","caller":"traceutil/trace.go:171","msg":"trace[1423104707] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"146.213925ms","start":"2026-05-05T09:50:46.981702Z","end":"2026-05-05T09:50:47.127915Z","steps":["trace[1423104707] 'process raft request' (duration: 80.83745ms)","trace[1423104707] 'compare' (duration: 65.21002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:47.699653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.279096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:47.699828Z","caller":"traceutil/trace.go:171","msg":"trace[1474534408] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"241.322162ms","start":"2026-05-05T09:50:47.45845Z","end":"2026-05-05T09:50:47.699772Z","steps":["trace[1474534408] 'read index received' (duration: 65.128758ms)","trace[1474534408] 'applied index is now lower than readState.Index' (duration: 176.191964ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:47.699868Z","caller":"traceutil/trace.go:171","msg":"trace[790924598] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"379.38471ms","start":"2026-05-05T09:50:47.32043Z","end":"2026-05-05T09:50:47.699815Z","steps":["trace[790924598] 'process raft request' (duration: 202.554841ms)","trace[790924598] 'compare' (duration: 174.987556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:47.70002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.57397ms","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-05-05T09:50:47.700058Z","caller":"traceutil/trace.go:171","msg":"trace[1340198257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1128; }","duration":"241.661901ms","start":"2026-05-05T09:50:47.458385Z","end":"2026-05-05T09:50:47.700047Z","steps":["trace[1340198257] 'agreement among raft nodes before linearized reading' (duration: 241.504448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:47.700154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:47.320416Z","time spent":"379.580104ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:50:47.700674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.701449ms","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-05-05T09:50:47.700749Z","caller":"traceutil/trace.go:171","msg":"trace[1190759020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1128; }","duration":"240.786851ms","start":"2026-05-05T09:50:47.459943Z","end":"2026-05-05T09:50:47.70073Z","steps":["trace[1190759020] 'agreement among raft nodes before linearized reading' (duration: 240.580536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:47.701495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.626299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:50:47.706094Z","caller":"traceutil/trace.go:171","msg":"trace[1371786036] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1128; }","duration":"163.272567ms","start":"2026-05-05T09:50:47.542781Z","end":"2026-05-05T09:50:47.706054Z","steps":["trace[1371786036] 'agreement among raft nodes before linearized reading' (duration: 157.77482ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:48.549146Z","caller":"traceutil/trace.go:171","msg":"trace[1586865094] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"149.195673ms","start":"2026-05-05T09:50:48.399929Z","end":"2026-05-05T09:50:48.549124Z","steps":["trace[1586865094] 'process raft request' (duration: 149.027259ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:05.139965Z","caller":"traceutil/trace.go:171","msg":"trace[2013667707] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"106.9391ms","start":"2026-05-05T09:51:05.033003Z","end":"2026-05-05T09:51:05.139942Z","steps":["trace[2013667707] 'read index received' (duration: 106.758626ms)","trace[2013667707] 'applied index is now lower than readState.Index' (duration: 179.594µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:51:05.140246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.220526ms","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-05-05T09:51:05.140291Z","caller":"traceutil/trace.go:171","msg":"trace[780891175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1244; }","duration":"107.318109ms","start":"2026-05-05T09:51:05.032961Z","end":"2026-05-05T09:51:05.140279Z","steps":["trace[780891175] 'agreement among raft nodes before linearized reading' (duration: 107.081954ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:05.140644Z","caller":"traceutil/trace.go:171","msg":"trace[1702211057] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"230.560545ms","start":"2026-05-05T09:51:04.91003Z","end":"2026-05-05T09:51:05.14059Z","steps":["trace[1702211057] 'process raft request' (duration: 229.772867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:05.468232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.290462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:51:05.468336Z","caller":"traceutil/trace.go:171","msg":"trace[1741910200] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"220.35601ms","start":"2026-05-05T09:51:05.247961Z","end":"2026-05-05T09:51:05.468317Z","steps":["trace[1741910200] 'read index received' (duration: 71.769031ms)","trace[1741910200] 'applied index is now lower than readState.Index' (duration: 148.585929ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:51:05.468555Z","caller":"traceutil/trace.go:171","msg":"trace[1942407973] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"324.295222ms","start":"2026-05-05T09:51:05.144243Z","end":"2026-05-05T09:51:05.468538Z","steps":["trace[1942407973] 'process raft request' (duration: 175.587389ms)","trace[1942407973] 'compare' (duration: 148.1878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:51:05.468625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:05.144229Z","time spent":"324.361734ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:51:05.468829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.875182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:51:05.468859Z","caller":"traceutil/trace.go:171","msg":"trace[1467257693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"220.938264ms","start":"2026-05-05T09:51:05.247911Z","end":"2026-05-05T09:51:05.468849Z","steps":["trace[1467257693] 'agreement among raft nodes before linearized reading' (duration: 220.886143ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:05.61845Z","caller":"traceutil/trace.go:171","msg":"trace[14996052] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"145.423676ms","start":"2026-05-05T09:51:05.472993Z","end":"2026-05-05T09:51:05.618417Z","steps":["trace[14996052] 'process raft request' (duration: 143.275456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:21.747544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799646072,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:51:21.819454Z","caller":"traceutil/trace.go:171","msg":"trace[1246180128] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"572.200046ms","start":"2026-05-05T09:51:21.247235Z","end":"2026-05-05T09:51:21.819435Z","steps":["trace[1246180128] 'read index received' (duration: 572.018732ms)","trace[1246180128] 'applied index is now lower than readState.Index' (duration: 180.544µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:51:21.819583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.734598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:51:21.819612Z","caller":"traceutil/trace.go:171","msg":"trace[304997755] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1318; }","duration":"532.802319ms","start":"2026-05-05T09:51:21.286794Z","end":"2026-05-05T09:51:21.819596Z","steps":["trace[304997755] 'agreement among raft nodes before linearized reading' (duration: 532.742078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:21.819639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:21.286743Z","time spent":"532.891951ms","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:51:21.819692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.41684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:51:21.819824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.523788ms","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-05-05T09:51:21.819822Z","caller":"traceutil/trace.go:171","msg":"trace[1235916495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"572.620995ms","start":"2026-05-05T09:51:21.247174Z","end":"2026-05-05T09:51:21.819795Z","steps":["trace[1235916495] 'agreement among raft nodes before linearized reading' (duration: 572.356669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:21.819837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.868788ms","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-05-05T09:51:21.819884Z","caller":"traceutil/trace.go:171","msg":"trace[1761643089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1318; }","duration":"119.97681ms","start":"2026-05-05T09:51:21.699895Z","end":"2026-05-05T09:51:21.819872Z","steps":["trace[1761643089] 'agreement among raft nodes before linearized reading' (duration: 119.839547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:21.8199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:21.24711Z","time spent":"572.771789ms","remote":"127.0.0.1:37758","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-05-05T09:51:21.819845Z","caller":"traceutil/trace.go:171","msg":"trace[747340057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1318; }","duration":"265.592341ms","start":"2026-05-05T09:51:21.554248Z","end":"2026-05-05T09:51:21.81984Z","steps":["trace[747340057] 'agreement among raft nodes before linearized reading' (duration: 265.5308ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:21.820687Z","caller":"traceutil/trace.go:171","msg":"trace[925395205] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"618.373788ms","start":"2026-05-05T09:51:21.202238Z","end":"2026-05-05T09:51:21.820612Z","steps":["trace[925395205] 'process raft request' (duration: 617.064908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:21.82084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:21.202198Z","time spent":"618.548282ms","remote":"127.0.0.1:46964","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-05-05T09:51:22.072072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.679875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:51:22.072216Z","caller":"traceutil/trace.go:171","msg":"trace[1353482203] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"205.037518ms","start":"2026-05-05T09:51:21.867158Z","end":"2026-05-05T09:51:22.072196Z","steps":["trace[1353482203] 'read index received' (duration: 91.018215ms)","trace[1353482203] 'applied index is now lower than readState.Index' (duration: 114.017662ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:51:22.072456Z","caller":"traceutil/trace.go:171","msg":"trace[1367986439] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"247.532026ms","start":"2026-05-05T09:51:21.82487Z","end":"2026-05-05T09:51:22.072402Z","steps":["trace[1367986439] 'process raft request' (duration: 133.376029ms)","trace[1367986439] 'compare' (duration: 113.472731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:51:22.072713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.53705ms","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-05-05T09:51:22.072781Z","caller":"traceutil/trace.go:171","msg":"trace[974677414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"205.663333ms","start":"2026-05-05T09:51:21.867099Z","end":"2026-05-05T09:51:22.072762Z","steps":["trace[974677414] 'agreement among raft nodes before linearized reading' (duration: 205.433158ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:22.224687Z","caller":"traceutil/trace.go:171","msg":"trace[672900220] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"152.413176ms","start":"2026-05-05T09:51:22.072245Z","end":"2026-05-05T09:51:22.224659Z","steps":["trace[672900220] 'read index received' (duration: 75.733262ms)","trace[672900220] 'applied index is now lower than readState.Index' (duration: 76.678764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:51:22.225046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.233996ms","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-05-05T09:51:22.225203Z","caller":"traceutil/trace.go:171","msg":"trace[1211118579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"276.449401ms","start":"2026-05-05T09:51:21.94873Z","end":"2026-05-05T09:51:22.225179Z","steps":["trace[1211118579] 'agreement among raft nodes before linearized reading' (duration: 276.165265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:22.225783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.473905ms","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-05-05T09:51:22.225853Z","caller":"traceutil/trace.go:171","msg":"trace[971784051] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"147.129375ms","start":"2026-05-05T09:51:22.078682Z","end":"2026-05-05T09:51:22.225811Z","steps":["trace[971784051] 'process raft request' (duration: 145.875387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:22.226034Z","caller":"traceutil/trace.go:171","msg":"trace[402121412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"274.67233ms","start":"2026-05-05T09:51:21.951256Z","end":"2026-05-05T09:51:22.225928Z","steps":["trace[402121412] 'agreement among raft nodes before linearized reading' (duration: 274.419704ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:23.958375Z","caller":"traceutil/trace.go:171","msg":"trace[53513939] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"129.52742ms","start":"2026-05-05T09:51:23.82882Z","end":"2026-05-05T09:51:23.958347Z","steps":["trace[53513939] 'process raft request' (duration: 129.160732ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:23.982218Z","caller":"traceutil/trace.go:171","msg":"trace[959108365] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"122.256932ms","start":"2026-05-05T09:51:23.859935Z","end":"2026-05-05T09:51:23.982192Z","steps":["trace[959108365] 'process raft request' (duration: 122.059208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:24.62518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.482218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:51:24.625358Z","caller":"traceutil/trace.go:171","msg":"trace[779785817] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"307.156458ms","start":"2026-05-05T09:51:24.318165Z","end":"2026-05-05T09:51:24.625322Z","steps":["trace[779785817] 'process raft request' (duration: 307.099516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:24.625436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:24.318153Z","time spent":"307.24704ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:51:24.625568Z","caller":"traceutil/trace.go:171","msg":"trace[1497666887] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"307.585268ms","start":"2026-05-05T09:51:24.317927Z","end":"2026-05-05T09:51:24.625513Z","steps":["trace[1497666887] 'process raft request' (duration: 81.647579ms)","trace[1497666887] 'compare' (duration: 225.261164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:51:24.626504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:24.317901Z","time spent":"308.447577ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:51:25.012619Z","caller":"traceutil/trace.go:171","msg":"trace[701753] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"109.443588ms","start":"2026-05-05T09:51:24.903151Z","end":"2026-05-05T09:51:25.012595Z","steps":["trace[701753] 'process raft request' (duration: 109.286245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:25.437302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.124099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:51:25.437441Z","caller":"traceutil/trace.go:171","msg":"trace[371064920] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"313.442402ms","start":"2026-05-05T09:51:25.123979Z","end":"2026-05-05T09:51:25.437422Z","steps":["trace[371064920] 'read index received' (duration: 148.004876ms)","trace[371064920] 'applied index is now lower than readState.Index' (duration: 165.435586ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:51:25.43754Z","caller":"traceutil/trace.go:171","msg":"trace[1439971991] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"349.430351ms","start":"2026-05-05T09:51:25.088097Z","end":"2026-05-05T09:51:25.437528Z","steps":["trace[1439971991] 'process raft request' (duration: 183.982974ms)","trace[1439971991] 'compare' (duration: 164.640989ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:51:25.437618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:25.088065Z","time spent":"349.512692ms","remote":"127.0.0.1:37840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":863,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:51:25.437735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.727639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:51:25.437829Z","caller":"traceutil/trace.go:171","msg":"trace[5981889] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1337; }","duration":"313.868322ms","start":"2026-05-05T09:51:25.123939Z","end":"2026-05-05T09:51:25.437807Z","steps":["trace[5981889] 'agreement among raft nodes before linearized reading' (duration: 313.664167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:25.437826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.64595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:51:25.437878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:25.123921Z","time spent":"313.948784ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T09:51:25.437921Z","caller":"traceutil/trace.go:171","msg":"trace[1404680286] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1337; }","duration":"198.798664ms","start":"2026-05-05T09:51:25.239099Z","end":"2026-05-05T09:51:25.437897Z","steps":["trace[1404680286] 'agreement among raft nodes before linearized reading' (duration: 198.62556ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:25.584051Z","caller":"traceutil/trace.go:171","msg":"trace[591065749] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"103.753967ms","start":"2026-05-05T09:51:25.480268Z","end":"2026-05-05T09:51:25.584022Z","steps":["trace[591065749] 'read index received' (duration: 103.573603ms)","trace[591065749] 'applied index is now lower than readState.Index' (duration: 178.674µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:51:25.584127Z","caller":"traceutil/trace.go:171","msg":"trace[1148118714] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"140.4031ms","start":"2026-05-05T09:51:25.443672Z","end":"2026-05-05T09:51:25.584075Z","steps":["trace[1148118714] 'process raft request' (duration: 140.180855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:25.584318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.023913ms","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:527"} {"level":"info","ts":"2026-05-05T09:51:25.584368Z","caller":"traceutil/trace.go:171","msg":"trace[845359851] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1339; }","duration":"104.126676ms","start":"2026-05-05T09:51:25.480227Z","end":"2026-05-05T09:51:25.584354Z","steps":["trace[845359851] 'agreement among raft nodes before linearized reading' (duration: 103.905821ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:25.590016Z","caller":"traceutil/trace.go:171","msg":"trace[1330657529] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"105.631169ms","start":"2026-05-05T09:51:25.484362Z","end":"2026-05-05T09:51:25.589993Z","steps":["trace[1330657529] 'process raft request' (duration: 105.510607ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:25.85994Z","caller":"traceutil/trace.go:171","msg":"trace[536723930] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"247.989376ms","start":"2026-05-05T09:51:25.611927Z","end":"2026-05-05T09:51:25.859916Z","steps":["trace[536723930] 'process raft request' (duration: 247.902074ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:25.859942Z","caller":"traceutil/trace.go:171","msg":"trace[1131726443] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"263.231597ms","start":"2026-05-05T09:51:25.596675Z","end":"2026-05-05T09:51:25.859907Z","steps":["trace[1131726443] 'process raft request' (duration: 252.832347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:34.544781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799646242,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:51:34.57419Z","caller":"traceutil/trace.go:171","msg":"trace[491538226] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"581.825587ms","start":"2026-05-05T09:51:33.992344Z","end":"2026-05-05T09:51:34.57417Z","steps":["trace[491538226] 'process raft request' (duration: 581.675494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:34.574346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:33.992321Z","time spent":"581.920199ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:51:34.59262Z","caller":"traceutil/trace.go:171","msg":"trace[1030173681] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1460; }","duration":"548.765658ms","start":"2026-05-05T09:51:34.043825Z","end":"2026-05-05T09:51:34.59259Z","steps":["trace[1030173681] 'read index received' (duration: 530.165489ms)","trace[1030173681] 'applied index is now lower than readState.Index' (duration: 18.598769ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:51:34.592662Z","caller":"traceutil/trace.go:171","msg":"trace[627515423] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"553.249741ms","start":"2026-05-05T09:51:34.039394Z","end":"2026-05-05T09:51:34.592643Z","steps":["trace[627515423] 'process raft request' (duration: 553.049226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:34.592869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.033133ms","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-05-05T09:51:34.59293Z","caller":"traceutil/trace.go:171","msg":"trace[939099373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1402; }","duration":"549.165456ms","start":"2026-05-05T09:51:34.04375Z","end":"2026-05-05T09:51:34.592916Z","steps":["trace[939099373] 'agreement among raft nodes before linearized reading' (duration: 548.931181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:34.592909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.754775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:51:34.592981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:34.043721Z","time spent":"549.250788ms","remote":"127.0.0.1:46964","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-05-05T09:51:34.592998Z","caller":"traceutil/trace.go:171","msg":"trace[464688824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1402; }","duration":"345.888318ms","start":"2026-05-05T09:51:34.247088Z","end":"2026-05-05T09:51:34.592976Z","steps":["trace[464688824] 'agreement among raft nodes before linearized reading' (duration: 345.751915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:34.593066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:34.247063Z","time spent":"345.993571ms","remote":"127.0.0.1:37758","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-05-05T09:51:34.593067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.015656ms","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-05-05T09:51:34.592926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.74144ms","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-05-05T09:51:34.593111Z","caller":"traceutil/trace.go:171","msg":"trace[302920308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1402; }","duration":"447.080168ms","start":"2026-05-05T09:51:34.146014Z","end":"2026-05-05T09:51:34.593094Z","steps":["trace[302920308] 'agreement among raft nodes before linearized reading' (duration: 446.761471ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:34.59324Z","caller":"traceutil/trace.go:171","msg":"trace[868860194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1402; }","duration":"261.040727ms","start":"2026-05-05T09:51:34.332125Z","end":"2026-05-05T09:51:34.593166Z","steps":["trace[868860194] 'agreement among raft nodes before linearized reading' (duration: 260.685669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:34.593343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:34.145994Z","time spent":"447.329333ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:51:34.593356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.037683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-05-05T09:51:34.593479Z","caller":"traceutil/trace.go:171","msg":"trace[1155706871] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1402; }","duration":"386.245588ms","start":"2026-05-05T09:51:34.207199Z","end":"2026-05-05T09:51:34.593444Z","steps":["trace[1155706871] 'agreement among raft nodes before linearized reading' (duration: 385.736466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:34.593588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:34.207172Z","time spent":"386.402761ms","remote":"127.0.0.1:37868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-05T09:51:34.593595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:51:34.039368Z","time spent":"553.395323ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:51:34.758448Z","caller":"traceutil/trace.go:171","msg":"trace[925437590] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"160.526583ms","start":"2026-05-05T09:51:34.597886Z","end":"2026-05-05T09:51:34.758412Z","steps":["trace[925437590] 'process raft request' (duration: 80.00808ms)","trace[925437590] 'compare' (duration: 79.9353ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:51:34.758535Z","caller":"traceutil/trace.go:171","msg":"trace[244357031] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"160.044783ms","start":"2026-05-05T09:51:34.598449Z","end":"2026-05-05T09:51:34.758494Z","steps":["trace[244357031] 'process raft request' (duration: 159.892279ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:34.758814Z","caller":"traceutil/trace.go:171","msg":"trace[617139228] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"157.065574ms","start":"2026-05-05T09:51:34.601734Z","end":"2026-05-05T09:51:34.758799Z","steps":["trace[617139228] 'process raft request' (duration: 156.869699ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:34.758838Z","caller":"traceutil/trace.go:171","msg":"trace[1645153222] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"159.325666ms","start":"2026-05-05T09:51:34.599496Z","end":"2026-05-05T09:51:34.758822Z","steps":["trace[1645153222] 'process raft request' (duration: 158.935227ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:53:36.640193Z","caller":"traceutil/trace.go:171","msg":"trace[1557236570] linearizableReadLoop","detail":"{readStateIndex:2106; appliedIndex:2105; }","duration":"497.339985ms","start":"2026-05-05T09:53:36.142833Z","end":"2026-05-05T09:53:36.640173Z","steps":["trace[1557236570] 'read index received' (duration: 496.225601ms)","trace[1557236570] 'applied index is now lower than readState.Index' (duration: 1.113484ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:53:36.640311Z","caller":"traceutil/trace.go:171","msg":"trace[1368878152] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"508.233762ms","start":"2026-05-05T09:53:36.132066Z","end":"2026-05-05T09:53:36.640299Z","steps":["trace[1368878152] 'process raft request' (duration: 507.038896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:36.640419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:36.132055Z","time spent":"508.285203ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:53:36.640479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.984687ms","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-05-05T09:53:36.640567Z","caller":"traceutil/trace.go:171","msg":"trace[1179114039] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2022; }","duration":"493.076839ms","start":"2026-05-05T09:53:36.147467Z","end":"2026-05-05T09:53:36.640544Z","steps":["trace[1179114039] 'agreement among raft nodes before linearized reading' (duration: 492.852974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:36.640612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:36.147414Z","time spent":"493.189241ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T09:53:36.640771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.934939ms","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:527"} {"level":"info","ts":"2026-05-05T09:53:36.640805Z","caller":"traceutil/trace.go:171","msg":"trace[1151797376] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2022; }","duration":"497.976069ms","start":"2026-05-05T09:53:36.14282Z","end":"2026-05-05T09:53:36.640796Z","steps":["trace[1151797376] 'agreement among raft nodes before linearized reading' (duration: 497.889577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:36.640811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.512112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:53:36.640831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:36.142815Z","time spent":"498.01057ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T09:53:36.640862Z","caller":"traceutil/trace.go:171","msg":"trace[1780335875] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2022; }","duration":"402.614865ms","start":"2026-05-05T09:53:36.238236Z","end":"2026-05-05T09:53:36.640851Z","steps":["trace[1780335875] 'agreement among raft nodes before linearized reading' (duration: 402.514312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:36.640901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:36.238216Z","time spent":"402.676035ms","remote":"127.0.0.1:43050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:53:36.640909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.739737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T09:53:36.640991Z","caller":"traceutil/trace.go:171","msg":"trace[1547926166] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2022; }","duration":"391.854019ms","start":"2026-05-05T09:53:36.249119Z","end":"2026-05-05T09:53:36.640973Z","steps":["trace[1547926166] 'agreement among raft nodes before linearized reading' (duration: 391.625904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:36.641043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:36.249105Z","time spent":"391.923172ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T09:53:36.641262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.554157ms","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:509"} {"level":"info","ts":"2026-05-05T09:53:36.641373Z","caller":"traceutil/trace.go:171","msg":"trace[2033181065] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2022; }","duration":"389.718221ms","start":"2026-05-05T09:53:36.251631Z","end":"2026-05-05T09:53:36.641349Z","steps":["trace[2033181065] 'agreement among raft nodes before linearized reading' (duration: 389.177089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:36.641609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:36.251609Z","time spent":"389.944656ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T09:53:39.36992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799647836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:53:39.870291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799647836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:53:40.612049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799647836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:53:41.113186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799647836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:53:41.246767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000085704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-05T09:53:41.246856Z","caller":"traceutil/trace.go:171","msg":"trace[1564408829] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000194146s","start":"2026-05-05T09:53:39.246646Z","end":"2026-05-05T09:53:41.24684Z","steps":["trace[1564408829] 'agreement among raft nodes before linearized reading' (duration: 2.000080744s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:41.246896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:39.246589Z","time spent":"2.000300968s","remote":"127.0.0.1:37758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T09:53:41.614131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799647836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:53:42.114891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364356799647836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:53:42.392977Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.740861078s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T09:53:42.393348Z","caller":"traceutil/trace.go:171","msg":"trace[1149561486] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"3.741314779s","start":"2026-05-05T09:53:38.652009Z","end":"2026-05-05T09:53:42.393324Z","steps":["trace[1149561486] 'process raft request' (duration: 3.741197447s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.393509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:38.651986Z","time spent":"3.741446593s","remote":"127.0.0.1:37868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:53:42.39623Z","caller":"traceutil/trace.go:171","msg":"trace[1922416222] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"3.378048468s","start":"2026-05-05T09:53:39.018152Z","end":"2026-05-05T09:53:42.396201Z","steps":["trace[1922416222] 'process raft request' (duration: 3.377927876s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.396406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:39.018124Z","time spent":"3.378192532s","remote":"127.0.0.1:46964","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-05-05T09:53:42.406877Z","caller":"traceutil/trace.go:171","msg":"trace[816160906] linearizableReadLoop","detail":"{readStateIndex:2122; appliedIndex:2119; }","duration":"3.536979368s","start":"2026-05-05T09:53:38.86988Z","end":"2026-05-05T09:53:42.40686Z","steps":["trace[816160906] 'read index received' (duration: 3.523332339s)","trace[816160906] 'applied index is now lower than readState.Index' (duration: 13.645949ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:53:42.407068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.036851981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T09:53:42.407096Z","caller":"traceutil/trace.go:171","msg":"trace[1530572637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2036; }","duration":"3.036926663s","start":"2026-05-05T09:53:39.370162Z","end":"2026-05-05T09:53:42.407089Z","steps":["trace[1530572637] 'agreement among raft nodes before linearized reading' (duration: 3.036797759s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.407057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.537131731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:53:42.407158Z","caller":"traceutil/trace.go:171","msg":"trace[927314286] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2036; }","duration":"3.537271605s","start":"2026-05-05T09:53:38.869863Z","end":"2026-05-05T09:53:42.407134Z","steps":["trace[927314286] 'agreement among raft nodes before linearized reading' (duration: 3.537058269s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.407179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.015892744s","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-05-05T09:53:42.407201Z","caller":"traceutil/trace.go:171","msg":"trace[1650452987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2036; }","duration":"3.015926955s","start":"2026-05-05T09:53:39.391269Z","end":"2026-05-05T09:53:42.407196Z","steps":["trace[1650452987] 'agreement among raft nodes before linearized reading' (duration: 3.015862574s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.407222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:39.391261Z","time spent":"3.015957986s","remote":"127.0.0.1:46964","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-05-05T09:53:42.40721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:38.869798Z","time spent":"3.537403408s","remote":"127.0.0.1:47062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:53:42.407182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.948770509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:53:42.407214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159544575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:53:42.407319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.02318389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T09:53:42.407338Z","caller":"traceutil/trace.go:171","msg":"trace[395662462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2036; }","duration":"3.023248521s","start":"2026-05-05T09:53:39.384084Z","end":"2026-05-05T09:53:42.407333Z","steps":["trace[395662462] 'agreement among raft nodes before linearized reading' (duration: 3.02320235s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.407334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750621649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:53:42.40735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:39.384064Z","time spent":"3.023283203s","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T09:53:42.407365Z","caller":"traceutil/trace.go:171","msg":"trace[105296234] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2036; }","duration":"1.75069098s","start":"2026-05-05T09:53:40.656664Z","end":"2026-05-05T09:53:42.407355Z","steps":["trace[105296234] 'agreement among raft nodes before linearized reading' (duration: 1.750646279s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.407119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:39.370139Z","time spent":"3.036974683s","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T09:53:42.407368Z","caller":"traceutil/trace.go:171","msg":"trace[401581253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2036; }","duration":"1.159719179s","start":"2026-05-05T09:53:41.247598Z","end":"2026-05-05T09:53:42.407317Z","steps":["trace[401581253] 'agreement among raft nodes before linearized reading' (duration: 1.159443553s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.407406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:40.656644Z","time spent":"1.750753112s","remote":"127.0.0.1:37912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:53:42.407473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:41.247578Z","time spent":"1.159873052s","remote":"127.0.0.1:37750","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-05-05T09:53:42.40728Z","caller":"traceutil/trace.go:171","msg":"trace[1218285959] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2036; }","duration":"2.948926183s","start":"2026-05-05T09:53:39.458333Z","end":"2026-05-05T09:53:42.40726Z","steps":["trace[1218285959] 'agreement among raft nodes before linearized reading' (duration: 2.948749089s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.408263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:39.458327Z","time spent":"2.949923706s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:53:42.40755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.032996032s","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-05-05T09:53:42.408641Z","caller":"traceutil/trace.go:171","msg":"trace[1174698217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2036; }","duration":"3.034079536s","start":"2026-05-05T09:53:39.374545Z","end":"2026-05-05T09:53:42.408625Z","steps":["trace[1174698217] 'agreement among raft nodes before linearized reading' (duration: 3.032937631s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.408731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:39.374529Z","time spent":"3.034184959s","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:53:42.407605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.027494207s","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-05-05T09:53:42.409011Z","caller":"traceutil/trace.go:171","msg":"trace[1744105287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2036; }","duration":"3.028954301s","start":"2026-05-05T09:53:39.380044Z","end":"2026-05-05T09:53:42.408998Z","steps":["trace[1744105287] 'agreement among raft nodes before linearized reading' (duration: 3.027523678s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.409089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:39.380016Z","time spent":"3.029060802s","remote":"127.0.0.1:46964","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-05-05T09:53:42.407614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793007431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:53:42.409331Z","caller":"traceutil/trace.go:171","msg":"trace[501877654] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2036; }","duration":"1.794733431s","start":"2026-05-05T09:53:40.614586Z","end":"2026-05-05T09:53:42.409319Z","steps":["trace[501877654] 'agreement among raft nodes before linearized reading' (duration: 1.792994062s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.409415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:40.614581Z","time spent":"1.794783792s","remote":"127.0.0.1:37956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:53:42.407637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.406996ms","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:509"} {"level":"info","ts":"2026-05-05T09:53:42.40965Z","caller":"traceutil/trace.go:171","msg":"trace[940569954] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2036; }","duration":"757.433952ms","start":"2026-05-05T09:53:41.652197Z","end":"2026-05-05T09:53:42.409631Z","steps":["trace[940569954] 'agreement among raft nodes before linearized reading' (duration: 755.403446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.409803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:41.652181Z","time spent":"757.575915ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T09:53:42.407666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.019737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:53:42.410033Z","caller":"traceutil/trace.go:171","msg":"trace[1571082808] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2036; }","duration":"646.3926ms","start":"2026-05-05T09:53:41.76362Z","end":"2026-05-05T09:53:42.410013Z","steps":["trace[1571082808] 'agreement among raft nodes before linearized reading' (duration: 644.028446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.410133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:41.76361Z","time spent":"646.499622ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:53:42.407678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793119904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:53:42.410275Z","caller":"traceutil/trace.go:171","msg":"trace[1883202364] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2036; }","duration":"1.795657203s","start":"2026-05-05T09:53:40.61455Z","end":"2026-05-05T09:53:42.410207Z","steps":["trace[1883202364] 'agreement among raft nodes before linearized reading' (duration: 1.793099124s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.4104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:40.614493Z","time spent":"1.795862957s","remote":"127.0.0.1:47090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:53:42.407707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.911438ms","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-05-05T09:53:42.410719Z","caller":"traceutil/trace.go:171","msg":"trace[1430187387] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2036; }","duration":"758.928637ms","start":"2026-05-05T09:53:41.651773Z","end":"2026-05-05T09:53:42.410702Z","steps":["trace[1430187387] 'agreement among raft nodes before linearized reading' (duration: 755.908098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.410777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:41.651763Z","time spent":"758.998068ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T09:53:42.40775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.544997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:53:42.411062Z","caller":"traceutil/trace.go:171","msg":"trace[208651692] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2036; }","duration":"846.896493ms","start":"2026-05-05T09:53:41.56415Z","end":"2026-05-05T09:53:42.411047Z","steps":["trace[208651692] 'agreement among raft nodes before linearized reading' (duration: 843.574768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.411117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:41.564128Z","time spent":"846.973966ms","remote":"127.0.0.1:37868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":18,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:53:42.407899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.666142ms","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:527"} {"level":"info","ts":"2026-05-05T09:53:42.411373Z","caller":"traceutil/trace.go:171","msg":"trace[870020446] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2036; }","duration":"759.167161ms","start":"2026-05-05T09:53:41.65219Z","end":"2026-05-05T09:53:42.411357Z","steps":["trace[870020446] 'agreement among raft nodes before linearized reading' (duration: 755.517819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.411434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:41.652176Z","time spent":"759.243484ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T09:53:42.40795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.546305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T09:53:42.411759Z","caller":"traceutil/trace.go:171","msg":"trace[632692210] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2036; }","duration":"761.411653ms","start":"2026-05-05T09:53:41.650334Z","end":"2026-05-05T09:53:42.411745Z","steps":["trace[632692210] 'agreement among raft nodes before linearized reading' (duration: 757.381862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:53:42.4119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:53:41.65031Z","time spent":"761.573876ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "}