{"level":"warn","ts":"2026-01-31T13:28:12.306369Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T13:28:12.306452Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-01-31T13:28:12.306521Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T13:28:12.30653Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-01-31T13:28:12.30656Z","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-01-31T13:28:12.307235Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-01-31T13:28:12.307475Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-01-31T13:28:12.316928Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.199858ms"} {"level":"info","ts":"2026-01-31T13:28:12.327094Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-01-31T13:28:12.327216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T13:28:12.327263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-01-31T13:28:12.327284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T13:28:12.32729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-01-31T13:28:12.327336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-01-31T13:28:12.338766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T13:28:12.340904Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T13:28:12.345437Z","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-01-31T13:28:12.348943Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T13:28:12.349301Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T13:28:12.349494Z","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-01-31T13:28:12.349527Z","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-01-31T13:28:12.349536Z","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-01-31T13:28:12.349905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-01-31T13:28:12.350604Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-01-31T13:28:12.365619Z","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-01-31T13:28:12.365941Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T13:28:12.365969Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T13:28:12.365948Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-01-31T13:28:12.36604Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-01-31T13:28:12.82814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T13:28:12.828177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T13:28:12.828191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-01-31T13:28:12.828288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-01-31T13:28:12.828393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-01-31T13:28:12.828443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-01-31T13:28:12.828457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-01-31T13:28:12.8295Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T13:28:12.831317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T13:28:12.831383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T13:28:12.831779Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T13:28:12.831942Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T13:28:12.831966Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T13:28:12.84315Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T13:28:12.843426Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T13:28:12.843812Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-01-31T13:28:12.846736Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T13:28:12.846768Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T13:28:42.628042Z","caller":"traceutil/trace.go:171","msg":"trace[1697664376] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"475.620461ms","start":"2026-01-31T13:28:42.152399Z","end":"2026-01-31T13:28:42.62802Z","steps":["trace[1697664376] 'process raft request' (duration: 475.488487ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:28:42.628567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:28:42.152387Z","time spent":"475.729544ms","remote":"127.0.0.1:35890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4253,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:28:42.629127Z","caller":"traceutil/trace.go:171","msg":"trace[1660881875] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:526; }","duration":"454.285336ms","start":"2026-01-31T13:28:42.174827Z","end":"2026-01-31T13:28:42.629112Z","steps":["trace[1660881875] 'read index received' (duration: 454.280096ms)","trace[1660881875] 'applied index is now lower than readState.Index' (duration: 4.37µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:28:42.629278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.937607ms","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-01-31T13:28:42.629324Z","caller":"traceutil/trace.go:171","msg":"trace[911844021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"175.0159ms","start":"2026-01-31T13:28:42.454298Z","end":"2026-01-31T13:28:42.629314Z","steps":["trace[911844021] 'agreement among raft nodes before linearized reading' (duration: 174.890937ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:28:42.629694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.87864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:28:42.629736Z","caller":"traceutil/trace.go:171","msg":"trace[1771150604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"454.921491ms","start":"2026-01-31T13:28:42.174804Z","end":"2026-01-31T13:28:42.629726Z","steps":["trace[1771150604] 'agreement among raft nodes before linearized reading' (duration: 454.752717ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:28:42.629764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:28:42.174766Z","time spent":"454.991462ms","remote":"127.0.0.1:35742","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-01-31T13:28:44.861966Z","caller":"traceutil/trace.go:171","msg":"trace[2069956957] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"212.6449ms","start":"2026-01-31T13:28:44.649304Z","end":"2026-01-31T13:28:44.861949Z","steps":["trace[2069956957] 'process raft request' (duration: 212.533567ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:28:45.472239Z","caller":"traceutil/trace.go:171","msg":"trace[734744219] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"103.542339ms","start":"2026-01-31T13:28:45.368673Z","end":"2026-01-31T13:28:45.472215Z","steps":["trace[734744219] 'process raft request' (duration: 103.420307ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:28:45.873733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.790619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:28:45.873897Z","caller":"traceutil/trace.go:171","msg":"trace[27977855] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"391.52098ms","start":"2026-01-31T13:28:45.482358Z","end":"2026-01-31T13:28:45.873879Z","steps":["trace[27977855] 'process raft request' (duration: 149.379923ms)","trace[27977855] 'compare' (duration: 241.456941ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:28:45.873959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:28:45.482339Z","time spent":"391.588023ms","remote":"127.0.0.1:35960","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-01-31T13:28:49.226055Z","caller":"traceutil/trace.go:171","msg":"trace[1918109791] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"353.180884ms","start":"2026-01-31T13:28:48.87286Z","end":"2026-01-31T13:28:49.226041Z","steps":["trace[1918109791] 'process raft request' (duration: 353.096882ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:28:49.226156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:28:48.872848Z","time spent":"353.252056ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:28:49.499416Z","caller":"traceutil/trace.go:171","msg":"trace[1563723748] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"273.016649ms","start":"2026-01-31T13:28:49.226391Z","end":"2026-01-31T13:28:49.499408Z","steps":["trace[1563723748] 'read index received' (duration: 243.986962ms)","trace[1563723748] 'applied index is now lower than readState.Index' (duration: 29.029277ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:28:49.499471Z","caller":"traceutil/trace.go:171","msg":"trace[1224604166] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"315.421581ms","start":"2026-01-31T13:28:49.184044Z","end":"2026-01-31T13:28:49.499466Z","steps":["trace[1224604166] 'process raft request' (duration: 286.326712ms)","trace[1224604166] 'compare' (duration: 28.795711ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:28:49.499524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:28:49.183992Z","time spent":"315.493153ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:28:49.49962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.293259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:28:49.499636Z","caller":"traceutil/trace.go:171","msg":"trace[99046752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"326.316539ms","start":"2026-01-31T13:28:49.173313Z","end":"2026-01-31T13:28:49.49963Z","steps":["trace[99046752] 'agreement among raft nodes before linearized reading' (duration: 326.284168ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:28:49.499648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:28:49.173307Z","time spent":"326.337519ms","remote":"127.0.0.1:35742","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-01-31T13:28:49.499725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.184515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-31T13:28:49.499738Z","caller":"traceutil/trace.go:171","msg":"trace[490159159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"334.206886ms","start":"2026-01-31T13:28:49.165526Z","end":"2026-01-31T13:28:49.499733Z","steps":["trace[490159159] 'agreement among raft nodes before linearized reading' (duration: 334.183686ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:28:49.49975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:28:49.165521Z","time spent":"334.225717ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-31T13:28:49.500001Z","caller":"traceutil/trace.go:171","msg":"trace[921519527] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"269.961206ms","start":"2026-01-31T13:28:49.229431Z","end":"2026-01-31T13:28:49.499392Z","steps":["trace[921519527] 'process raft request' (duration: 269.935506ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:28:50.819034Z","caller":"traceutil/trace.go:171","msg":"trace[815633487] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"287.46745ms","start":"2026-01-31T13:28:50.531508Z","end":"2026-01-31T13:28:50.818975Z","steps":["trace[815633487] 'process raft request' (duration: 287.344747ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:28:55.92101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.086502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-01-31T13:28:55.921081Z","caller":"traceutil/trace.go:171","msg":"trace[14287613] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"211.188125ms","start":"2026-01-31T13:28:55.709878Z","end":"2026-01-31T13:28:55.921066Z","steps":["trace[14287613] 'range keys from in-memory index tree' (duration: 211.018221ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:03.495943Z","caller":"traceutil/trace.go:171","msg":"trace[1153871924] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"212.535667ms","start":"2026-01-31T13:29:03.283389Z","end":"2026-01-31T13:29:03.495925Z","steps":["trace[1153871924] 'read index received' (duration: 212.391174ms)","trace[1153871924] 'applied index is now lower than readState.Index' (duration: 143.983µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:03.496114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.708251ms","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-01-31T13:29:03.496134Z","caller":"traceutil/trace.go:171","msg":"trace[1569628638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"212.780753ms","start":"2026-01-31T13:29:03.283346Z","end":"2026-01-31T13:29:03.496127Z","steps":["trace[1569628638] 'agreement among raft nodes before linearized reading' (duration: 212.641489ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:03.49628Z","caller":"traceutil/trace.go:171","msg":"trace[1835508403] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"383.245185ms","start":"2026-01-31T13:29:03.11303Z","end":"2026-01-31T13:29:03.496275Z","steps":["trace[1835508403] 'process raft request' (duration: 382.787954ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:03.49632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:03.113014Z","time spent":"383.278005ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T13:29:03.882431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.828797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:29:03.882571Z","caller":"traceutil/trace.go:171","msg":"trace[1645752717] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"369.642384ms","start":"2026-01-31T13:29:03.512913Z","end":"2026-01-31T13:29:03.882556Z","steps":["trace[1645752717] 'read index received' (duration: 87.630153ms)","trace[1645752717] 'applied index is now lower than readState.Index' (duration: 282.011631ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:29:03.882633Z","caller":"traceutil/trace.go:171","msg":"trace[527694533] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"383.783018ms","start":"2026-01-31T13:29:03.498842Z","end":"2026-01-31T13:29:03.882625Z","steps":["trace[527694533] 'process raft request' (duration: 101.692405ms)","trace[527694533] 'compare' (duration: 281.657703ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:03.882671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:03.498825Z","time spent":"383.82433ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:03.882753Z","caller":"traceutil/trace.go:171","msg":"trace[1201266974] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"363.219343ms","start":"2026-01-31T13:29:03.519509Z","end":"2026-01-31T13:29:03.882729Z","steps":["trace[1201266974] 'process raft request' (duration: 363.000758ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:03.882882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.966041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T13:29:03.882903Z","caller":"traceutil/trace.go:171","msg":"trace[735227978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:660; }","duration":"370.006873ms","start":"2026-01-31T13:29:03.51289Z","end":"2026-01-31T13:29:03.882897Z","steps":["trace[735227978] 'agreement among raft nodes before linearized reading' (duration: 369.9084ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:03.882922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:03.512883Z","time spent":"370.035053ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T13:29:03.882902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:03.51949Z","time spent":"363.339435ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T13:29:03.883056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.897553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T13:29:03.883076Z","caller":"traceutil/trace.go:171","msg":"trace[181589468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"258.959235ms","start":"2026-01-31T13:29:03.62411Z","end":"2026-01-31T13:29:03.883069Z","steps":["trace[181589468] 'agreement among raft nodes before linearized reading' (duration: 258.906183ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:03.883226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.099647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T13:29:03.883243Z","caller":"traceutil/trace.go:171","msg":"trace[139800765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"182.142699ms","start":"2026-01-31T13:29:03.701096Z","end":"2026-01-31T13:29:03.883238Z","steps":["trace[139800765] 'agreement among raft nodes before linearized reading' (duration: 182.086877ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:03.883458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.142481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T13:29:03.883523Z","caller":"traceutil/trace.go:171","msg":"trace[2130288565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"254.233604ms","start":"2026-01-31T13:29:03.629278Z","end":"2026-01-31T13:29:03.883512Z","steps":["trace[2130288565] 'agreement among raft nodes before linearized reading' (duration: 254.040129ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:04.566452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.654185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:29:04.56651Z","caller":"traceutil/trace.go:171","msg":"trace[1675870833] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"678.557981ms","start":"2026-01-31T13:29:03.88794Z","end":"2026-01-31T13:29:04.566498Z","steps":["trace[1675870833] 'process raft request' (duration: 100.794995ms)","trace[1675870833] 'compare' (duration: 577.544051ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:04.56657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:03.887927Z","time spent":"678.629803ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:04.635559Z","caller":"traceutil/trace.go:171","msg":"trace[1955034547] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:681; }","duration":"457.598615ms","start":"2026-01-31T13:29:04.177946Z","end":"2026-01-31T13:29:04.635545Z","steps":["trace[1955034547] 'read index received' (duration: 457.443082ms)","trace[1955034547] 'applied index is now lower than readState.Index' (duration: 155.053µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:04.635649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.691428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:04.635665Z","caller":"traceutil/trace.go:171","msg":"trace[1119316115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"457.78041ms","start":"2026-01-31T13:29:04.17788Z","end":"2026-01-31T13:29:04.63566Z","steps":["trace[1119316115] 'agreement among raft nodes before linearized reading' (duration: 457.717939ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:04.635683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:04.177865Z","time spent":"457.814851ms","remote":"127.0.0.1:35742","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-01-31T13:29:04.635889Z","caller":"traceutil/trace.go:171","msg":"trace[1131187193] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"745.652168ms","start":"2026-01-31T13:29:03.890231Z","end":"2026-01-31T13:29:04.635883Z","steps":["trace[1131187193] 'process raft request' (duration: 745.198988ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:04.635935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:03.890215Z","time spent":"745.68732ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:04.635999Z","caller":"traceutil/trace.go:171","msg":"trace[132220652] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"733.202375ms","start":"2026-01-31T13:29:03.902792Z","end":"2026-01-31T13:29:04.635995Z","steps":["trace[132220652] 'process raft request' (duration: 732.712484ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:04.636021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:03.902781Z","time spent":"733.227295ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:04.636075Z","caller":"traceutil/trace.go:171","msg":"trace[1876571768] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"733.188145ms","start":"2026-01-31T13:29:03.902883Z","end":"2026-01-31T13:29:04.636072Z","steps":["trace[1876571768] 'process raft request' (duration: 732.643672ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:04.636096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:03.902872Z","time spent":"733.212215ms","remote":"127.0.0.1:35960","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-01-31T13:29:08.830704Z","caller":"traceutil/trace.go:171","msg":"trace[1146059126] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"233.311129ms","start":"2026-01-31T13:29:08.597376Z","end":"2026-01-31T13:29:08.830688Z","steps":["trace[1146059126] 'read index received' (duration: 233.217137ms)","trace[1146059126] 'applied index is now lower than readState.Index' (duration: 93.172µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:29:08.830723Z","caller":"traceutil/trace.go:171","msg":"trace[1323649640] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"475.14068ms","start":"2026-01-31T13:29:08.355561Z","end":"2026-01-31T13:29:08.830701Z","steps":["trace[1323649640] 'process raft request' (duration: 475.041588ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:08.830849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:08.35552Z","time spent":"475.272503ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T13:29:08.830874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.484283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T13:29:08.830896Z","caller":"traceutil/trace.go:171","msg":"trace[1386977342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"233.551125ms","start":"2026-01-31T13:29:08.597338Z","end":"2026-01-31T13:29:08.830889Z","steps":["trace[1386977342] 'agreement among raft nodes before linearized reading' (duration: 233.437652ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:08.831097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.31706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T13:29:08.831127Z","caller":"traceutil/trace.go:171","msg":"trace[479065713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:712; }","duration":"173.370872ms","start":"2026-01-31T13:29:08.657751Z","end":"2026-01-31T13:29:08.831122Z","steps":["trace[479065713] 'agreement among raft nodes before linearized reading' (duration: 173.3042ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:08.831278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.876285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T13:29:08.831307Z","caller":"traceutil/trace.go:171","msg":"trace[1146993026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"171.914227ms","start":"2026-01-31T13:29:08.659382Z","end":"2026-01-31T13:29:08.831296Z","steps":["trace[1146993026] 'agreement among raft nodes before linearized reading' (duration: 171.810335ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:08.831362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.892846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T13:29:08.831384Z","caller":"traceutil/trace.go:171","msg":"trace[562335332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"171.933057ms","start":"2026-01-31T13:29:08.659446Z","end":"2026-01-31T13:29:08.831379Z","steps":["trace[562335332] 'agreement among raft nodes before linearized reading' (duration: 171.861345ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:13.193855Z","caller":"traceutil/trace.go:171","msg":"trace[269768050] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"342.989563ms","start":"2026-01-31T13:29:12.850848Z","end":"2026-01-31T13:29:13.193838Z","steps":["trace[269768050] 'process raft request' (duration: 342.888191ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:13.193983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:12.850839Z","time spent":"343.067485ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:13.443103Z","caller":"traceutil/trace.go:171","msg":"trace[1724942455] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"588.181523ms","start":"2026-01-31T13:29:12.854897Z","end":"2026-01-31T13:29:13.443079Z","steps":["trace[1724942455] 'read index received' (duration: 339.387448ms)","trace[1724942455] 'applied index is now lower than readState.Index' (duration: 248.792985ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:13.443521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.452492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T13:29:13.443556Z","caller":"traceutil/trace.go:171","msg":"trace[667536950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"586.520674ms","start":"2026-01-31T13:29:12.857025Z","end":"2026-01-31T13:29:13.443545Z","steps":["trace[667536950] 'agreement among raft nodes before linearized reading' (duration: 586.38865ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:13.443586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:12.857014Z","time spent":"586.564614ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T13:29:13.443825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.181368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:13.443854Z","caller":"traceutil/trace.go:171","msg":"trace[1015805710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"272.233109ms","start":"2026-01-31T13:29:13.171612Z","end":"2026-01-31T13:29:13.443845Z","steps":["trace[1015805710] 'agreement among raft nodes before linearized reading' (duration: 272.167977ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:13.444036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.111154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T13:29:13.444089Z","caller":"traceutil/trace.go:171","msg":"trace[1960309437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"589.190616ms","start":"2026-01-31T13:29:12.854885Z","end":"2026-01-31T13:29:13.444075Z","steps":["trace[1960309437] 'agreement among raft nodes before linearized reading' (duration: 588.397227ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:13.444123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:12.85488Z","time spent":"589.237077ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-31T13:29:13.444186Z","caller":"traceutil/trace.go:171","msg":"trace[641029797] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"592.281699ms","start":"2026-01-31T13:29:12.851892Z","end":"2026-01-31T13:29:13.444173Z","steps":["trace[641029797] 'process raft request' (duration: 511.258173ms)","trace[641029797] 'compare' (duration: 79.794707ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:13.444265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:12.851886Z","time spent":"592.32796ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:13.612947Z","caller":"traceutil/trace.go:171","msg":"trace[1797196916] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"163.107328ms","start":"2026-01-31T13:29:13.449822Z","end":"2026-01-31T13:29:13.61293Z","steps":["trace[1797196916] 'process raft request' (duration: 145.893521ms)","trace[1797196916] 'compare' (duration: 16.987222ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:18.122799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.905913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:18.122858Z","caller":"traceutil/trace.go:171","msg":"trace[902775310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"329.994016ms","start":"2026-01-31T13:29:17.792849Z","end":"2026-01-31T13:29:18.122843Z","steps":["trace[902775310] 'range keys from in-memory index tree' (duration: 329.851882ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:18.122885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:17.792835Z","time spent":"330.042957ms","remote":"127.0.0.1:35742","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-01-31T13:29:18.122821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.621838ms","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-01-31T13:29:18.12301Z","caller":"traceutil/trace.go:171","msg":"trace[542097032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:744; }","duration":"116.805962ms","start":"2026-01-31T13:29:18.006166Z","end":"2026-01-31T13:29:18.122972Z","steps":["trace[542097032] 'range keys from in-memory index tree' (duration: 116.496185ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:29.582472Z","caller":"traceutil/trace.go:171","msg":"trace[1245238470] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"323.798978ms","start":"2026-01-31T13:29:29.258652Z","end":"2026-01-31T13:29:29.582451Z","steps":["trace[1245238470] 'read index received' (duration: 323.391658ms)","trace[1245238470] 'applied index is now lower than readState.Index' (duration: 406.17µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:29:29.582838Z","caller":"traceutil/trace.go:171","msg":"trace[30109925] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"332.858524ms","start":"2026-01-31T13:29:29.249938Z","end":"2026-01-31T13:29:29.582797Z","steps":["trace[30109925] 'process raft request' (duration: 332.217219ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:29.582974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:29.24993Z","time spent":"332.972716ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:29:29.583334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.28717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T13:29:29.583379Z","caller":"traceutil/trace.go:171","msg":"trace[696079095] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:775; }","duration":"246.352831ms","start":"2026-01-31T13:29:29.337015Z","end":"2026-01-31T13:29:29.583368Z","steps":["trace[696079095] 'agreement among raft nodes before linearized reading' (duration: 246.27406ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:29.587055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.235878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:29.587372Z","caller":"traceutil/trace.go:171","msg":"trace[620856309] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:775; }","duration":"328.716595ms","start":"2026-01-31T13:29:29.25864Z","end":"2026-01-31T13:29:29.587357Z","steps":["trace[620856309] 'agreement among raft nodes before linearized reading' (duration: 324.189107ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:29.587586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:29.258635Z","time spent":"328.911941ms","remote":"127.0.0.1:35928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-01-31T13:29:29.866615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.787242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T13:29:29.866716Z","caller":"traceutil/trace.go:171","msg":"trace[1527796261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"100.985247ms","start":"2026-01-31T13:29:29.765712Z","end":"2026-01-31T13:29:29.866697Z","steps":["trace[1527796261] 'range keys from in-memory index tree' (duration: 100.508275ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:30.44332Z","caller":"traceutil/trace.go:171","msg":"trace[494752149] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"146.108372ms","start":"2026-01-31T13:29:30.297188Z","end":"2026-01-31T13:29:30.443297Z","steps":["trace[494752149] 'process raft request' (duration: 145.99293ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:31.870497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.082115ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-31T13:29:31.870607Z","caller":"traceutil/trace.go:171","msg":"trace[2030559468] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"280.018164ms","start":"2026-01-31T13:29:31.590562Z","end":"2026-01-31T13:29:31.87058Z","steps":["trace[2030559468] 'read index received' (duration: 40.011µs)","trace[2030559468] 'applied index is now lower than readState.Index' (duration: 279.976703ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:31.871054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.490716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-31T13:29:31.871095Z","caller":"traceutil/trace.go:171","msg":"trace[334101834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"280.578468ms","start":"2026-01-31T13:29:31.590506Z","end":"2026-01-31T13:29:31.871085Z","steps":["trace[334101834] 'agreement among raft nodes before linearized reading' (duration: 280.389934ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:31.871576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.855501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-01-31T13:29:31.871758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.977861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T13:29:31.871803Z","caller":"traceutil/trace.go:171","msg":"trace[856867564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"274.043653ms","start":"2026-01-31T13:29:31.597749Z","end":"2026-01-31T13:29:31.871792Z","steps":["trace[856867564] 'agreement among raft nodes before linearized reading' (duration: 273.93803ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:31.87196Z","caller":"traceutil/trace.go:171","msg":"trace[1068129849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"275.119247ms","start":"2026-01-31T13:29:31.596651Z","end":"2026-01-31T13:29:31.87177Z","steps":["trace[1068129849] 'agreement among raft nodes before linearized reading' (duration: 274.314048ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:31.977454Z","caller":"traceutil/trace.go:171","msg":"trace[1033186000] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"100.152538ms","start":"2026-01-31T13:29:31.877284Z","end":"2026-01-31T13:29:31.977437Z","steps":["trace[1033186000] 'process raft request' (duration: 100.002844ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:32.008863Z","caller":"traceutil/trace.go:171","msg":"trace[466144381] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"108.690311ms","start":"2026-01-31T13:29:31.900159Z","end":"2026-01-31T13:29:32.008849Z","steps":["trace[466144381] 'read index received' (duration: 77.630021ms)","trace[466144381] 'applied index is now lower than readState.Index' (duration: 31.05968ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:32.00904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.868355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T13:29:32.00907Z","caller":"traceutil/trace.go:171","msg":"trace[136578929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"108.935826ms","start":"2026-01-31T13:29:31.900125Z","end":"2026-01-31T13:29:32.009061Z","steps":["trace[136578929] 'agreement among raft nodes before linearized reading' (duration: 108.784113ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:32.009618Z","caller":"traceutil/trace.go:171","msg":"trace[533677053] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"129.963748ms","start":"2026-01-31T13:29:31.879641Z","end":"2026-01-31T13:29:32.009605Z","steps":["trace[533677053] 'process raft request' (duration: 129.104448ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:42.672125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359964285054531,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T13:29:42.738215Z","caller":"traceutil/trace.go:171","msg":"trace[1953656762] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:893; }","duration":"566.444481ms","start":"2026-01-31T13:29:42.171756Z","end":"2026-01-31T13:29:42.7382Z","steps":["trace[1953656762] 'read index received' (duration: 566.313748ms)","trace[1953656762] 'applied index is now lower than readState.Index' (duration: 129.163µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:42.738451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.685586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:42.738472Z","caller":"traceutil/trace.go:171","msg":"trace[214483006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"566.733928ms","start":"2026-01-31T13:29:42.17173Z","end":"2026-01-31T13:29:42.738464Z","steps":["trace[214483006] 'agreement among raft nodes before linearized reading' (duration: 566.691297ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:42.738487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:42.171702Z","time spent":"566.781949ms","remote":"127.0.0.1:35742","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-01-31T13:29:42.738468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.471832ms","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":"info","ts":"2026-01-31T13:29:42.738521Z","caller":"traceutil/trace.go:171","msg":"trace[1009585349] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:863; }","duration":"527.580075ms","start":"2026-01-31T13:29:42.210927Z","end":"2026-01-31T13:29:42.738507Z","steps":["trace[1009585349] 'agreement among raft nodes before linearized reading' (duration: 527.474843ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:42.738563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:42.210913Z","time spent":"527.644236ms","remote":"127.0.0.1:35866","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":"info","ts":"2026-01-31T13:29:42.738591Z","caller":"traceutil/trace.go:171","msg":"trace[1518654977] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"586.252383ms","start":"2026-01-31T13:29:42.152333Z","end":"2026-01-31T13:29:42.738585Z","steps":["trace[1518654977] 'process raft request' (duration: 585.778002ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:42.738638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:42.152323Z","time spent":"586.280993ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:29:42.738808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.073657ms","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-01-31T13:29:42.738832Z","caller":"traceutil/trace.go:171","msg":"trace[1400108409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:863; }","duration":"463.129849ms","start":"2026-01-31T13:29:42.275695Z","end":"2026-01-31T13:29:42.738825Z","steps":["trace[1400108409] 'agreement among raft nodes before linearized reading' (duration: 463.029066ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:42.738869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:42.275677Z","time spent":"463.186899ms","remote":"127.0.0.1:35960","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-01-31T13:29:44.359219Z","caller":"traceutil/trace.go:171","msg":"trace[1654361786] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"311.426852ms","start":"2026-01-31T13:29:44.047768Z","end":"2026-01-31T13:29:44.359195Z","steps":["trace[1654361786] 'read index received' (duration: 311.266069ms)","trace[1654361786] 'applied index is now lower than readState.Index' (duration: 159.893µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:29:44.359437Z","caller":"traceutil/trace.go:171","msg":"trace[413878063] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"316.910834ms","start":"2026-01-31T13:29:44.042514Z","end":"2026-01-31T13:29:44.359425Z","steps":["trace[413878063] 'process raft request' (duration: 316.578496ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:44.359527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:44.042502Z","time spent":"316.966365ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:29:44.35969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.919504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-31T13:29:44.359738Z","caller":"traceutil/trace.go:171","msg":"trace[236981427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:865; }","duration":"311.978426ms","start":"2026-01-31T13:29:44.04775Z","end":"2026-01-31T13:29:44.359728Z","steps":["trace[236981427] 'agreement among raft nodes before linearized reading' (duration: 311.844803ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:44.359769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:44.047742Z","time spent":"312.020737ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T13:29:44.359964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.074446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-31T13:29:44.359994Z","caller":"traceutil/trace.go:171","msg":"trace[163618149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"207.103707ms","start":"2026-01-31T13:29:44.152883Z","end":"2026-01-31T13:29:44.359986Z","steps":["trace[163618149] 'agreement among raft nodes before linearized reading' (duration: 207.026755ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:44.360236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.48374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:44.360267Z","caller":"traceutil/trace.go:171","msg":"trace[2086651811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"177.536781ms","start":"2026-01-31T13:29:44.182722Z","end":"2026-01-31T13:29:44.360259Z","steps":["trace[2086651811] 'agreement among raft nodes before linearized reading' (duration: 177.49257ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:44.981612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.553806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:29:44.981722Z","caller":"traceutil/trace.go:171","msg":"trace[1950824119] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"239.828916ms","start":"2026-01-31T13:29:44.741884Z","end":"2026-01-31T13:29:44.981713Z","steps":["trace[1950824119] 'read index received' (duration: 25.85µs)","trace[1950824119] 'applied index is now lower than readState.Index' (duration: 239.802176ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:29:44.981922Z","caller":"traceutil/trace.go:171","msg":"trace[522744645] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"617.355164ms","start":"2026-01-31T13:29:44.364561Z","end":"2026-01-31T13:29:44.981916Z","steps":["trace[522744645] 'process raft request' (duration: 99.44506ms)","trace[522744645] 'compare' (duration: 517.476174ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:44.981983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:44.364552Z","time spent":"617.404185ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:44.982069Z","caller":"traceutil/trace.go:171","msg":"trace[823631669] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"617.471457ms","start":"2026-01-31T13:29:44.364593Z","end":"2026-01-31T13:29:44.982065Z","steps":["trace[823631669] 'process raft request' (duration: 617.093078ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:44.982091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:44.36458Z","time spent":"617.498477ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:29:44.982196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.316258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-31T13:29:44.982209Z","caller":"traceutil/trace.go:171","msg":"trace[1182438895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:867; }","duration":"240.368879ms","start":"2026-01-31T13:29:44.741836Z","end":"2026-01-31T13:29:44.982205Z","steps":["trace[1182438895] 'agreement among raft nodes before linearized reading' (duration: 240.316878ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:44.982289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.123448ms","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-01-31T13:29:44.982304Z","caller":"traceutil/trace.go:171","msg":"trace[2123755622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:867; }","duration":"231.137378ms","start":"2026-01-31T13:29:44.751162Z","end":"2026-01-31T13:29:44.982299Z","steps":["trace[2123755622] 'agreement among raft nodes before linearized reading' (duration: 231.100917ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:44.982413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.935667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T13:29:44.982426Z","caller":"traceutil/trace.go:171","msg":"trace[1089161675] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:867; }","duration":"176.974028ms","start":"2026-01-31T13:29:44.805448Z","end":"2026-01-31T13:29:44.982422Z","steps":["trace[1089161675] 'agreement among raft nodes before linearized reading' (duration: 176.949258ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:45.144129Z","caller":"traceutil/trace.go:171","msg":"trace[722755293] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"158.387185ms","start":"2026-01-31T13:29:44.985721Z","end":"2026-01-31T13:29:45.144108Z","steps":["trace[722755293] 'process raft request' (duration: 152.543866ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:45.144652Z","caller":"traceutil/trace.go:171","msg":"trace[811967121] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"158.326553ms","start":"2026-01-31T13:29:44.986313Z","end":"2026-01-31T13:29:45.14464Z","steps":["trace[811967121] 'process raft request' (duration: 158.249281ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:53.41005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.380384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-31T13:29:53.410078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.075552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-31T13:29:53.4101Z","caller":"traceutil/trace.go:171","msg":"trace[1446750799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"137.464447ms","start":"2026-01-31T13:29:53.272622Z","end":"2026-01-31T13:29:53.410087Z","steps":["trace[1446750799] 'range keys from in-memory index tree' (duration: 137.316123ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:53.410125Z","caller":"traceutil/trace.go:171","msg":"trace[215264891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"185.163303ms","start":"2026-01-31T13:29:53.224949Z","end":"2026-01-31T13:29:53.410113Z","steps":["trace[215264891] 'range keys from in-memory index tree' (duration: 184.959978ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:55.335688Z","caller":"traceutil/trace.go:171","msg":"trace[1356280912] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"321.484203ms","start":"2026-01-31T13:29:55.014188Z","end":"2026-01-31T13:29:55.335672Z","steps":["trace[1356280912] 'read index received' (duration: 321.37331ms)","trace[1356280912] 'applied index is now lower than readState.Index' (duration: 110.203µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:29:55.335763Z","caller":"traceutil/trace.go:171","msg":"trace[805571527] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"323.697416ms","start":"2026-01-31T13:29:55.012061Z","end":"2026-01-31T13:29:55.335759Z","steps":["trace[805571527] 'process raft request' (duration: 323.508962ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:55.335828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:55.012051Z","time spent":"323.727007ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:29:55.335906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.457154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:55.335951Z","caller":"traceutil/trace.go:171","msg":"trace[2100758507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"165.543225ms","start":"2026-01-31T13:29:55.170397Z","end":"2026-01-31T13:29:55.33594Z","steps":["trace[2100758507] 'agreement among raft nodes before linearized reading' (duration: 165.471324ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:55.336154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.412387ms","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-01-31T13:29:55.336173Z","caller":"traceutil/trace.go:171","msg":"trace[329001796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:894; }","duration":"135.446518ms","start":"2026-01-31T13:29:55.200721Z","end":"2026-01-31T13:29:55.336168Z","steps":["trace[329001796] 'agreement among raft nodes before linearized reading' (duration: 135.363346ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:55.336213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.022326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-31T13:29:55.336229Z","caller":"traceutil/trace.go:171","msg":"trace[1857030545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"322.047796ms","start":"2026-01-31T13:29:55.014176Z","end":"2026-01-31T13:29:55.336224Z","steps":["trace[1857030545] 'agreement among raft nodes before linearized reading' (duration: 321.981664ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:55.336245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:55.014171Z","time spent":"322.067656ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-31T13:29:56.834642Z","caller":"traceutil/trace.go:171","msg":"trace[851793463] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"260.215302ms","start":"2026-01-31T13:29:56.574412Z","end":"2026-01-31T13:29:56.834627Z","steps":["trace[851793463] 'read index received' (duration: 186.605268ms)","trace[851793463] 'applied index is now lower than readState.Index' (duration: 73.609374ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:56.834746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.322164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-01-31T13:29:56.834762Z","caller":"traceutil/trace.go:171","msg":"trace[614446185] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:899; }","duration":"260.374406ms","start":"2026-01-31T13:29:56.574383Z","end":"2026-01-31T13:29:56.834758Z","steps":["trace[614446185] 'agreement among raft nodes before linearized reading' (duration: 260.301314ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:56.834856Z","caller":"traceutil/trace.go:171","msg":"trace[898497231] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"290.381642ms","start":"2026-01-31T13:29:56.544442Z","end":"2026-01-31T13:29:56.834823Z","steps":["trace[898497231] 'process raft request' (duration: 216.634544ms)","trace[898497231] 'compare' (duration: 73.494842ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:57.40928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.084692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T13:29:57.409349Z","caller":"traceutil/trace.go:171","msg":"trace[566713120] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:900; }","duration":"300.183915ms","start":"2026-01-31T13:29:57.109146Z","end":"2026-01-31T13:29:57.40933Z","steps":["trace[566713120] 'count revisions from in-memory index tree' (duration: 300.032401ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:57.409387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:57.109132Z","time spent":"300.244796ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-01-31T13:29:57.409606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.953105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:57.409647Z","caller":"traceutil/trace.go:171","msg":"trace[884834263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"239.025517ms","start":"2026-01-31T13:29:57.170611Z","end":"2026-01-31T13:29:57.409637Z","steps":["trace[884834263] 'range keys from in-memory index tree' (duration: 238.904394ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:57.722776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.494095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:29:57.723017Z","caller":"traceutil/trace.go:171","msg":"trace[1756045986] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:935; }","duration":"301.67922ms","start":"2026-01-31T13:29:57.421288Z","end":"2026-01-31T13:29:57.722968Z","steps":["trace[1756045986] 'read index received' (duration: 22.525278ms)","trace[1756045986] 'applied index is now lower than readState.Index' (duration: 279.152882ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:57.723205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.909056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T13:29:57.723234Z","caller":"traceutil/trace.go:171","msg":"trace[1435082329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"301.949007ms","start":"2026-01-31T13:29:57.421276Z","end":"2026-01-31T13:29:57.723225Z","steps":["trace[1435082329] 'agreement among raft nodes before linearized reading' (duration: 301.788654ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:57.723266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:57.42127Z","time spent":"301.987598ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-31T13:29:57.723542Z","caller":"traceutil/trace.go:171","msg":"trace[746113808] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"305.752117ms","start":"2026-01-31T13:29:57.41776Z","end":"2026-01-31T13:29:57.723512Z","steps":["trace[746113808] 'process raft request' (duration: 305.102232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:57.723715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:57.417755Z","time spent":"305.854309ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:57.723938Z","caller":"traceutil/trace.go:171","msg":"trace[634760020] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"307.864968ms","start":"2026-01-31T13:29:57.416061Z","end":"2026-01-31T13:29:57.723926Z","steps":["trace[634760020] 'process raft request' (duration: 128.098043ms)","trace[634760020] 'compare' (duration: 178.144506ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:57.723994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:57.416055Z","time spent":"307.909159ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:29:59.284103Z","caller":"traceutil/trace.go:171","msg":"trace[762994123] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"112.265946ms","start":"2026-01-31T13:29:59.171819Z","end":"2026-01-31T13:29:59.284085Z","steps":["trace[762994123] 'read index received' (duration: 112.130433ms)","trace[762994123] 'applied index is now lower than readState.Index' (duration: 134.813µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:29:59.284198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.362678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:29:59.284218Z","caller":"traceutil/trace.go:171","msg":"trace[1490852400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"112.420289ms","start":"2026-01-31T13:29:59.171792Z","end":"2026-01-31T13:29:59.284212Z","steps":["trace[1490852400] 'agreement among raft nodes before linearized reading' (duration: 112.357777ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:29:59.284277Z","caller":"traceutil/trace.go:171","msg":"trace[1470998929] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"441.684907ms","start":"2026-01-31T13:29:58.842575Z","end":"2026-01-31T13:29:59.28426Z","steps":["trace[1470998929] 'process raft request' (duration: 441.387939ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:29:59.284368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:29:58.842564Z","time spent":"441.752409ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:30:03.644273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.427038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T13:30:03.644341Z","caller":"traceutil/trace.go:171","msg":"trace[2133902119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"182.530561ms","start":"2026-01-31T13:30:03.461789Z","end":"2026-01-31T13:30:03.644319Z","steps":["trace[2133902119] 'range keys from in-memory index tree' (duration: 182.266875ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:30:03.86267Z","caller":"traceutil/trace.go:171","msg":"trace[1837142498] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"116.530958ms","start":"2026-01-31T13:30:03.746123Z","end":"2026-01-31T13:30:03.862654Z","steps":["trace[1837142498] 'read index received' (duration: 116.403125ms)","trace[1837142498] 'applied index is now lower than readState.Index' (duration: 127.173µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:30:03.862889Z","caller":"traceutil/trace.go:171","msg":"trace[1083527824] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"213.760484ms","start":"2026-01-31T13:30:03.649122Z","end":"2026-01-31T13:30:03.862882Z","steps":["trace[1083527824] 'process raft request' (duration: 213.450797ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:03.863094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.958038ms","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-01-31T13:30:03.863116Z","caller":"traceutil/trace.go:171","msg":"trace[1194651891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"117.009459ms","start":"2026-01-31T13:30:03.7461Z","end":"2026-01-31T13:30:03.86311Z","steps":["trace[1194651891] 'agreement among raft nodes before linearized reading' (duration: 116.889177ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:03.863232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.664667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T13:30:03.863249Z","caller":"traceutil/trace.go:171","msg":"trace[1858807749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"115.689528ms","start":"2026-01-31T13:30:03.747553Z","end":"2026-01-31T13:30:03.863243Z","steps":["trace[1858807749] 'agreement among raft nodes before linearized reading' (duration: 115.643626ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:03.863381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.155855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T13:30:03.863397Z","caller":"traceutil/trace.go:171","msg":"trace[635020602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"115.180605ms","start":"2026-01-31T13:30:03.748212Z","end":"2026-01-31T13:30:03.863392Z","steps":["trace[635020602] 'agreement among raft nodes before linearized reading' (duration: 115.133494ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:04.570548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.936559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:30:04.570648Z","caller":"traceutil/trace.go:171","msg":"trace[1023489354] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"704.312087ms","start":"2026-01-31T13:30:03.866317Z","end":"2026-01-31T13:30:04.570629Z","steps":["trace[1023489354] 'process raft request' (duration: 229.204453ms)","trace[1023489354] 'compare' (duration: 474.819437ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:30:04.570704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:03.866306Z","time spent":"704.371418ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:30:05.146546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.158448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:30:05.146912Z","caller":"traceutil/trace.go:171","msg":"trace[422773037] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.279107207s","start":"2026-01-31T13:30:03.867792Z","end":"2026-01-31T13:30:05.146899Z","steps":["trace[422773037] 'process raft request' (duration: 870.541999ms)","trace[422773037] 'compare' (duration: 407.861941ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:30:05.146955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:03.867786Z","time spent":"1.279148968s","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:30:05.147084Z","caller":"traceutil/trace.go:171","msg":"trace[1575893195] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.278015431s","start":"2026-01-31T13:30:03.869057Z","end":"2026-01-31T13:30:05.147072Z","steps":["trace[1575893195] 'process raft request' (duration: 1.277573201s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:05.147117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:03.869051Z","time spent":"1.278049072s","remote":"127.0.0.1:35960","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-01-31T13:30:05.552596Z","caller":"traceutil/trace.go:171","msg":"trace[84441905] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"117.208133ms","start":"2026-01-31T13:30:05.43537Z","end":"2026-01-31T13:30:05.552578Z","steps":["trace[84441905] 'process raft request' (duration: 117.031569ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:05.959376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.69684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T13:30:05.959444Z","caller":"traceutil/trace.go:171","msg":"trace[1310420487] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:929; }","duration":"139.795683ms","start":"2026-01-31T13:30:05.819631Z","end":"2026-01-31T13:30:05.959426Z","steps":["trace[1310420487] 'count revisions from in-memory index tree' (duration: 139.650749ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:30:07.664368Z","caller":"traceutil/trace.go:171","msg":"trace[665781070] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"103.824735ms","start":"2026-01-31T13:30:07.560521Z","end":"2026-01-31T13:30:07.664346Z","steps":["trace[665781070] 'process raft request' (duration: 103.691022ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:07.905975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.553046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:30:07.906037Z","caller":"traceutil/trace.go:171","msg":"trace[1902258470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"113.652758ms","start":"2026-01-31T13:30:07.792374Z","end":"2026-01-31T13:30:07.906027Z","steps":["trace[1902258470] 'range keys from in-memory index tree' (duration: 113.469844ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:30:08.459955Z","caller":"traceutil/trace.go:171","msg":"trace[125121420] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"483.528195ms","start":"2026-01-31T13:30:07.976404Z","end":"2026-01-31T13:30:08.459933Z","steps":["trace[125121420] 'process raft request' (duration: 483.345091ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:08.460101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:07.976394Z","time spent":"483.623247ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:30:08.460305Z","caller":"traceutil/trace.go:171","msg":"trace[855272633] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:985; }","duration":"287.610655ms","start":"2026-01-31T13:30:08.172682Z","end":"2026-01-31T13:30:08.460292Z","steps":["trace[855272633] 'read index received' (duration: 287.606625ms)","trace[855272633] 'applied index is now lower than readState.Index' (duration: 3.3µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:30:08.460394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.708078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:30:08.460421Z","caller":"traceutil/trace.go:171","msg":"trace[442126697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"287.756309ms","start":"2026-01-31T13:30:08.172656Z","end":"2026-01-31T13:30:08.460413Z","steps":["trace[442126697] 'agreement among raft nodes before linearized reading' (duration: 287.690147ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:30:17.455395Z","caller":"traceutil/trace.go:171","msg":"trace[125426904] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"284.463561ms","start":"2026-01-31T13:30:17.17091Z","end":"2026-01-31T13:30:17.455373Z","steps":["trace[125426904] 'read index received' (duration: 284.285167ms)","trace[125426904] 'applied index is now lower than readState.Index' (duration: 177.534µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:30:17.45664Z","caller":"traceutil/trace.go:171","msg":"trace[1406628787] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"551.405773ms","start":"2026-01-31T13:30:16.905225Z","end":"2026-01-31T13:30:17.456631Z","steps":["trace[1406628787] 'process raft request' (duration: 550.02525ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.456919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:16.905213Z","time spent":"551.622068ms","remote":"127.0.0.1:35896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13253,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:30:17.457134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.208313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:30:17.457196Z","caller":"traceutil/trace.go:171","msg":"trace[1084546353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"286.338185ms","start":"2026-01-31T13:30:17.170842Z","end":"2026-01-31T13:30:17.457181Z","steps":["trace[1084546353] 'agreement among raft nodes before linearized reading' (duration: 286.204502ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.457463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.204257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-31T13:30:17.45758Z","caller":"traceutil/trace.go:171","msg":"trace[1180325995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1010; }","duration":"222.348011ms","start":"2026-01-31T13:30:17.235223Z","end":"2026-01-31T13:30:17.457571Z","steps":["trace[1180325995] 'agreement among raft nodes before linearized reading' (duration: 222.172736ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.457583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.786172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-31T13:30:17.46218Z","caller":"traceutil/trace.go:171","msg":"trace[1295251038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"217.395221ms","start":"2026-01-31T13:30:17.24477Z","end":"2026-01-31T13:30:17.462165Z","steps":["trace[1295251038] 'agreement among raft nodes before linearized reading' (duration: 212.734731ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.926914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.68679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:30:17.927234Z","caller":"traceutil/trace.go:171","msg":"trace[1426420494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"460.789502ms","start":"2026-01-31T13:30:17.46618Z","end":"2026-01-31T13:30:17.926969Z","steps":["trace[1426420494] 'range keys from in-memory index tree' (duration: 460.635179ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.927323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:17.466169Z","time spent":"461.120101ms","remote":"127.0.0.1:35736","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-01-31T13:30:17.927488Z","caller":"traceutil/trace.go:171","msg":"trace[1518527578] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"457.759221ms","start":"2026-01-31T13:30:17.469703Z","end":"2026-01-31T13:30:17.927463Z","steps":["trace[1518527578] 'process raft request' (duration: 411.140689ms)","trace[1518527578] 'compare' (duration: 45.879143ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:30:17.927699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:17.46969Z","time spent":"457.908535ms","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1524,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:30:17.975528Z","caller":"traceutil/trace.go:171","msg":"trace[1857482790] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1048; }","duration":"278.305223ms","start":"2026-01-31T13:30:17.6972Z","end":"2026-01-31T13:30:17.975505Z","steps":["trace[1857482790] 'read index received' (duration: 184.084417ms)","trace[1857482790] 'applied index is now lower than readState.Index' (duration: 94.219846ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:30:17.975967Z","caller":"traceutil/trace.go:171","msg":"trace[811281802] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"496.163876ms","start":"2026-01-31T13:30:17.479789Z","end":"2026-01-31T13:30:17.975953Z","steps":["trace[811281802] 'process raft request' (duration: 495.527951ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.976501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:17.479785Z","time spent":"496.226347ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10772,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:30:17.975995Z","caller":"traceutil/trace.go:171","msg":"trace[367447271] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"496.226507ms","start":"2026-01-31T13:30:17.479748Z","end":"2026-01-31T13:30:17.975974Z","steps":["trace[367447271] 'process raft request' (duration: 495.312896ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.977125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:17.479741Z","time spent":"497.288952ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:30:17.978083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.626455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T13:30:17.978175Z","caller":"traceutil/trace.go:171","msg":"trace[660933642] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1016; }","duration":"172.746868ms","start":"2026-01-31T13:30:17.805416Z","end":"2026-01-31T13:30:17.978162Z","steps":["trace[660933642] 'agreement among raft nodes before linearized reading' (duration: 172.620545ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:30:17.978428Z","caller":"traceutil/trace.go:171","msg":"trace[1628331465] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"498.475012ms","start":"2026-01-31T13:30:17.479939Z","end":"2026-01-31T13:30:17.978414Z","steps":["trace[1628331465] 'process raft request' (duration: 495.47592ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.978571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:17.479935Z","time spent":"498.566074ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:30:17.978814Z","caller":"traceutil/trace.go:171","msg":"trace[1996371706] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"493.737528ms","start":"2026-01-31T13:30:17.485065Z","end":"2026-01-31T13:30:17.978802Z","steps":["trace[1996371706] 'process raft request' (duration: 490.381838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.978953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:17.485056Z","time spent":"493.82903ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:30:17.979413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.216147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T13:30:17.979482Z","caller":"traceutil/trace.go:171","msg":"trace[1275728768] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"282.313929ms","start":"2026-01-31T13:30:17.697157Z","end":"2026-01-31T13:30:17.979471Z","steps":["trace[1275728768] 'agreement among raft nodes before linearized reading' (duration: 282.038182ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:30:17.980528Z","caller":"traceutil/trace.go:171","msg":"trace[484600867] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"500.637342ms","start":"2026-01-31T13:30:17.479874Z","end":"2026-01-31T13:30:17.980511Z","steps":["trace[484600867] 'process raft request' (duration: 495.49541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:30:17.981118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:30:17.479869Z","time spent":"500.95661ms","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:30:18.133678Z","caller":"traceutil/trace.go:171","msg":"trace[1222913999] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"142.126588ms","start":"2026-01-31T13:30:17.991531Z","end":"2026-01-31T13:30:18.133658Z","steps":["trace[1222913999] 'process raft request' (duration: 142.077006ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:30:18.135804Z","caller":"traceutil/trace.go:171","msg":"trace[1525507729] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"149.176076ms","start":"2026-01-31T13:30:17.986612Z","end":"2026-01-31T13:30:18.135788Z","steps":["trace[1525507729] 'process raft request' (duration: 100.485356ms)","trace[1525507729] 'compare' (duration: 46.416465ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:33:19.713174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.745903ms","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-01-31T13:33:19.71322Z","caller":"traceutil/trace.go:171","msg":"trace[168721674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1784; }","duration":"288.833475ms","start":"2026-01-31T13:33:19.424376Z","end":"2026-01-31T13:33:19.71321Z","steps":["trace[168721674] 'range keys from in-memory index tree' (duration: 288.630531ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:33:19.713481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.426622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-01-31T13:33:19.713509Z","caller":"traceutil/trace.go:171","msg":"trace[169480115] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1784; }","duration":"113.455503ms","start":"2026-01-31T13:33:19.600048Z","end":"2026-01-31T13:33:19.713504Z","steps":["trace[169480115] 'range keys from in-memory index tree' (duration: 113.382351ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:33:19.713587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.602916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-01-31T13:33:19.713599Z","caller":"traceutil/trace.go:171","msg":"trace[647502870] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1784; }","duration":"113.643237ms","start":"2026-01-31T13:33:19.599952Z","end":"2026-01-31T13:33:19.713596Z","steps":["trace[647502870] 'range keys from in-memory index tree' (duration: 113.478263ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:33:24.979017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.850612ms","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:536"} {"level":"info","ts":"2026-01-31T13:33:24.979088Z","caller":"traceutil/trace.go:171","msg":"trace[1988875975] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1808; }","duration":"625.007956ms","start":"2026-01-31T13:33:24.354062Z","end":"2026-01-31T13:33:24.97907Z","steps":["trace[1988875975] 'range keys from in-memory index tree' (duration: 624.712919ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:33:24.979429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:33:24.354032Z","time spent":"625.084688ms","remote":"127.0.0.1:35960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-31T13:33:24.980476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.585431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-01-31T13:33:24.981856Z","caller":"traceutil/trace.go:171","msg":"trace[1074020547] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1808; }","duration":"176.673223ms","start":"2026-01-31T13:33:24.803835Z","end":"2026-01-31T13:33:24.980508Z","steps":["trace[1074020547] 'range keys from in-memory index tree' (duration: 176.482048ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:33:24.982134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.594613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:33:24.9823Z","caller":"traceutil/trace.go:171","msg":"trace[905773202] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1808; }","duration":"129.658905ms","start":"2026-01-31T13:33:24.852504Z","end":"2026-01-31T13:33:24.982163Z","steps":["trace[905773202] 'range keys from in-memory index tree' (duration: 129.550122ms)"],"step_count":1}