{"level":"warn","ts":"2026-02-25T10:04:46.535714Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T10:04:46.535836Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-02-25T10:04:46.535928Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T10:04:46.535969Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-25T10:04:46.535992Z","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-02-25T10:04:46.536853Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-02-25T10:04:46.536959Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-02-25T10:04:46.54181Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.565811ms"} {"level":"info","ts":"2026-02-25T10:04:46.548295Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-02-25T10:04:46.548379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T10:04:46.5484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-02-25T10:04:46.54841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T10:04:46.548421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-02-25T10:04:46.548463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-02-25T10:04:46.554881Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T10:04:46.556352Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T10:04:46.557446Z","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-02-25T10:04:46.560753Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T10:04:46.561013Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T10:04:46.561115Z","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-02-25T10:04:46.561203Z","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-02-25T10:04:46.561212Z","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-02-25T10:04:46.56435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-02-25T10:04:46.564583Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-25T10:04:46.565445Z","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-02-25T10:04:46.565716Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T10:04:46.565747Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T10:04:46.56587Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-25T10:04:46.565881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-25T10:04:46.948657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T10:04:46.948731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T10:04:46.948763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-02-25T10:04:46.948783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-02-25T10:04:46.948804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-25T10:04:46.94891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-02-25T10:04:46.948933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-25T10:04:46.949867Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:04:46.951289Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:04:46.951366Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:04:46.951379Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:04:46.951403Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T10:04:46.951536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:04:46.951472Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:04:46.952496Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T10:04:46.952521Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T10:04:46.953716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T10:04:46.957535Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-02-25T10:04:52.36385Z","caller":"traceutil/trace.go:171","msg":"trace[2005312844] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"202.530096ms","start":"2026-02-25T10:04:52.161253Z","end":"2026-02-25T10:04:52.363783Z","steps":["trace[2005312844] 'process raft request' (duration: 202.337051ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:52.363944Z","caller":"traceutil/trace.go:171","msg":"trace[1764269099] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:297; }","duration":"190.479107ms","start":"2026-02-25T10:04:52.173428Z","end":"2026-02-25T10:04:52.363907Z","steps":["trace[1764269099] 'read index received' (duration: 87.46185ms)","trace[1764269099] 'applied index is now lower than readState.Index' (duration: 103.015867ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:52.363986Z","caller":"traceutil/trace.go:171","msg":"trace[1621390347] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"187.605073ms","start":"2026-02-25T10:04:52.176332Z","end":"2026-02-25T10:04:52.363937Z","steps":["trace[1621390347] 'process raft request' (duration: 187.342636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:52.364083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.63621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T10:04:52.364147Z","caller":"traceutil/trace.go:171","msg":"trace[1769661006] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:294; }","duration":"190.750473ms","start":"2026-02-25T10:04:52.173388Z","end":"2026-02-25T10:04:52.364138Z","steps":["trace[1769661006] 'agreement among raft nodes before linearized reading' (duration: 190.61578ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:52.364267Z","caller":"traceutil/trace.go:171","msg":"trace[63414642] transaction","detail":"{read_only:false; number_of_response:0; response_revision:292; }","duration":"186.057249ms","start":"2026-02-25T10:04:52.178196Z","end":"2026-02-25T10:04:52.364254Z","steps":["trace[63414642] 'process raft request' (duration: 185.617249ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:52.36434Z","caller":"traceutil/trace.go:171","msg":"trace[676171212] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"184.909512ms","start":"2026-02-25T10:04:52.179422Z","end":"2026-02-25T10:04:52.364332Z","steps":["trace[676171212] 'process raft request' (duration: 184.418582ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:52.364386Z","caller":"traceutil/trace.go:171","msg":"trace[52446022] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"187.571462ms","start":"2026-02-25T10:04:52.176805Z","end":"2026-02-25T10:04:52.364377Z","steps":["trace[52446022] 'process raft request' (duration: 186.898337ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:52.364464Z","caller":"traceutil/trace.go:171","msg":"trace[1981307434] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"143.247894ms","start":"2026-02-25T10:04:52.221208Z","end":"2026-02-25T10:04:52.364456Z","steps":["trace[1981307434] 'process raft request' (duration: 142.664351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:52.364463Z","caller":"traceutil/trace.go:171","msg":"trace[2068947566] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"186.296824ms","start":"2026-02-25T10:04:52.178152Z","end":"2026-02-25T10:04:52.364449Z","steps":["trace[2068947566] 'process raft request' (duration: 185.628839ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:52.376086Z","caller":"traceutil/trace.go:171","msg":"trace[400379053] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"111.341773ms","start":"2026-02-25T10:04:52.264733Z","end":"2026-02-25T10:04:52.376075Z","steps":["trace[400379053] 'process raft request' (duration: 111.275422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:55.677289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.616317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T10:04:55.677349Z","caller":"traceutil/trace.go:171","msg":"trace[1922621389] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:337; }","duration":"102.73043ms","start":"2026-02-25T10:04:55.574606Z","end":"2026-02-25T10:04:55.677336Z","steps":["trace[1922621389] 'range keys from in-memory index tree' (duration: 102.485834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:57.35842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.925462ms","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-02-25T10:04:57.35852Z","caller":"traceutil/trace.go:171","msg":"trace[1252203053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"202.089045ms","start":"2026-02-25T10:04:57.156412Z","end":"2026-02-25T10:04:57.358501Z","steps":["trace[1252203053] 'range keys from in-memory index tree' (duration: 201.775459ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:57.483762Z","caller":"traceutil/trace.go:171","msg":"trace[1664165605] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"118.87225ms","start":"2026-02-25T10:04:57.364867Z","end":"2026-02-25T10:04:57.483739Z","steps":["trace[1664165605] 'process raft request' (duration: 118.695686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:59.183638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754556786,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:59.529251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.929437287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:59.529387Z","caller":"traceutil/trace.go:171","msg":"trace[1491760908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"1.929611651s","start":"2026-02-25T10:04:57.599734Z","end":"2026-02-25T10:04:59.529345Z","steps":["trace[1491760908] 'range keys from in-memory index tree' (duration: 1.929330615s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:59.529452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:57.599712Z","time spent":"1.929720883s","remote":"127.0.0.1:42740","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-02-25T10:04:59.53015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.811044619s","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-02-25T10:04:59.530245Z","caller":"traceutil/trace.go:171","msg":"trace[846767136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"1.811201562s","start":"2026-02-25T10:04:57.719021Z","end":"2026-02-25T10:04:59.530223Z","steps":["trace[846767136] 'range keys from in-memory index tree' (duration: 1.810940966s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:59.530288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:57.718999Z","time spent":"1.811278764s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:04:59.530838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.76587009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-25T10:04:59.530884Z","caller":"traceutil/trace.go:171","msg":"trace[1639253434] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:390; }","duration":"1.765961993s","start":"2026-02-25T10:04:57.764909Z","end":"2026-02-25T10:04:59.530871Z","steps":["trace[1639253434] 'range keys from in-memory index tree' (duration: 1.765743548s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:59.530916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:57.764886Z","time spent":"1.766019934s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-25T10:04:59.531058Z","caller":"traceutil/trace.go:171","msg":"trace[1339198857] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"847.440114ms","start":"2026-02-25T10:04:58.683595Z","end":"2026-02-25T10:04:59.531035Z","steps":["trace[1339198857] 'read index received' (duration: 846.278909ms)","trace[1339198857] 'applied index is now lower than readState.Index' (duration: 1.160115ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:59.531258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.67031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:04:59.531299Z","caller":"traceutil/trace.go:171","msg":"trace[808623659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"847.742492ms","start":"2026-02-25T10:04:58.683544Z","end":"2026-02-25T10:04:59.531287Z","steps":["trace[808623659] 'agreement among raft nodes before linearized reading' (duration: 847.624979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:59.531328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.683524Z","time spent":"847.796992ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:04:59.53134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.596972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T10:04:59.531384Z","caller":"traceutil/trace.go:171","msg":"trace[1656561469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"566.695204ms","start":"2026-02-25T10:04:58.964677Z","end":"2026-02-25T10:04:59.531372Z","steps":["trace[1656561469] 'agreement among raft nodes before linearized reading' (duration: 566.606643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:59.531418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.964653Z","time spent":"566.757875ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:04:59.531476Z","caller":"traceutil/trace.go:171","msg":"trace[970064767] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"888.811526ms","start":"2026-02-25T10:04:58.64264Z","end":"2026-02-25T10:04:59.531451Z","steps":["trace[970064767] 'process raft request' (duration: 887.307473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:59.532129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.642614Z","time spent":"888.909158ms","remote":"127.0.0.1:43018","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-02-25T10:05:01.540586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000151704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:05:01.540673Z","caller":"traceutil/trace.go:171","msg":"trace[1258929199] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000268667s","start":"2026-02-25T10:04:59.540372Z","end":"2026-02-25T10:05:01.540641Z","steps":["trace[1258929199] 'agreement among raft nodes before linearized reading' (duration: 2.000176605s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:01.540868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:59.540364Z","time spent":"2.000497242s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:05:01 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:05:01.545099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539483135s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:01.545223Z","caller":"traceutil/trace.go:171","msg":"trace[97174859] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"2.009753928s","start":"2026-02-25T10:04:59.535451Z","end":"2026-02-25T10:05:01.545205Z","steps":["trace[97174859] 'process raft request' (duration: 469.940527ms)","trace[97174859] 'compare' (duration: 1.53933174s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:01.545292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:59.535433Z","time spent":"2.009831369s","remote":"127.0.0.1:43018","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-02-25T10:05:02.199365Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.193883345s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:05:02.307692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.038138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:02.307951Z","caller":"traceutil/trace.go:171","msg":"trace[991830325] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"2.770423938s","start":"2026-02-25T10:04:59.537509Z","end":"2026-02-25T10:05:02.307933Z","steps":["trace[991830325] 'process raft request' (duration: 2.770278355s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:02.30798Z","caller":"traceutil/trace.go:171","msg":"trace[420858331] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"2.770311006s","start":"2026-02-25T10:04:59.537642Z","end":"2026-02-25T10:05:02.307953Z","steps":["trace[420858331] 'process raft request' (duration: 2.770191403s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:02.308032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:59.537491Z","time spent":"2.77050586s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:02.308063Z","caller":"traceutil/trace.go:171","msg":"trace[61738217] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:405; }","duration":"2.767634206s","start":"2026-02-25T10:04:59.540406Z","end":"2026-02-25T10:05:02.30804Z","steps":["trace[61738217] 'read index received' (duration: 464.999087ms)","trace[61738217] 'applied index is now lower than readState.Index' (duration: 2.302633078s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:02.308121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:59.537596Z","time spent":"2.770448909s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:02.308157Z","caller":"traceutil/trace.go:171","msg":"trace[256764867] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"2.767857361s","start":"2026-02-25T10:04:59.54029Z","end":"2026-02-25T10:05:02.308147Z","steps":["trace[256764867] 'process raft request' (duration: 2.767578995s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:02.308239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:59.540272Z","time spent":"2.767917202s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6879,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:02.308182Z","caller":"traceutil/trace.go:171","msg":"trace[1435984659] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"2.770704074s","start":"2026-02-25T10:04:59.537259Z","end":"2026-02-25T10:05:02.307963Z","steps":["trace[1435984659] 'process raft request' (duration: 2.662300148s)","trace[1435984659] 'compare' (duration: 107.915655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:02.308506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:59.537249Z","time spent":"2.771146314s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:02.808704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754556797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:03.309408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754556797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:03.540779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999884628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:05:03.540911Z","caller":"traceutil/trace.go:171","msg":"trace[456292606] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000084213s","start":"2026-02-25T10:05:01.540811Z","end":"2026-02-25T10:05:03.540895Z","steps":["trace[456292606] 'agreement among raft nodes before linearized reading' (duration: 1.999923159s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:03.54095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:01.540798Z","time spent":"2.000144515s","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:03.810206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754556797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:03.842808Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.537003908s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:05:03.938348Z","caller":"traceutil/trace.go:171","msg":"trace[49442383] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:410; }","duration":"1.630179386s","start":"2026-02-25T10:05:02.308116Z","end":"2026-02-25T10:05:03.938296Z","steps":["trace[49442383] 'read index received' (duration: 1.534912712s)","trace[49442383] 'applied index is now lower than readState.Index' (duration: 95.265154ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:03.938697Z","caller":"traceutil/trace.go:171","msg":"trace[684848854] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"1.724682822s","start":"2026-02-25T10:05:02.213993Z","end":"2026-02-25T10:05:03.938676Z","steps":["trace[684848854] 'process raft request' (duration: 1.629077371s)","trace[684848854] 'compare' (duration: 94.987978ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:03.938867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:02.21397Z","time spent":"1.724788664s","remote":"127.0.0.1:43018","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-02-25T10:05:03.938991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627366012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-25T10:05:03.939072Z","caller":"traceutil/trace.go:171","msg":"trace[1050553714] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:398; }","duration":"1.627503077s","start":"2026-02-25T10:05:02.311549Z","end":"2026-02-25T10:05:03.939053Z","steps":["trace[1050553714] 'agreement among raft nodes before linearized reading' (duration: 1.627334722s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:03.939115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:02.311529Z","time spent":"1.627578578s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-25T10:05:03.939127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.749626341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:05:03.939183Z","caller":"traceutil/trace.go:171","msg":"trace[840524029] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:398; }","duration":"3.749716852s","start":"2026-02-25T10:05:00.189453Z","end":"2026-02-25T10:05:03.93917Z","steps":["trace[840524029] 'agreement among raft nodes before linearized reading' (duration: 3.74962366s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:03.939207Z","caller":"traceutil/trace.go:171","msg":"trace[778642356] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"389.834593ms","start":"2026-02-25T10:05:03.549356Z","end":"2026-02-25T10:05:03.939191Z","steps":["trace[778642356] 'process raft request' (duration: 388.875681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:03.939228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:00.189432Z","time spent":"3.749785924s","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-02-25T10:05:03.939342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:03.549327Z","time spent":"389.914674ms","remote":"127.0.0.1:42808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:03.939379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.986086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:03.939408Z","caller":"traceutil/trace.go:171","msg":"trace[316026736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:398; }","duration":"386.032157ms","start":"2026-02-25T10:05:03.553368Z","end":"2026-02-25T10:05:03.939401Z","steps":["trace[316026736] 'agreement among raft nodes before linearized reading' (duration: 385.984856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:03.939433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:03.553361Z","time spent":"386.065978ms","remote":"127.0.0.1:42740","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-02-25T10:05:03.939555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.094337ms","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-02-25T10:05:03.939582Z","caller":"traceutil/trace.go:171","msg":"trace[196240033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"390.157799ms","start":"2026-02-25T10:05:03.549417Z","end":"2026-02-25T10:05:03.939575Z","steps":["trace[196240033] 'agreement among raft nodes before linearized reading' (duration: 390.107548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:03.939602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:03.549395Z","time spent":"390.202421ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:04.806734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:03.940859Z","time spent":"865.868555ms","remote":"127.0.0.1:42754","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T10:05:04.806863Z","caller":"traceutil/trace.go:171","msg":"trace[1116143443] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:412; }","duration":"492.977651ms","start":"2026-02-25T10:05:04.313872Z","end":"2026-02-25T10:05:04.80685Z","steps":["trace[1116143443] 'read index received' (duration: 397.151945ms)","trace[1116143443] 'applied index is now lower than readState.Index' (duration: 95.825026ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:04.806947Z","caller":"traceutil/trace.go:171","msg":"trace[1107129446] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"859.055113ms","start":"2026-02-25T10:05:03.947884Z","end":"2026-02-25T10:05:04.806939Z","steps":["trace[1107129446] 'process raft request' (duration: 858.92531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:04.807049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:03.947859Z","time spent":"859.109454ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:04.807045Z","caller":"traceutil/trace.go:171","msg":"trace[1460122426] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"861.812724ms","start":"2026-02-25T10:05:03.945207Z","end":"2026-02-25T10:05:04.807019Z","steps":["trace[1460122426] 'process raft request' (duration: 861.448796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:04.807105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.202056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:05:04.807145Z","caller":"traceutil/trace.go:171","msg":"trace[1066661277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"493.304068ms","start":"2026-02-25T10:05:04.313826Z","end":"2026-02-25T10:05:04.80713Z","steps":["trace[1066661277] 'agreement among raft nodes before linearized reading' (duration: 493.193436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:04.807182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:04.313805Z","time spent":"493.36946ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:04.807183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:03.945186Z","time spent":"861.930607ms","remote":"127.0.0.1:43018","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-02-25T10:05:04.810526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.100034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:04.810573Z","caller":"traceutil/trace.go:171","msg":"trace[1983627711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"210.213987ms","start":"2026-02-25T10:05:04.600349Z","end":"2026-02-25T10:05:04.810563Z","steps":["trace[1983627711] 'agreement among raft nodes before linearized reading' (duration: 210.097134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:04.810529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.589113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T10:05:04.810588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.632043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T10:05:04.810628Z","caller":"traceutil/trace.go:171","msg":"trace[1688426347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"496.689875ms","start":"2026-02-25T10:05:04.313926Z","end":"2026-02-25T10:05:04.810616Z","steps":["trace[1688426347] 'agreement among raft nodes before linearized reading' (duration: 496.531361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:04.810654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:04.313904Z","time spent":"496.742995ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:05:04.810653Z","caller":"traceutil/trace.go:171","msg":"trace[1241650267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"496.741766ms","start":"2026-02-25T10:05:04.313895Z","end":"2026-02-25T10:05:04.810637Z","steps":["trace[1241650267] 'agreement among raft nodes before linearized reading' (duration: 496.572293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:04.81069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:04.313882Z","time spent":"496.800257ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:05:24.510006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.957109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:24.510164Z","caller":"traceutil/trace.go:171","msg":"trace[1053905191] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"740.205063ms","start":"2026-02-25T10:05:23.769932Z","end":"2026-02-25T10:05:24.510137Z","steps":["trace[1053905191] 'process raft request' (duration: 252.026249ms)","trace[1053905191] 'compare' (duration: 487.688852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:24.510232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.769899Z","time spent":"740.307535ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14059,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:24.64612Z","caller":"traceutil/trace.go:171","msg":"trace[820425067] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"123.518984ms","start":"2026-02-25T10:05:24.522558Z","end":"2026-02-25T10:05:24.646077Z","steps":["trace[820425067] 'process raft request' (duration: 114.653066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.960993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.097584ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:24.96112Z","caller":"traceutil/trace.go:171","msg":"trace[300254911] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:498; }","duration":"175.261848ms","start":"2026-02-25T10:05:24.785841Z","end":"2026-02-25T10:05:24.961102Z","steps":["trace[300254911] 'range keys from in-memory index tree' (duration: 175.081994ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:24.961512Z","caller":"traceutil/trace.go:171","msg":"trace[815303742] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"307.965176ms","start":"2026-02-25T10:05:24.653509Z","end":"2026-02-25T10:05:24.961474Z","steps":["trace[815303742] 'process raft request' (duration: 261.175992ms)","trace[815303742] 'compare' (duration: 46.353264ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:24.961692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:24.65349Z","time spent":"308.12419ms","remote":"127.0.0.1:42808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:24.96191Z","caller":"traceutil/trace.go:171","msg":"trace[1161025723] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"306.918963ms","start":"2026-02-25T10:05:24.654964Z","end":"2026-02-25T10:05:24.961883Z","steps":["trace[1161025723] 'process raft request' (duration: 306.828351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:24.961942Z","caller":"traceutil/trace.go:171","msg":"trace[67587482] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"308.301903ms","start":"2026-02-25T10:05:24.653593Z","end":"2026-02-25T10:05:24.961895Z","steps":["trace[67587482] 'process raft request' (duration: 308.050997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.962246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:24.654954Z","time spent":"307.213909ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3554,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:24.962334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:24.653568Z","time spent":"308.650781ms","remote":"127.0.0.1:43206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4298,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:24.962625Z","caller":"traceutil/trace.go:171","msg":"trace[731320607] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"298.573776ms","start":"2026-02-25T10:05:24.664022Z","end":"2026-02-25T10:05:24.962596Z","steps":["trace[731320607] 'process raft request' (duration: 298.28888ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:24.962609Z","caller":"traceutil/trace.go:171","msg":"trace[100029993] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"305.625553ms","start":"2026-02-25T10:05:24.656959Z","end":"2026-02-25T10:05:24.962585Z","steps":["trace[100029993] 'process raft request' (duration: 304.890377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.969283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:24.656945Z","time spent":"312.242432ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14860,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:27.30142Z","caller":"traceutil/trace.go:171","msg":"trace[2064980856] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"209.165174ms","start":"2026-02-25T10:05:27.092232Z","end":"2026-02-25T10:05:27.301397Z","steps":["trace[2064980856] 'process raft request' (duration: 202.993686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:27.301569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.667227ms","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-02-25T10:05:27.301622Z","caller":"traceutil/trace.go:171","msg":"trace[1468138575] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"175.467933ms","start":"2026-02-25T10:05:27.12589Z","end":"2026-02-25T10:05:27.301358Z","steps":["trace[1468138575] 'read index received' (duration: 169.330506ms)","trace[1468138575] 'applied index is now lower than readState.Index' (duration: 6.136607ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:27.301622Z","caller":"traceutil/trace.go:171","msg":"trace[337497015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"175.780419ms","start":"2026-02-25T10:05:27.125828Z","end":"2026-02-25T10:05:27.301608Z","steps":["trace[337497015] 'agreement among raft nodes before linearized reading' (duration: 175.663147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:27.301821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.274258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T10:05:27.301914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.697627ms","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-02-25T10:05:27.301972Z","caller":"traceutil/trace.go:171","msg":"trace[500714436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"175.783008ms","start":"2026-02-25T10:05:27.126175Z","end":"2026-02-25T10:05:27.301958Z","steps":["trace[500714436] 'agreement among raft nodes before linearized reading' (duration: 175.688516ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:27.301943Z","caller":"traceutil/trace.go:171","msg":"trace[586147616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"171.429302ms","start":"2026-02-25T10:05:27.130491Z","end":"2026-02-25T10:05:27.30192Z","steps":["trace[586147616] 'agreement among raft nodes before linearized reading' (duration: 171.240148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:29.672125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.953023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:29.67394Z","caller":"traceutil/trace.go:171","msg":"trace[465664875] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"257.738337ms","start":"2026-02-25T10:05:29.416161Z","end":"2026-02-25T10:05:29.673899Z","steps":["trace[465664875] 'process raft request' (duration: 96.875471ms)","trace[465664875] 'compare' (duration: 158.82002ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:32.294318Z","caller":"traceutil/trace.go:171","msg":"trace[1810984543] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"602.374519ms","start":"2026-02-25T10:05:31.69191Z","end":"2026-02-25T10:05:32.294285Z","steps":["trace[1810984543] 'process raft request' (duration: 602.105003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:32.29472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:31.691887Z","time spent":"602.603484ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:32.939411Z","caller":"traceutil/trace.go:171","msg":"trace[1243946356] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"701.693234ms","start":"2026-02-25T10:05:32.23768Z","end":"2026-02-25T10:05:32.939374Z","steps":["trace[1243946356] 'read index received' (duration: 56.687954ms)","trace[1243946356] 'applied index is now lower than readState.Index' (duration: 644.929798ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:32.939447Z","caller":"traceutil/trace.go:171","msg":"trace[1917390039] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.107268807s","start":"2026-02-25T10:05:31.832132Z","end":"2026-02-25T10:05:32.939401Z","steps":["trace[1917390039] 'process raft request' (duration: 1.106786205s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:32.93979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:31.832088Z","time spent":"1.107605074s","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:32.939808Z","caller":"traceutil/trace.go:171","msg":"trace[1099555720] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"903.894321ms","start":"2026-02-25T10:05:32.035894Z","end":"2026-02-25T10:05:32.939788Z","steps":["trace[1099555720] 'process raft request' (duration: 903.346669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:32.939915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.802555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-02-25T10:05:32.939966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.953239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-25T10:05:32.939982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.283838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-02-25T10:05:32.940021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:32.035856Z","time spent":"904.122536ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:32.940039Z","caller":"traceutil/trace.go:171","msg":"trace[1047388276] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"108.982909ms","start":"2026-02-25T10:05:32.831028Z","end":"2026-02-25T10:05:32.940011Z","steps":["trace[1047388276] 'agreement among raft nodes before linearized reading' (duration: 108.654602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:32.940024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.12452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-25T10:05:32.940081Z","caller":"traceutil/trace.go:171","msg":"trace[1415961861] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"310.449991ms","start":"2026-02-25T10:05:32.629606Z","end":"2026-02-25T10:05:32.940056Z","steps":["trace[1415961861] 'agreement among raft nodes before linearized reading' (duration: 310.131254ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:32.940058Z","caller":"traceutil/trace.go:171","msg":"trace[1455340566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:569; }","duration":"676.129023ms","start":"2026-02-25T10:05:32.263911Z","end":"2026-02-25T10:05:32.94004Z","steps":["trace[1455340566] 'agreement among raft nodes before linearized reading' (duration: 675.717724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:32.940142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:32.629579Z","time spent":"310.550664ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-02-25T10:05:32.940154Z","caller":"traceutil/trace.go:171","msg":"trace[2018992062] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"509.306794ms","start":"2026-02-25T10:05:32.430811Z","end":"2026-02-25T10:05:32.940118Z","steps":["trace[2018992062] 'agreement among raft nodes before linearized reading' (duration: 508.944756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:32.940181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:32.263895Z","time spent":"676.271496ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:32.940231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:32.43079Z","time spent":"509.425328ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-25T10:05:32.940444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.766027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-25T10:05:32.940496Z","caller":"traceutil/trace.go:171","msg":"trace[1228524288] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"702.868419ms","start":"2026-02-25T10:05:32.237614Z","end":"2026-02-25T10:05:32.940482Z","steps":["trace[1228524288] 'agreement among raft nodes before linearized reading' (duration: 701.96268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:32.940535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:32.237587Z","time spent":"702.938451ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-25T10:05:33.178716Z","caller":"traceutil/trace.go:171","msg":"trace[1872889860] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"134.54924ms","start":"2026-02-25T10:05:33.044135Z","end":"2026-02-25T10:05:33.178684Z","steps":["trace[1872889860] 'process raft request' (duration: 134.269414ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:33.216026Z","caller":"traceutil/trace.go:171","msg":"trace[696386826] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"101.748908ms","start":"2026-02-25T10:05:33.114249Z","end":"2026-02-25T10:05:33.215998Z","steps":["trace[696386826] 'process raft request' (duration: 97.994704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:34.651194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.108788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:05:34.651291Z","caller":"traceutil/trace.go:171","msg":"trace[1841056727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"325.341693ms","start":"2026-02-25T10:05:34.325928Z","end":"2026-02-25T10:05:34.65127Z","steps":["trace[1841056727] 'range keys from in-memory index tree' (duration: 324.938494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:34.651288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.1446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-02-25T10:05:34.651526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:34.325906Z","time spent":"325.420214ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:05:34.65155Z","caller":"traceutil/trace.go:171","msg":"trace[416249542] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"221.481458ms","start":"2026-02-25T10:05:34.43005Z","end":"2026-02-25T10:05:34.651531Z","steps":["trace[416249542] 'range keys from in-memory index tree' (duration: 220.998617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:34.651678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.254682ms","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-02-25T10:05:34.651919Z","caller":"traceutil/trace.go:171","msg":"trace[1692524229] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"421.528028ms","start":"2026-02-25T10:05:34.230374Z","end":"2026-02-25T10:05:34.651902Z","steps":["trace[1692524229] 'range keys from in-memory index tree' (duration: 420.432323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:34.651962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:34.230354Z","time spent":"421.595579ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-25T10:05:41.273815Z","caller":"traceutil/trace.go:171","msg":"trace[895737436] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"261.746586ms","start":"2026-02-25T10:05:41.012044Z","end":"2026-02-25T10:05:41.273791Z","steps":["trace[895737436] 'process raft request' (duration: 261.601763ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:41.416913Z","caller":"traceutil/trace.go:171","msg":"trace[301238178] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"138.112228ms","start":"2026-02-25T10:05:41.278764Z","end":"2026-02-25T10:05:41.416876Z","steps":["trace[301238178] 'process raft request' (duration: 127.834859ms)","trace[301238178] 'compare' (duration: 10.120826ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:41.508202Z","caller":"traceutil/trace.go:171","msg":"trace[1674263376] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"216.620619ms","start":"2026-02-25T10:05:41.291558Z","end":"2026-02-25T10:05:41.508179Z","steps":["trace[1674263376] 'process raft request' (duration: 216.427665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:43.778851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754557255,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:44.279725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754557255,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:44.780733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754557255,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:44.943504Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.175994162s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:05:44.944153Z","caller":"traceutil/trace.go:171","msg":"trace[1943756276] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"1.665656245s","start":"2026-02-25T10:05:43.27847Z","end":"2026-02-25T10:05:44.944126Z","steps":["trace[1943756276] 'read index received' (duration: 1.665262156s)","trace[1943756276] 'applied index is now lower than readState.Index' (duration: 392.509µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:44.944245Z","caller":"traceutil/trace.go:171","msg":"trace[1537731031] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.176835421s","start":"2026-02-25T10:05:42.767384Z","end":"2026-02-25T10:05:44.94422Z","steps":["trace[1537731031] 'process raft request' (duration: 2.176554465s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.944377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344532786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:05:44.944395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:42.767353Z","time spent":"2.176950093s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:44.944445Z","caller":"traceutil/trace.go:171","msg":"trace[467533384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"1.344663118s","start":"2026-02-25T10:05:43.599766Z","end":"2026-02-25T10:05:44.944429Z","steps":["trace[467533384] 'agreement among raft nodes before linearized reading' (duration: 1.344547086s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.944498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.599746Z","time spent":"1.34474033s","remote":"127.0.0.1:42740","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-02-25T10:05:44.944512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376819575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T10:05:44.944504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666005413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T10:05:44.944628Z","caller":"traceutil/trace.go:171","msg":"trace[272588197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.666195496s","start":"2026-02-25T10:05:43.278412Z","end":"2026-02-25T10:05:44.944608Z","steps":["trace[272588197] 'agreement among raft nodes before linearized reading' (duration: 1.665850269s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:44.944568Z","caller":"traceutil/trace.go:171","msg":"trace[1778835295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"1.376900536s","start":"2026-02-25T10:05:43.567646Z","end":"2026-02-25T10:05:44.944547Z","steps":["trace[1778835295] 'agreement among raft nodes before linearized reading' (duration: 1.376695721s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.94469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.278363Z","time spent":"1.666316469s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:44.944727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.567626Z","time spent":"1.37708569s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:44.944648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37839437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:05:44.9449Z","caller":"traceutil/trace.go:171","msg":"trace[1892888577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.378721167s","start":"2026-02-25T10:05:43.566153Z","end":"2026-02-25T10:05:44.944874Z","steps":["trace[1892888577] 'agreement among raft nodes before linearized reading' (duration: 1.378237646s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.945026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.566129Z","time spent":"1.378878731s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:44.945368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.524106769s","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-02-25T10:05:44.945427Z","caller":"traceutil/trace.go:171","msg":"trace[422865911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"1.524254172s","start":"2026-02-25T10:05:43.421158Z","end":"2026-02-25T10:05:44.945412Z","steps":["trace[422865911] 'agreement among raft nodes before linearized reading' (duration: 1.523129717s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.94547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.421136Z","time spent":"1.524322093s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:05:45.109194Z","caller":"traceutil/trace.go:171","msg":"trace[367697085] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"159.759011ms","start":"2026-02-25T10:05:44.949408Z","end":"2026-02-25T10:05:45.109167Z","steps":["trace[367697085] 'process raft request' (duration: 93.722388ms)","trace[367697085] 'compare' (duration: 65.92619ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:45.127025Z","caller":"traceutil/trace.go:171","msg":"trace[1066923333] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"177.300682ms","start":"2026-02-25T10:05:44.949704Z","end":"2026-02-25T10:05:45.127005Z","steps":["trace[1066923333] 'process raft request' (duration: 177.147209ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:45.127392Z","caller":"traceutil/trace.go:171","msg":"trace[819739556] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"177.228321ms","start":"2026-02-25T10:05:44.950078Z","end":"2026-02-25T10:05:45.127307Z","steps":["trace[819739556] 'process raft request' (duration: 176.895713ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:45.127597Z","caller":"traceutil/trace.go:171","msg":"trace[145965130] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:639; }","duration":"173.836455ms","start":"2026-02-25T10:05:44.95375Z","end":"2026-02-25T10:05:45.127586Z","steps":["trace[145965130] 'read index received' (duration: 89.393012ms)","trace[145965130] 'applied index is now lower than readState.Index' (duration: 84.442503ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:45.127737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.963757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:45.127779Z","caller":"traceutil/trace.go:171","msg":"trace[866536540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"174.03026ms","start":"2026-02-25T10:05:44.953734Z","end":"2026-02-25T10:05:45.127764Z","steps":["trace[866536540] 'agreement among raft nodes before linearized reading' (duration: 173.898297ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:45.1278Z","caller":"traceutil/trace.go:171","msg":"trace[1869102191] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"176.910425ms","start":"2026-02-25T10:05:44.950866Z","end":"2026-02-25T10:05:45.127776Z","steps":["trace[1869102191] 'process raft request' (duration: 176.348642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:48.840082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.366249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:48.840163Z","caller":"traceutil/trace.go:171","msg":"trace[2073282527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"240.537563ms","start":"2026-02-25T10:05:48.599606Z","end":"2026-02-25T10:05:48.840144Z","steps":["trace[2073282527] 'range keys from in-memory index tree' (duration: 240.241597ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:49.29825Z","caller":"traceutil/trace.go:171","msg":"trace[1929365198] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"175.752409ms","start":"2026-02-25T10:05:49.122455Z","end":"2026-02-25T10:05:49.298208Z","steps":["trace[1929365198] 'read index received' (duration: 175.663137ms)","trace[1929365198] 'applied index is now lower than readState.Index' (duration: 87.902µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:49.298515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.039405ms","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-02-25T10:05:49.298563Z","caller":"traceutil/trace.go:171","msg":"trace[117864790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"176.128847ms","start":"2026-02-25T10:05:49.122422Z","end":"2026-02-25T10:05:49.29855Z","steps":["trace[117864790] 'agreement among raft nodes before linearized reading' (duration: 175.949433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:49.298569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.83503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T10:05:49.298569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.612585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T10:05:49.29852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.730337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T10:05:49.298641Z","caller":"traceutil/trace.go:171","msg":"trace[1431928992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"149.947163ms","start":"2026-02-25T10:05:49.148677Z","end":"2026-02-25T10:05:49.298624Z","steps":["trace[1431928992] 'agreement among raft nodes before linearized reading' (duration: 149.712328ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:49.298679Z","caller":"traceutil/trace.go:171","msg":"trace[145221151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:642; }","duration":"149.86345ms","start":"2026-02-25T10:05:49.148779Z","end":"2026-02-25T10:05:49.298642Z","steps":["trace[145221151] 'agreement among raft nodes before linearized reading' (duration: 149.677516ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:49.298648Z","caller":"traceutil/trace.go:171","msg":"trace[889147942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"149.725887ms","start":"2026-02-25T10:05:49.148906Z","end":"2026-02-25T10:05:49.298632Z","steps":["trace[889147942] 'agreement among raft nodes before linearized reading' (duration: 149.518483ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:49.298802Z","caller":"traceutil/trace.go:171","msg":"trace[767885928] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"314.89512ms","start":"2026-02-25T10:05:48.983427Z","end":"2026-02-25T10:05:49.298322Z","steps":["trace[767885928] 'process raft request' (duration: 314.628044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:49.299107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:48.983401Z","time spent":"315.642627ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:51.63695Z","caller":"traceutil/trace.go:171","msg":"trace[1797758398] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"332.971594ms","start":"2026-02-25T10:05:51.303956Z","end":"2026-02-25T10:05:51.636928Z","steps":["trace[1797758398] 'read index received' (duration: 332.698818ms)","trace[1797758398] 'applied index is now lower than readState.Index' (duration: 271.906µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:51.636986Z","caller":"traceutil/trace.go:171","msg":"trace[375889544] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"854.491359ms","start":"2026-02-25T10:05:50.782476Z","end":"2026-02-25T10:05:51.636967Z","steps":["trace[375889544] 'process raft request' (duration: 854.266004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:51.637313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.23697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T10:05:51.637317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.993512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wdncd\" ","response":"range_response_count:1 size:17914"} {"level":"info","ts":"2026-02-25T10:05:51.637386Z","caller":"traceutil/trace.go:171","msg":"trace[647681539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"324.325861ms","start":"2026-02-25T10:05:51.313043Z","end":"2026-02-25T10:05:51.637369Z","steps":["trace[647681539] 'agreement among raft nodes before linearized reading' (duration: 324.069096ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:51.637391Z","caller":"traceutil/trace.go:171","msg":"trace[492019926] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wdncd; range_end:; response_count:1; response_revision:653; }","duration":"306.098284ms","start":"2026-02-25T10:05:51.331278Z","end":"2026-02-25T10:05:51.637376Z","steps":["trace[492019926] 'agreement among raft nodes before linearized reading' (duration: 305.858069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:51.637433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.331261Z","time spent":"306.161425ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17937,"request content":"key:\"/registry/pods/kube-system/cilium-wdncd\" "} {"level":"warn","ts":"2026-02-25T10:05:51.637434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.044211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T10:05:51.637523Z","caller":"traceutil/trace.go:171","msg":"trace[1582002064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:653; }","duration":"322.168923ms","start":"2026-02-25T10:05:51.315335Z","end":"2026-02-25T10:05:51.637504Z","steps":["trace[1582002064] 'agreement among raft nodes before linearized reading' (duration: 321.843706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:51.637557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.628963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T10:05:51.637577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.315332Z","time spent":"322.232925ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T10:05:51.637602Z","caller":"traceutil/trace.go:171","msg":"trace[1191791583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"322.682584ms","start":"2026-02-25T10:05:51.314907Z","end":"2026-02-25T10:05:51.637589Z","steps":["trace[1191791583] 'agreement among raft nodes before linearized reading' (duration: 322.552261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:51.637435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.313035Z","time spent":"324.384262ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:05:51.637603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.927699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:05:51.63767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.314902Z","time spent":"322.731885ms","remote":"127.0.0.1:43018","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-02-25T10:05:51.637711Z","caller":"traceutil/trace.go:171","msg":"trace[2081378621] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:653; }","duration":"249.198206ms","start":"2026-02-25T10:05:51.388487Z","end":"2026-02-25T10:05:51.637685Z","steps":["trace[2081378621] 'agreement among raft nodes before linearized reading' (duration: 248.882369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:51.637156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:50.782449Z","time spent":"854.616083ms","remote":"127.0.0.1:43018","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-02-25T10:05:51.6378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.390298ms","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-02-25T10:05:51.63784Z","caller":"traceutil/trace.go:171","msg":"trace[1997142813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"322.434048ms","start":"2026-02-25T10:05:51.315394Z","end":"2026-02-25T10:05:51.637828Z","steps":["trace[1997142813] 'agreement among raft nodes before linearized reading' (duration: 322.324226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:51.637893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.315391Z","time spent":"322.49381ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:51.637961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.997537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:05:51.638394Z","caller":"traceutil/trace.go:171","msg":"trace[1360202805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"334.481318ms","start":"2026-02-25T10:05:51.303895Z","end":"2026-02-25T10:05:51.638377Z","steps":["trace[1360202805] 'agreement among raft nodes before linearized reading' (duration: 333.188449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:51.638442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.303871Z","time spent":"334.555909ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:05:52.329394Z","caller":"traceutil/trace.go:171","msg":"trace[1039739712] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"686.704569ms","start":"2026-02-25T10:05:51.642666Z","end":"2026-02-25T10:05:52.329371Z","steps":["trace[1039739712] 'process raft request' (duration: 685.927542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:52.32954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.642647Z","time spent":"686.807911ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:53.272157Z","caller":"traceutil/trace.go:171","msg":"trace[1487541004] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.629451947s","start":"2026-02-25T10:05:51.642674Z","end":"2026-02-25T10:05:53.272126Z","steps":["trace[1487541004] 'process raft request' (duration: 1.594442806s)","trace[1487541004] 'compare' (duration: 33.953747ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:53.272259Z","caller":"traceutil/trace.go:171","msg":"trace[431613217] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.629537729s","start":"2026-02-25T10:05:51.642702Z","end":"2026-02-25T10:05:53.272239Z","steps":["trace[431613217] 'process raft request' (duration: 1.628827164s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:53.272326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.642642Z","time spent":"1.62959929s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:53.272382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.642679Z","time spent":"1.629635151s","remote":"127.0.0.1:43018","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-02-25T10:05:53.272363Z","caller":"traceutil/trace.go:171","msg":"trace[1632958852] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.629311234s","start":"2026-02-25T10:05:51.643028Z","end":"2026-02-25T10:05:53.272339Z","steps":["trace[1632958852] 'process raft request' (duration: 1.628541638s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:53.272506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.643007Z","time spent":"1.629419586s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:53.272568Z","caller":"traceutil/trace.go:171","msg":"trace[1207528097] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.620028767s","start":"2026-02-25T10:05:51.652517Z","end":"2026-02-25T10:05:53.272545Z","steps":["trace[1207528097] 'process raft request' (duration: 1.619921835s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:53.272564Z","caller":"traceutil/trace.go:171","msg":"trace[1777688753] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.629239743s","start":"2026-02-25T10:05:51.643297Z","end":"2026-02-25T10:05:53.272537Z","steps":["trace[1777688753] 'process raft request' (duration: 1.628988667s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:53.272697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.652505Z","time spent":"1.620116599s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18061,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:53.27271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:51.643284Z","time spent":"1.629348095s","remote":"127.0.0.1:43018","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-02-25T10:06:09.223027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.388168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:09.223141Z","caller":"traceutil/trace.go:171","msg":"trace[1234166727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"623.555941ms","start":"2026-02-25T10:06:08.599563Z","end":"2026-02-25T10:06:09.223119Z","steps":["trace[1234166727] 'range keys from in-memory index tree' (duration: 623.288866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:09.22318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:08.599543Z","time spent":"623.629413ms","remote":"127.0.0.1:42740","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-02-25T10:06:09.568271Z","caller":"traceutil/trace.go:171","msg":"trace[922590250] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:799; }","duration":"200.07943ms","start":"2026-02-25T10:06:09.368163Z","end":"2026-02-25T10:06:09.568242Z","steps":["trace[922590250] 'read index received' (duration: 9.45539ms)","trace[922590250] 'applied index is now lower than readState.Index' (duration: 190.62312ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:09.568336Z","caller":"traceutil/trace.go:171","msg":"trace[368953284] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"214.627946ms","start":"2026-02-25T10:06:09.353682Z","end":"2026-02-25T10:06:09.568309Z","steps":["trace[368953284] 'process raft request' (duration: 202.626739ms)","trace[368953284] 'compare' (duration: 11.790552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:09.568523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.345416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:06:09.568599Z","caller":"traceutil/trace.go:171","msg":"trace[89057637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"200.451319ms","start":"2026-02-25T10:06:09.36813Z","end":"2026-02-25T10:06:09.568582Z","steps":["trace[89057637] 'agreement among raft nodes before linearized reading' (duration: 200.255475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:09.56908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.166728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T10:06:09.569149Z","caller":"traceutil/trace.go:171","msg":"trace[728443573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"198.295001ms","start":"2026-02-25T10:06:09.370834Z","end":"2026-02-25T10:06:09.569129Z","steps":["trace[728443573] 'agreement among raft nodes before linearized reading' (duration: 197.564125ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:09.721433Z","caller":"traceutil/trace.go:171","msg":"trace[1514617015] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"148.394698ms","start":"2026-02-25T10:06:09.572999Z","end":"2026-02-25T10:06:09.721394Z","steps":["trace[1514617015] 'process raft request' (duration: 113.104491ms)","trace[1514617015] 'compare' (duration: 35.143084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:11.320344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.63151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:11.320425Z","caller":"traceutil/trace.go:171","msg":"trace[555428214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"723.782023ms","start":"2026-02-25T10:06:10.596625Z","end":"2026-02-25T10:06:11.320407Z","steps":["trace[555428214] 'range keys from in-memory index tree' (duration: 723.553228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.320481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:10.596609Z","time spent":"723.862196ms","remote":"127.0.0.1:42740","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-02-25T10:06:11.3209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.496615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:06:11.320971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.797294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:06:11.321005Z","caller":"traceutil/trace.go:171","msg":"trace[820311386] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:772; }","duration":"967.835775ms","start":"2026-02-25T10:06:10.35316Z","end":"2026-02-25T10:06:11.320996Z","steps":["trace[820311386] 'count revisions from in-memory index tree' (duration: 967.746343ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:11.321008Z","caller":"traceutil/trace.go:171","msg":"trace[115310648] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:772; }","duration":"107.7072ms","start":"2026-02-25T10:06:11.213276Z","end":"2026-02-25T10:06:11.320983Z","steps":["trace[115310648] 'count revisions from in-memory index tree' (duration: 107.362332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.321033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:10.353117Z","time spent":"967.907997ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:06:11.321024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125914218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:06:11.321074Z","caller":"traceutil/trace.go:171","msg":"trace[919258879] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:772; }","duration":"1.125994s","start":"2026-02-25T10:06:10.195065Z","end":"2026-02-25T10:06:11.321059Z","steps":["trace[919258879] 'range keys from in-memory index tree' (duration: 1.125672713s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.321108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:10.195048Z","time spent":"1.126051471s","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-02-25T10:06:11.321032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.696814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:06:11.321182Z","caller":"traceutil/trace.go:171","msg":"trace[1360662316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"911.865747ms","start":"2026-02-25T10:06:10.409301Z","end":"2026-02-25T10:06:11.321167Z","steps":["trace[1360662316] 'range keys from in-memory index tree' (duration: 911.597092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.321207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:10.409285Z","time spent":"911.915358ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:06:11.321262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061286328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:06:11.321293Z","caller":"traceutil/trace.go:171","msg":"trace[1877759034] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:772; }","duration":"1.061337529s","start":"2026-02-25T10:06:10.259945Z","end":"2026-02-25T10:06:11.321282Z","steps":["trace[1877759034] 'count revisions from in-memory index tree' (duration: 1.061222666s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.321323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:10.259933Z","time spent":"1.06138251s","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:06:11.809414Z","caller":"traceutil/trace.go:171","msg":"trace[501757822] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"483.953529ms","start":"2026-02-25T10:06:11.325409Z","end":"2026-02-25T10:06:11.809362Z","steps":["trace[501757822] 'process raft request' (duration: 430.466043ms)","trace[501757822] 'compare' (duration: 53.2086ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:11.809506Z","caller":"traceutil/trace.go:171","msg":"trace[560915817] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"452.797918ms","start":"2026-02-25T10:06:11.356683Z","end":"2026-02-25T10:06:11.809481Z","steps":["trace[560915817] 'read index received' (duration: 399.16872ms)","trace[560915817] 'applied index is now lower than readState.Index' (duration: 53.627358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:11.809641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:11.325392Z","time spent":"484.122342ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:11.809745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.481908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T10:06:11.809739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.593833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:11.809814Z","caller":"traceutil/trace.go:171","msg":"trace[848967158] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:774; }","duration":"461.717525ms","start":"2026-02-25T10:06:11.348078Z","end":"2026-02-25T10:06:11.809795Z","steps":["trace[848967158] 'agreement among raft nodes before linearized reading' (duration: 461.515261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.809868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:11.348059Z","time spent":"461.800328ms","remote":"127.0.0.1:43128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:06:11.809957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.967354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:06:11.809464Z","caller":"traceutil/trace.go:171","msg":"trace[102176361] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"446.12037ms","start":"2026-02-25T10:06:11.36333Z","end":"2026-02-25T10:06:11.80945Z","steps":["trace[102176361] 'process raft request' (duration: 445.954396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.810008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.543122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:11.810024Z","caller":"traceutil/trace.go:171","msg":"trace[310839262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"212.602653ms","start":"2026-02-25T10:06:11.597417Z","end":"2026-02-25T10:06:11.81002Z","steps":["trace[310839262] 'agreement among raft nodes before linearized reading' (duration: 212.578762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.810072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:11.363312Z","time spent":"446.708952ms","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:11.80978Z","caller":"traceutil/trace.go:171","msg":"trace[1297682733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"428.571781ms","start":"2026-02-25T10:06:11.3812Z","end":"2026-02-25T10:06:11.809772Z","steps":["trace[1297682733] 'agreement among raft nodes before linearized reading' (duration: 428.416077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:11.810142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:11.381184Z","time spent":"428.950339ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:06:11.810186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.219357ms","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-02-25T10:06:11.810212Z","caller":"traceutil/trace.go:171","msg":"trace[1900716565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:774; }","duration":"237.286949ms","start":"2026-02-25T10:06:11.57292Z","end":"2026-02-25T10:06:11.810206Z","steps":["trace[1900716565] 'agreement among raft nodes before linearized reading' (duration: 237.218358ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:11.809984Z","caller":"traceutil/trace.go:171","msg":"trace[1871382660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"205.050415ms","start":"2026-02-25T10:06:11.604927Z","end":"2026-02-25T10:06:11.809978Z","steps":["trace[1871382660] 'agreement among raft nodes before linearized reading' (duration: 204.993554ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:12.049826Z","caller":"traceutil/trace.go:171","msg":"trace[760497244] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"235.119882ms","start":"2026-02-25T10:06:11.814678Z","end":"2026-02-25T10:06:12.049798Z","steps":["trace[760497244] 'read index received' (duration: 160.16021ms)","trace[760497244] 'applied index is now lower than readState.Index' (duration: 74.958222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:12.050103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.398739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:06:12.050152Z","caller":"traceutil/trace.go:171","msg":"trace[439344364] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:775; }","duration":"235.514721ms","start":"2026-02-25T10:06:11.814623Z","end":"2026-02-25T10:06:12.050138Z","steps":["trace[439344364] 'agreement among raft nodes before linearized reading' (duration: 235.284746ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:12.0503Z","caller":"traceutil/trace.go:171","msg":"trace[998650286] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"235.745056ms","start":"2026-02-25T10:06:11.81451Z","end":"2026-02-25T10:06:12.050255Z","steps":["trace[998650286] 'process raft request' (duration: 160.415166ms)","trace[998650286] 'compare' (duration: 74.755387ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:12.06737Z","caller":"traceutil/trace.go:171","msg":"trace[2137629216] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"252.516627ms","start":"2026-02-25T10:06:11.814813Z","end":"2026-02-25T10:06:12.067329Z","steps":["trace[2137629216] 'process raft request' (duration: 252.281742ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:12.067488Z","caller":"traceutil/trace.go:171","msg":"trace[1175188458] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"252.036597ms","start":"2026-02-25T10:06:11.815415Z","end":"2026-02-25T10:06:12.067452Z","steps":["trace[1175188458] 'process raft request' (duration: 251.850413ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:12.067399Z","caller":"traceutil/trace.go:171","msg":"trace[2087368501] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"252.501457ms","start":"2026-02-25T10:06:11.814874Z","end":"2026-02-25T10:06:12.067376Z","steps":["trace[2087368501] 'process raft request' (duration: 252.346184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:32.676364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754557758,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:06:32.894438Z","caller":"traceutil/trace.go:171","msg":"trace[197940472] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"718.862746ms","start":"2026-02-25T10:06:32.175551Z","end":"2026-02-25T10:06:32.894414Z","steps":["trace[197940472] 'read index received' (duration: 718.730623ms)","trace[197940472] 'applied index is now lower than readState.Index' (duration: 131.153µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:32.894488Z","caller":"traceutil/trace.go:171","msg":"trace[263562073] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"733.915249ms","start":"2026-02-25T10:06:32.160548Z","end":"2026-02-25T10:06:32.894463Z","steps":["trace[263562073] 'process raft request' (duration: 733.640703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:32.894728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:32.160525Z","time spent":"734.023842ms","remote":"127.0.0.1:43018","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-02-25T10:06:32.89487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.279944ms","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-02-25T10:06:32.894996Z","caller":"traceutil/trace.go:171","msg":"trace[172543598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"719.471669ms","start":"2026-02-25T10:06:32.175494Z","end":"2026-02-25T10:06:32.894966Z","steps":["trace[172543598] 'agreement among raft nodes before linearized reading' (duration: 719.06082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:32.895063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:32.175472Z","time spent":"719.579762ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:06:32.901172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.862048ms","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-02-25T10:06:32.901435Z","caller":"traceutil/trace.go:171","msg":"trace[843864512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:837; }","duration":"716.129444ms","start":"2026-02-25T10:06:32.185243Z","end":"2026-02-25T10:06:32.901372Z","steps":["trace[843864512] 'agreement among raft nodes before linearized reading' (duration: 715.735665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:32.901613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.80477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T10:06:32.901652Z","caller":"traceutil/trace.go:171","msg":"trace[2028949350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:837; }","duration":"707.859301ms","start":"2026-02-25T10:06:32.19378Z","end":"2026-02-25T10:06:32.901639Z","steps":["trace[2028949350] 'agreement among raft nodes before linearized reading' (duration: 707.33243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:32.90168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:32.193738Z","time spent":"707.934662ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:06:32.901613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:32.185231Z","time spent":"716.365809ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:06:32.901454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.899971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:06:32.902028Z","caller":"traceutil/trace.go:171","msg":"trace[160463850] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:837; }","duration":"214.492214ms","start":"2026-02-25T10:06:32.687497Z","end":"2026-02-25T10:06:32.901989Z","steps":["trace[160463850] 'agreement among raft nodes before linearized reading' (duration: 213.535813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:32.901496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.44685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:32.902354Z","caller":"traceutil/trace.go:171","msg":"trace[817619052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:837; }","duration":"301.324799ms","start":"2026-02-25T10:06:32.601012Z","end":"2026-02-25T10:06:32.902336Z","steps":["trace[817619052] 'agreement among raft nodes before linearized reading' (duration: 300.063441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:32.90246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:32.600995Z","time spent":"301.450493ms","remote":"127.0.0.1:42740","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-02-25T10:06:33.009729Z","caller":"traceutil/trace.go:171","msg":"trace[406963986] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"103.149056ms","start":"2026-02-25T10:06:32.90655Z","end":"2026-02-25T10:06:33.009699Z","steps":["trace[406963986] 'process raft request' (duration: 103.087665ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:33.010099Z","caller":"traceutil/trace.go:171","msg":"trace[1805468608] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"103.561466ms","start":"2026-02-25T10:06:32.906486Z","end":"2026-02-25T10:06:33.010047Z","steps":["trace[1805468608] 'process raft request' (duration: 102.970873ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:35.025499Z","caller":"traceutil/trace.go:171","msg":"trace[2121122607] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"120.517102ms","start":"2026-02-25T10:06:34.904924Z","end":"2026-02-25T10:06:35.025441Z","steps":["trace[2121122607] 'process raft request' (duration: 119.799316ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:35.21033Z","caller":"traceutil/trace.go:171","msg":"trace[881183287] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"180.00645ms","start":"2026-02-25T10:06:35.030283Z","end":"2026-02-25T10:06:35.210289Z","steps":["trace[881183287] 'process raft request' (duration: 162.127944ms)","trace[881183287] 'compare' (duration: 17.695432ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:35.249239Z","caller":"traceutil/trace.go:171","msg":"trace[584800891] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"218.32435ms","start":"2026-02-25T10:06:35.03088Z","end":"2026-02-25T10:06:35.249204Z","steps":["trace[584800891] 'process raft request' (duration: 218.061414ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:35.249616Z","caller":"traceutil/trace.go:171","msg":"trace[1948917332] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"218.639476ms","start":"2026-02-25T10:06:35.030948Z","end":"2026-02-25T10:06:35.249587Z","steps":["trace[1948917332] 'process raft request' (duration: 218.199817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:35.40081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.422044ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T10:06:48.687458Z","caller":"traceutil/trace.go:171","msg":"trace[1260226747] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"482.25826ms","start":"2026-02-25T10:06:48.205151Z","end":"2026-02-25T10:06:48.687409Z","steps":["trace[1260226747] 'process raft request' (duration: 482.082376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:48.687631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:48.205116Z","time spent":"482.428043ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:49.860817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.744279ms","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-02-25T10:06:49.860984Z","caller":"traceutil/trace.go:171","msg":"trace[1294764546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"661.970574ms","start":"2026-02-25T10:06:49.198981Z","end":"2026-02-25T10:06:49.860951Z","steps":["trace[1294764546] 'range keys from in-memory index tree' (duration: 661.394151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.861035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:49.19895Z","time spent":"662.074796ms","remote":"127.0.0.1:43018","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-02-25T10:06:49.861242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.173414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:06:49.861292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.033245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T10:06:49.861358Z","caller":"traceutil/trace.go:171","msg":"trace[905401326] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:931; }","duration":"134.362739ms","start":"2026-02-25T10:06:49.72697Z","end":"2026-02-25T10:06:49.861333Z","steps":["trace[905401326] 'count revisions from in-memory index tree' (duration: 134.076062ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:49.861378Z","caller":"traceutil/trace.go:171","msg":"trace[2029566169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"542.160447ms","start":"2026-02-25T10:06:49.319198Z","end":"2026-02-25T10:06:49.861359Z","steps":["trace[2029566169] 'range keys from in-memory index tree' (duration: 541.871872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.861359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.463897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T10:06:49.861422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:49.319189Z","time spent":"542.225069ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:06:49.861432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.988876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T10:06:49.861449Z","caller":"traceutil/trace.go:171","msg":"trace[1963174697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"548.656173ms","start":"2026-02-25T10:06:49.31277Z","end":"2026-02-25T10:06:49.861426Z","steps":["trace[1963174697] 'range keys from in-memory index tree' (duration: 548.217722ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:49.861462Z","caller":"traceutil/trace.go:171","msg":"trace[1995013611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"543.041467ms","start":"2026-02-25T10:06:49.318412Z","end":"2026-02-25T10:06:49.861453Z","steps":["trace[1995013611] 'range keys from in-memory index tree' (duration: 542.850683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.861489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:49.318401Z","time spent":"543.082438ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:06:49.861494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:49.312733Z","time spent":"548.753465ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:06:49.863046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.949979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:49.863154Z","caller":"traceutil/trace.go:171","msg":"trace[1091058684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"263.126103ms","start":"2026-02-25T10:06:49.600003Z","end":"2026-02-25T10:06:49.863129Z","steps":["trace[1091058684] 'range keys from in-memory index tree' (duration: 262.835867ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:07.812192Z","caller":"traceutil/trace.go:171","msg":"trace[1190378293] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"209.155816ms","start":"2026-02-25T10:07:07.603007Z","end":"2026-02-25T10:07:07.812163Z","steps":["trace[1190378293] 'process raft request' (duration: 208.975352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:08.90408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.215557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:08.904176Z","caller":"traceutil/trace.go:171","msg":"trace[162067175] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"903.013658ms","start":"2026-02-25T10:07:08.001142Z","end":"2026-02-25T10:07:08.904156Z","steps":["trace[162067175] 'read index received' (duration: 66.532085ms)","trace[162067175] 'applied index is now lower than readState.Index' (duration: 836.480003ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:08.904403Z","caller":"traceutil/trace.go:171","msg":"trace[36296172] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"903.639251ms","start":"2026-02-25T10:07:08.000749Z","end":"2026-02-25T10:07:08.904388Z","steps":["trace[36296172] 'process raft request' (duration: 67.018475ms)","trace[36296172] 'compare' (duration: 835.837369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:08.904466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:08.000723Z","time spent":"903.710154ms","remote":"127.0.0.1:43018","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-02-25T10:07:08.904687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.540739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T10:07:08.904717Z","caller":"traceutil/trace.go:171","msg":"trace[1118565694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"903.58312ms","start":"2026-02-25T10:07:08.001123Z","end":"2026-02-25T10:07:08.904706Z","steps":["trace[1118565694] 'agreement among raft nodes before linearized reading' (duration: 903.484028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:08.904745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:08.00111Z","time spent":"903.62857ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:08.904883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.69589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T10:07:08.904953Z","caller":"traceutil/trace.go:171","msg":"trace[389392622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"888.806943ms","start":"2026-02-25T10:07:08.016123Z","end":"2026-02-25T10:07:08.90493Z","steps":["trace[389392622] 'agreement among raft nodes before linearized reading' (duration: 888.587968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:08.904989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:08.01611Z","time spent":"888.872814ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:09.647665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.500546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:09.64782Z","caller":"traceutil/trace.go:171","msg":"trace[1106381425] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"738.782486ms","start":"2026-02-25T10:07:08.909011Z","end":"2026-02-25T10:07:09.647793Z","steps":["trace[1106381425] 'process raft request' (duration: 362.017875ms)","trace[1106381425] 'compare' (duration: 376.018594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:09.647895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:08.908995Z","time spent":"738.863598ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:10.178027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.919144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:10.178183Z","caller":"traceutil/trace.go:171","msg":"trace[657960004] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1051; }","duration":"574.904664ms","start":"2026-02-25T10:07:09.603263Z","end":"2026-02-25T10:07:10.178167Z","steps":["trace[657960004] 'read index received' (duration: 433.740524ms)","trace[657960004] 'applied index is now lower than readState.Index' (duration: 141.160499ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:10.178262Z","caller":"traceutil/trace.go:171","msg":"trace[1139570525] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"1.267997848s","start":"2026-02-25T10:07:08.910254Z","end":"2026-02-25T10:07:10.178252Z","steps":["trace[1139570525] 'process raft request' (duration: 1.267857515s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:10.178289Z","caller":"traceutil/trace.go:171","msg":"trace[1199872465] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"1.268106931s","start":"2026-02-25T10:07:08.910171Z","end":"2026-02-25T10:07:10.178278Z","steps":["trace[1199872465] 'process raft request' (duration: 1.126848589s)","trace[1199872465] 'compare' (duration: 140.808352ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:10.178321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:08.910248Z","time spent":"1.268042879s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:10.178335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:08.910141Z","time spent":"1.268169413s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:10.178431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.17689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:10.178454Z","caller":"traceutil/trace.go:171","msg":"trace[454904252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"575.248122ms","start":"2026-02-25T10:07:09.603196Z","end":"2026-02-25T10:07:10.178444Z","steps":["trace[454904252] 'agreement among raft nodes before linearized reading' (duration: 575.212341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:10.178475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:09.603167Z","time spent":"575.303742ms","remote":"127.0.0.1:42740","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-02-25T10:07:10.178619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.372286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:07:10.178727Z","caller":"traceutil/trace.go:171","msg":"trace[2107384816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"183.525519ms","start":"2026-02-25T10:07:09.995179Z","end":"2026-02-25T10:07:10.178704Z","steps":["trace[2107384816] 'agreement among raft nodes before linearized reading' (duration: 183.253153ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:10.344149Z","caller":"traceutil/trace.go:171","msg":"trace[1509302667] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"120.601193ms","start":"2026-02-25T10:07:10.223524Z","end":"2026-02-25T10:07:10.344126Z","steps":["trace[1509302667] 'process raft request' (duration: 106.374508ms)","trace[1509302667] 'compare' (duration: 14.124303ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:11.134579Z","caller":"traceutil/trace.go:171","msg":"trace[204331852] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"220.56741ms","start":"2026-02-25T10:07:10.913984Z","end":"2026-02-25T10:07:11.134551Z","steps":["trace[204331852] 'process raft request' (duration: 220.410426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.457973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.809626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:07:12.458036Z","caller":"traceutil/trace.go:171","msg":"trace[911038581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"253.920519ms","start":"2026-02-25T10:07:12.2041Z","end":"2026-02-25T10:07:12.45802Z","steps":["trace[911038581] 'range keys from in-memory index tree' (duration: 253.653854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.458118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.5221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:12.45819Z","caller":"traceutil/trace.go:171","msg":"trace[986671563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"848.653492ms","start":"2026-02-25T10:07:11.609519Z","end":"2026-02-25T10:07:12.458173Z","steps":["trace[986671563] 'range keys from in-memory index tree' (duration: 848.408157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.457975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.803912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T10:07:12.458238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.927525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:07:12.458267Z","caller":"traceutil/trace.go:171","msg":"trace[947687011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"275.003326ms","start":"2026-02-25T10:07:12.183257Z","end":"2026-02-25T10:07:12.45826Z","steps":["trace[947687011] 'range keys from in-memory index tree' (duration: 274.809632ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:12.458265Z","caller":"traceutil/trace.go:171","msg":"trace[1106074921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"805.142369ms","start":"2026-02-25T10:07:11.653109Z","end":"2026-02-25T10:07:12.458252Z","steps":["trace[1106074921] 'range keys from in-memory index tree' (duration: 804.662048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.4583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:11.653082Z","time spent":"805.20748ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:12.458325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.818342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T10:07:12.458351Z","caller":"traceutil/trace.go:171","msg":"trace[335534606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"274.886693ms","start":"2026-02-25T10:07:12.183458Z","end":"2026-02-25T10:07:12.458345Z","steps":["trace[335534606] 'range keys from in-memory index tree' (duration: 274.639028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.458135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077620398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:07:12.458386Z","caller":"traceutil/trace.go:171","msg":"trace[644505986] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1011; }","duration":"1.077927425s","start":"2026-02-25T10:07:11.38045Z","end":"2026-02-25T10:07:12.458377Z","steps":["trace[644505986] 'count revisions from in-memory index tree' (duration: 1.077512216s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.458419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:11.380421Z","time spent":"1.077989817s","remote":"127.0.0.1:43206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:07:12.458227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:11.609494Z","time spent":"848.726884ms","remote":"127.0.0.1:42740","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-02-25T10:07:12.613977Z","caller":"traceutil/trace.go:171","msg":"trace[1338778504] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"151.522739ms","start":"2026-02-25T10:07:12.462427Z","end":"2026-02-25T10:07:12.61395Z","steps":["trace[1338778504] 'process raft request' (duration: 151.356115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:13.144662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754558181,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:07:13.365979Z","caller":"traceutil/trace.go:171","msg":"trace[1874110091] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1059; }","duration":"721.404073ms","start":"2026-02-25T10:07:12.644543Z","end":"2026-02-25T10:07:13.365947Z","steps":["trace[1874110091] 'read index received' (duration: 721.094096ms)","trace[1874110091] 'applied index is now lower than readState.Index' (duration: 308.407µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:13.366059Z","caller":"traceutil/trace.go:171","msg":"trace[969137798] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"902.873345ms","start":"2026-02-25T10:07:12.463138Z","end":"2026-02-25T10:07:13.366012Z","steps":["trace[969137798] 'process raft request' (duration: 902.509477ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:13.366127Z","caller":"traceutil/trace.go:171","msg":"trace[134754372] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"902.957027ms","start":"2026-02-25T10:07:12.463157Z","end":"2026-02-25T10:07:13.366114Z","steps":["trace[134754372] 'process raft request' (duration: 902.704722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:13.366253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.463112Z","time spent":"903.039569ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:13.366358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.463119Z","time spent":"903.09963ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:13.366529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.978755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:13.366591Z","caller":"traceutil/trace.go:171","msg":"trace[1577057928] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1015; }","duration":"722.087697ms","start":"2026-02-25T10:07:12.644488Z","end":"2026-02-25T10:07:13.366576Z","steps":["trace[1577057928] 'agreement among raft nodes before linearized reading' (duration: 721.979595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:13.366633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.644467Z","time spent":"722.154899ms","remote":"127.0.0.1:46568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:07:13.366644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.933631ms","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-02-25T10:07:13.366662Z","caller":"traceutil/trace.go:171","msg":"trace[1615051626] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"902.539577ms","start":"2026-02-25T10:07:12.464107Z","end":"2026-02-25T10:07:13.366646Z","steps":["trace[1615051626] 'process raft request' (duration: 901.798031ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:13.366676Z","caller":"traceutil/trace.go:171","msg":"trace[1662093344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"226.992842ms","start":"2026-02-25T10:07:13.139672Z","end":"2026-02-25T10:07:13.366665Z","steps":["trace[1662093344] 'agreement among raft nodes before linearized reading' (duration: 226.850209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:13.366742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.464083Z","time spent":"902.613059ms","remote":"127.0.0.1:43018","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-02-25T10:07:13.975702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.537256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:13.975881Z","caller":"traceutil/trace.go:171","msg":"trace[848494380] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"401.462409ms","start":"2026-02-25T10:07:13.574393Z","end":"2026-02-25T10:07:13.975855Z","steps":["trace[848494380] 'process raft request' (duration: 264.687517ms)","trace[848494380] 'compare' (duration: 136.358473ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:13.975956Z","caller":"traceutil/trace.go:171","msg":"trace[2101880849] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"395.615899ms","start":"2026-02-25T10:07:13.580319Z","end":"2026-02-25T10:07:13.975935Z","steps":["trace[2101880849] 'process raft request' (duration: 395.478786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:13.975996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.574381Z","time spent":"401.556111ms","remote":"127.0.0.1:42808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:13.976055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.580161Z","time spent":"395.844934ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:14.062376Z","caller":"traceutil/trace.go:171","msg":"trace[1535607604] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1066; }","duration":"464.66261ms","start":"2026-02-25T10:07:13.597683Z","end":"2026-02-25T10:07:14.062345Z","steps":["trace[1535607604] 'read index received' (duration: 241.403852ms)","trace[1535607604] 'applied index is now lower than readState.Index' (duration: 223.257568ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:14.062431Z","caller":"traceutil/trace.go:171","msg":"trace[1986236215] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"466.685324ms","start":"2026-02-25T10:07:13.595703Z","end":"2026-02-25T10:07:14.062388Z","steps":["trace[1986236215] 'process raft request' (duration: 466.49618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:14.062602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.595681Z","time spent":"466.833198ms","remote":"127.0.0.1:42808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:14.062576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.863434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:14.062791Z","caller":"traceutil/trace.go:171","msg":"trace[495604506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"465.11936ms","start":"2026-02-25T10:07:13.59765Z","end":"2026-02-25T10:07:14.062769Z","steps":["trace[495604506] 'agreement among raft nodes before linearized reading' (duration: 464.812914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:14.062952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.597643Z","time spent":"465.193062ms","remote":"127.0.0.1:42740","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-02-25T10:07:15.075786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.982007ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:15.07588Z","caller":"traceutil/trace.go:171","msg":"trace[1383910174] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1022; }","duration":"290.08998ms","start":"2026-02-25T10:07:14.785775Z","end":"2026-02-25T10:07:15.075865Z","steps":["trace[1383910174] 'range keys from in-memory index tree' (duration: 289.966707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:15.076169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.45498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:15.076209Z","caller":"traceutil/trace.go:171","msg":"trace[445978425] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"1.011491932s","start":"2026-02-25T10:07:14.064711Z","end":"2026-02-25T10:07:15.076203Z","steps":["trace[445978425] 'read index received' (duration: 447.855708ms)","trace[445978425] 'applied index is now lower than readState.Index' (duration: 563.635624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:15.07633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.447411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T10:07:15.076353Z","caller":"traceutil/trace.go:171","msg":"trace[1382290959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"457.507262ms","start":"2026-02-25T10:07:14.61884Z","end":"2026-02-25T10:07:15.076347Z","steps":["trace[1382290959] 'agreement among raft nodes before linearized reading' (duration: 457.41794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:15.076379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:14.618817Z","time spent":"457.555823ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:15.076374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011624675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:15.076573Z","caller":"traceutil/trace.go:171","msg":"trace[320547800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"1.011884511s","start":"2026-02-25T10:07:14.064647Z","end":"2026-02-25T10:07:15.076532Z","steps":["trace[320547800] 'agreement among raft nodes before linearized reading' (duration: 1.011612905s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:15.076693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:14.064626Z","time spent":"1.012022184s","remote":"127.0.0.1:42744","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-02-25T10:07:15.077096Z","caller":"traceutil/trace.go:171","msg":"trace[870782990] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.185782877s","start":"2026-02-25T10:07:13.891285Z","end":"2026-02-25T10:07:15.077068Z","steps":["trace[870782990] 'process raft request' (duration: 621.383055ms)","trace[870782990] 'compare' (duration: 562.957919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:15.07732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.891256Z","time spent":"1.185935649s","remote":"127.0.0.1:43018","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-02-25T10:07:15.416095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.756319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:15.416393Z","caller":"traceutil/trace.go:171","msg":"trace[791730377] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"335.745283ms","start":"2026-02-25T10:07:15.080604Z","end":"2026-02-25T10:07:15.416349Z","steps":["trace[791730377] 'process raft request' (duration: 157.620804ms)","trace[791730377] 'compare' (duration: 177.627247ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:15.416477Z","caller":"traceutil/trace.go:171","msg":"trace[1321993426] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"331.744085ms","start":"2026-02-25T10:07:15.084706Z","end":"2026-02-25T10:07:15.41645Z","steps":["trace[1321993426] 'read index received' (duration: 153.489413ms)","trace[1321993426] 'applied index is now lower than readState.Index' (duration: 178.252182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:15.416923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:15.080579Z","time spent":"335.931797ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:15.481526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.13674ms","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-02-25T10:07:15.481587Z","caller":"traceutil/trace.go:171","msg":"trace[195010028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"109.246572ms","start":"2026-02-25T10:07:15.372325Z","end":"2026-02-25T10:07:15.481572Z","steps":["trace[195010028] 'agreement among raft nodes before linearized reading' (duration: 109.075868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:15.481883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.445396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-25T10:07:15.481878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.065198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:07:15.48197Z","caller":"traceutil/trace.go:171","msg":"trace[1486181841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"109.19575ms","start":"2026-02-25T10:07:15.37275Z","end":"2026-02-25T10:07:15.481945Z","steps":["trace[1486181841] 'agreement among raft nodes before linearized reading' (duration: 108.911674ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:15.481915Z","caller":"traceutil/trace.go:171","msg":"trace[485365092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"109.528828ms","start":"2026-02-25T10:07:15.372377Z","end":"2026-02-25T10:07:15.481905Z","steps":["trace[485365092] 'agreement among raft nodes before linearized reading' (duration: 109.422676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:15.48218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.470451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:15.482239Z","caller":"traceutil/trace.go:171","msg":"trace[1850743352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"397.560853ms","start":"2026-02-25T10:07:15.084664Z","end":"2026-02-25T10:07:15.482225Z","steps":["trace[1850743352] 'agreement among raft nodes before linearized reading' (duration: 332.110873ms)","trace[1850743352] 'range keys from in-memory index tree' (duration: 65.376229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:15.48227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:15.084648Z","time spent":"397.612694ms","remote":"127.0.0.1:42740","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-02-25T10:07:22.73945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.884549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:22.739557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.729162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:22.739592Z","caller":"traceutil/trace.go:171","msg":"trace[141743582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"138.828865ms","start":"2026-02-25T10:07:22.600752Z","end":"2026-02-25T10:07:22.739581Z","steps":["trace[141743582] 'range keys from in-memory index tree' (duration: 138.61838ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:22.739553Z","caller":"traceutil/trace.go:171","msg":"trace[1900665148] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1071; }","duration":"470.070574ms","start":"2026-02-25T10:07:22.269457Z","end":"2026-02-25T10:07:22.739527Z","steps":["trace[1900665148] 'range keys from in-memory index tree' (duration: 469.800538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:22.739725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:22.269432Z","time spent":"470.279298ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-25T10:07:23.48749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754558295,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:23.988332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420780754558295,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:24.163878Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.384735467s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:07:24.164541Z","caller":"traceutil/trace.go:171","msg":"trace[818522757] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"1.419643458s","start":"2026-02-25T10:07:22.74487Z","end":"2026-02-25T10:07:24.164513Z","steps":["trace[818522757] 'process raft request' (duration: 1.41930163s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.164715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:22.744802Z","time spent":"1.419826231s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:07:24.16481Z","caller":"traceutil/trace.go:171","msg":"trace[1368993356] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1121; }","duration":"1.178034867s","start":"2026-02-25T10:07:22.986762Z","end":"2026-02-25T10:07:24.164797Z","steps":["trace[1368993356] 'read index received' (duration: 1.178030167s)","trace[1368993356] 'applied index is now lower than readState.Index' (duration: 3.72µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:24.165034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130344615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-25T10:07:24.165058Z","caller":"traceutil/trace.go:171","msg":"trace[527131218] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1072; }","duration":"1.130377005s","start":"2026-02-25T10:07:23.034673Z","end":"2026-02-25T10:07:24.16505Z","steps":["trace[527131218] 'agreement among raft nodes before linearized reading' (duration: 1.130232032s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.165079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.034647Z","time spent":"1.130427356s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T10:07:24.165171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.512049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:24.165209Z","caller":"traceutil/trace.go:171","msg":"trace[1851920649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"526.56681ms","start":"2026-02-25T10:07:23.638631Z","end":"2026-02-25T10:07:24.165198Z","steps":["trace[1851920649] 'agreement among raft nodes before linearized reading' (duration: 526.510068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.165239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.638626Z","time spent":"526.60668ms","remote":"127.0.0.1:42740","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-02-25T10:07:24.165277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.723796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:07:24.165299Z","caller":"traceutil/trace.go:171","msg":"trace[1272698347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1072; }","duration":"527.747527ms","start":"2026-02-25T10:07:23.637545Z","end":"2026-02-25T10:07:24.165293Z","steps":["trace[1272698347] 'agreement among raft nodes before linearized reading' (duration: 527.677295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.165325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.63753Z","time spent":"527.790817ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:24.165331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178506917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:07:24.16541Z","caller":"traceutil/trace.go:171","msg":"trace[839011754] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1072; }","duration":"1.178661091s","start":"2026-02-25T10:07:22.986722Z","end":"2026-02-25T10:07:24.165383Z","steps":["trace[839011754] 'agreement among raft nodes before linearized reading' (duration: 1.178448886s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.165451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.394996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-25T10:07:24.165477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:22.986705Z","time spent":"1.178759242s","remote":"127.0.0.1:42854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:07:24.165485Z","caller":"traceutil/trace.go:171","msg":"trace[1555843326] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1072; }","duration":"173.469778ms","start":"2026-02-25T10:07:23.992005Z","end":"2026-02-25T10:07:24.165475Z","steps":["trace[1555843326] 'agreement among raft nodes before linearized reading' (duration: 173.371765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.16557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.120244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:07:24.165597Z","caller":"traceutil/trace.go:171","msg":"trace[1357568217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1072; }","duration":"528.163016ms","start":"2026-02-25T10:07:23.637426Z","end":"2026-02-25T10:07:24.165589Z","steps":["trace[1357568217] 'agreement among raft nodes before linearized reading' (duration: 528.088974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.165619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.63742Z","time spent":"528.192067ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:24.165662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.837035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T10:07:24.165693Z","caller":"traceutil/trace.go:171","msg":"trace[2021210104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1072; }","duration":"526.877427ms","start":"2026-02-25T10:07:23.638808Z","end":"2026-02-25T10:07:24.165685Z","steps":["trace[2021210104] 'agreement among raft nodes before linearized reading' (duration: 526.792805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.165721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.638802Z","time spent":"526.912378ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:24.165738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.300138ms","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-02-25T10:07:24.165755Z","caller":"traceutil/trace.go:171","msg":"trace[1669551355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1072; }","duration":"528.34629ms","start":"2026-02-25T10:07:23.637403Z","end":"2026-02-25T10:07:24.16575Z","steps":["trace[1669551355] 'agreement among raft nodes before linearized reading' (duration: 528.292748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.165771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.637387Z","time spent":"528.38051ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:24.165862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.443145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T10:07:24.165878Z","caller":"traceutil/trace.go:171","msg":"trace[178286480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1072; }","duration":"718.491786ms","start":"2026-02-25T10:07:23.44738Z","end":"2026-02-25T10:07:24.165872Z","steps":["trace[178286480] 'agreement among raft nodes before linearized reading' (duration: 718.444135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.165893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.447329Z","time spent":"718.560748ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:24.473206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.200053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:24.473273Z","caller":"traceutil/trace.go:171","msg":"trace[1726753627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"304.358377ms","start":"2026-02-25T10:07:24.168897Z","end":"2026-02-25T10:07:24.473256Z","steps":["trace[1726753627] 'range keys from in-memory index tree' (duration: 304.096712ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.473262Z","caller":"traceutil/trace.go:171","msg":"trace[5645739] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"300.952731ms","start":"2026-02-25T10:07:24.172285Z","end":"2026-02-25T10:07:24.473238Z","steps":["trace[5645739] 'process raft request' (duration: 239.556426ms)","trace[5645739] 'compare' (duration: 61.280583ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:24.473303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:24.168863Z","time spent":"304.433539ms","remote":"127.0.0.1:42744","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-02-25T10:07:24.473702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:24.172258Z","time spent":"301.066604ms","remote":"127.0.0.1:43018","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-02-25T10:07:24.473812Z","caller":"traceutil/trace.go:171","msg":"trace[330448999] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"297.257479ms","start":"2026-02-25T10:07:24.176536Z","end":"2026-02-25T10:07:24.473793Z","steps":["trace[330448999] 'process raft request' (duration: 297.102856ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.473929Z","caller":"traceutil/trace.go:171","msg":"trace[876047582] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"300.994942ms","start":"2026-02-25T10:07:24.17292Z","end":"2026-02-25T10:07:24.473915Z","steps":["trace[876047582] 'process raft request' (duration: 300.600824ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.473988Z","caller":"traceutil/trace.go:171","msg":"trace[1289743889] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"297.102186ms","start":"2026-02-25T10:07:24.176865Z","end":"2026-02-25T10:07:24.473967Z","steps":["trace[1289743889] 'process raft request' (duration: 296.844921ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.475225Z","caller":"traceutil/trace.go:171","msg":"trace[128386202] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1121; }","duration":"298.434705ms","start":"2026-02-25T10:07:24.176754Z","end":"2026-02-25T10:07:24.475189Z","steps":["trace[128386202] 'read index received' (duration: 235.099468ms)","trace[128386202] 'applied index is now lower than readState.Index' (duration: 63.334297ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:24.475874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.11476ms","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:531"} {"level":"info","ts":"2026-02-25T10:07:24.475918Z","caller":"traceutil/trace.go:171","msg":"trace[1151083751] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1080; }","duration":"299.189552ms","start":"2026-02-25T10:07:24.17671Z","end":"2026-02-25T10:07:24.4759Z","steps":["trace[1151083751] 'agreement among raft nodes before linearized reading' (duration: 298.554457ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.476501Z","caller":"traceutil/trace.go:171","msg":"trace[1009898823] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"299.51687ms","start":"2026-02-25T10:07:24.17696Z","end":"2026-02-25T10:07:24.476477Z","steps":["trace[1009898823] 'process raft request' (duration: 296.939993ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.476659Z","caller":"traceutil/trace.go:171","msg":"trace[1575959167] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"299.54725ms","start":"2026-02-25T10:07:24.177103Z","end":"2026-02-25T10:07:24.47665Z","steps":["trace[1575959167] 'process raft request' (duration: 296.901352ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.476844Z","caller":"traceutil/trace.go:171","msg":"trace[542622950] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"298.420496ms","start":"2026-02-25T10:07:24.178382Z","end":"2026-02-25T10:07:24.476803Z","steps":["trace[542622950] 'process raft request' (duration: 296.700867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.474016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:24.172903Z","time spent":"301.060594ms","remote":"127.0.0.1:43018","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-02-25T10:07:24.477087Z","caller":"traceutil/trace.go:171","msg":"trace[1369352392] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"294.404887ms","start":"2026-02-25T10:07:24.182443Z","end":"2026-02-25T10:07:24.476848Z","steps":["trace[1369352392] 'process raft request' (duration: 292.704829ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:47.482034Z","caller":"traceutil/trace.go:171","msg":"trace[1376905047] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"160.138462ms","start":"2026-02-25T10:08:47.321862Z","end":"2026-02-25T10:08:47.482Z","steps":["trace[1376905047] 'process raft request' (duration: 159.964868ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:10:43.06461Z","caller":"traceutil/trace.go:171","msg":"trace[1296571595] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"306.535399ms","start":"2026-02-25T10:10:42.758042Z","end":"2026-02-25T10:10:43.064577Z","steps":["trace[1296571595] 'process raft request' (duration: 306.287084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:10:43.064814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:10:42.758013Z","time spent":"306.702243ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:10:43.066749Z","caller":"traceutil/trace.go:171","msg":"trace[609066147] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"269.209419ms","start":"2026-02-25T10:10:42.797527Z","end":"2026-02-25T10:10:43.066737Z","steps":["trace[609066147] 'process raft request' (duration: 269.158828ms)"],"step_count":1}