{"level":"warn","ts":"2026-07-31T07:22:48.674404Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-31T07:22:48.674474Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-31T07:22:48.67453Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-31T07:22:48.674542Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-07-31T07:22:48.67456Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-31T07:22:48.675252Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-07-31T07:22:48.675322Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-31T07:22:48.679414Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.909042ms"} {"level":"info","ts":"2026-07-31T07:22:48.692199Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-07-31T07:22:48.692271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-07-31T07:22:48.692291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-07-31T07:22:48.692301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-31T07:22:48.692312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-07-31T07:22:48.692347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-07-31T07:22:48.705361Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-31T07:22:48.709313Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-31T07:22:48.71048Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-31T07:22:48.713696Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-31T07:22:48.713971Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-31T07:22:48.714106Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T07:22:48.714152Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T07:22:48.714161Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T07:22:48.716293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-07-31T07:22:48.716471Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-07-31T07:22:48.718564Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-31T07:22:48.718786Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-07-31T07:22:48.718832Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-07-31T07:22:48.720147Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-31T07:22:48.720184Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-31T07:22:49.593029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-07-31T07:22:49.593082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-31T07:22:49.593122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-07-31T07:22:49.593135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-07-31T07:22:49.593145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-07-31T07:22:49.593155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-07-31T07:22:49.593167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-07-31T07:22:49.595464Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T07:22:49.596607Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-07-31T07:22:49.596642Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T07:22:49.596663Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T07:22:49.596935Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-31T07:22:49.596995Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-31T07:22:49.597272Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T07:22:49.597401Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T07:22:49.597446Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T07:22:49.59862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-07-31T07:22:49.5991Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-31T07:22:54.114077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505762289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:22:54.603536Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103853787s","expected-duration":"1s"} {"level":"info","ts":"2026-07-31T07:22:54.603996Z","caller":"traceutil/trace.go:171","msg":"trace[1956091409] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"1.104420601s","start":"2026-07-31T07:22:53.499548Z","end":"2026-07-31T07:22:54.603969Z","steps":["trace[1956091409] 'process raft request' (duration: 1.104272738s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:22:54.604634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:22:53.499516Z","time spent":"1.104561615s","remote":"127.0.0.1:58248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:22:56.177785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.573939099s","expected-duration":"1s"} {"level":"info","ts":"2026-07-31T07:22:56.182287Z","caller":"traceutil/trace.go:171","msg":"trace[740331496] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:208; }","duration":"2.568623712s","start":"2026-07-31T07:22:53.613636Z","end":"2026-07-31T07:22:56.18226Z","steps":["trace[740331496] 'read index received' (duration: 990.173785ms)","trace[740331496] 'applied index is now lower than readState.Index' (duration: 1.578448557s)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:22:56.182499Z","caller":"traceutil/trace.go:171","msg":"trace[608200485] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"2.680379743s","start":"2026-07-31T07:22:53.502099Z","end":"2026-07-31T07:22:56.182479Z","steps":["trace[608200485] 'process raft request' (duration: 2.675903536s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:22:56.182616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:22:53.502088Z","time spent":"2.680452484s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:22:56.183038Z","caller":"traceutil/trace.go:171","msg":"trace[1835213385] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"2.657050192s","start":"2026-07-31T07:22:53.525936Z","end":"2026-07-31T07:22:56.182986Z","steps":["trace[1835213385] 'process raft request' (duration: 2.656162138s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:22:56.18307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.569419883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:22:56.183171Z","caller":"traceutil/trace.go:171","msg":"trace[1212721783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:206; }","duration":"2.569566537s","start":"2026-07-31T07:22:53.613585Z","end":"2026-07-31T07:22:56.183152Z","steps":["trace[1212721783] 'agreement among raft nodes before linearized reading' (duration: 2.569311261s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:22:56.183209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:22:53.613565Z","time spent":"2.569634349s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:22:56.183205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:22:53.525914Z","time spent":"2.657218317s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:22:56.188444Z","caller":"traceutil/trace.go:171","msg":"trace[1510119440] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"398.021702ms","start":"2026-07-31T07:22:55.790403Z","end":"2026-07-31T07:22:56.188425Z","steps":["trace[1510119440] 'process raft request' (duration: 397.96432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:22:56.188584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:22:55.790385Z","time spent":"398.111374ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:22:56.188982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.169879282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:22:56.188984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580393098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-0pzyhv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:22:56.188995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107717599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:22:56.189031Z","caller":"traceutil/trace.go:171","msg":"trace[1082569316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:207; }","duration":"1.107793851s","start":"2026-07-31T07:22:55.081228Z","end":"2026-07-31T07:22:56.189022Z","steps":["trace[1082569316] 'agreement among raft nodes before linearized reading' (duration: 1.107670018s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:22:56.189031Z","caller":"traceutil/trace.go:171","msg":"trace[208000625] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-0pzyhv; range_end:; response_count:0; response_revision:207; }","duration":"1.58048884s","start":"2026-07-31T07:22:54.608528Z","end":"2026-07-31T07:22:56.189017Z","steps":["trace[208000625] 'agreement among raft nodes before linearized reading' (duration: 1.580379127s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:22:56.189059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:22:55.081209Z","time spent":"1.107844973s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T07:22:56.189016Z","caller":"traceutil/trace.go:171","msg":"trace[853088799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:207; }","duration":"2.169950084s","start":"2026-07-31T07:22:54.019057Z","end":"2026-07-31T07:22:56.189007Z","steps":["trace[853088799] 'agreement among raft nodes before linearized reading' (duration: 2.169862951s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:22:56.189064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:22:54.608506Z","time spent":"1.580552852s","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-0pzyhv\" "} {"level":"warn","ts":"2026-07-31T07:22:56.189096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:22:54.019036Z","time spent":"2.170052967s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T07:23:04.422477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.292524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:23:04.422591Z","caller":"traceutil/trace.go:171","msg":"trace[407928431] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"180.518306ms","start":"2026-07-31T07:23:04.242055Z","end":"2026-07-31T07:23:04.422573Z","steps":["trace[407928431] 'read index received' (duration: 21.756601ms)","trace[407928431] 'applied index is now lower than readState.Index' (duration: 158.760245ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:23:04.422651Z","caller":"traceutil/trace.go:171","msg":"trace[2089798816] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"182.516707ms","start":"2026-07-31T07:23:04.240072Z","end":"2026-07-31T07:23:04.422589Z","steps":["trace[2089798816] 'process raft request' (duration: 79.830563ms)","trace[2089798816] 'compare' (duration: 102.15349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:23:04.422707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.644999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-31T07:23:04.422925Z","caller":"traceutil/trace.go:171","msg":"trace[1690586218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:382; }","duration":"180.850035ms","start":"2026-07-31T07:23:04.242039Z","end":"2026-07-31T07:23:04.422889Z","steps":["trace[1690586218] 'agreement among raft nodes before linearized reading' (duration: 180.623459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:04.422794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.448437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-31T07:23:04.423071Z","caller":"traceutil/trace.go:171","msg":"trace[2000436269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:382; }","duration":"179.760315ms","start":"2026-07-31T07:23:04.243288Z","end":"2026-07-31T07:23:04.423048Z","steps":["trace[2000436269] 'agreement among raft nodes before linearized reading' (duration: 179.410126ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.029007Z","caller":"traceutil/trace.go:171","msg":"trace[1921613740] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"782.719213ms","start":"2026-07-31T07:23:29.246262Z","end":"2026-07-31T07:23:30.028981Z","steps":["trace[1921613740] 'process raft request' (duration: 782.574028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:30.029264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.246227Z","time spent":"782.950729ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:23:30.085618Z","caller":"traceutil/trace.go:171","msg":"trace[1532133808] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"831.902533ms","start":"2026-07-31T07:23:29.253683Z","end":"2026-07-31T07:23:30.085585Z","steps":["trace[1532133808] 'process raft request' (duration: 831.656656ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.085722Z","caller":"traceutil/trace.go:171","msg":"trace[64835928] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"826.983274ms","start":"2026-07-31T07:23:29.258682Z","end":"2026-07-31T07:23:30.085665Z","steps":["trace[64835928] 'process raft request' (duration: 826.82384ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.085784Z","caller":"traceutil/trace.go:171","msg":"trace[730716760] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"810.42883ms","start":"2026-07-31T07:23:29.275294Z","end":"2026-07-31T07:23:30.085722Z","steps":["trace[730716760] 'process raft request' (duration: 810.307557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:30.085962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.258662Z","time spent":"827.188159ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:23:30.085936Z","caller":"traceutil/trace.go:171","msg":"trace[854778489] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"814.370513ms","start":"2026-07-31T07:23:29.271304Z","end":"2026-07-31T07:23:30.085674Z","steps":["trace[854778489] 'process raft request' (duration: 814.25433ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.08615Z","caller":"traceutil/trace.go:171","msg":"trace[512648468] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"759.537334ms","start":"2026-07-31T07:23:29.3266Z","end":"2026-07-31T07:23:30.086138Z","steps":["trace[512648468] 'process raft request' (duration: 759.271177ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.086161Z","caller":"traceutil/trace.go:171","msg":"trace[1629573551] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"789.976653ms","start":"2026-07-31T07:23:29.296167Z","end":"2026-07-31T07:23:30.086144Z","steps":["trace[1629573551] 'process raft request' (duration: 789.645245ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.086139Z","caller":"traceutil/trace.go:171","msg":"trace[1523884316] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"806.776074ms","start":"2026-07-31T07:23:29.279325Z","end":"2026-07-31T07:23:30.086101Z","steps":["trace[1523884316] 'process raft request' (duration: 806.356983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:30.086219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.32657Z","time spent":"759.623726ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:23:30.086225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.296142Z","time spent":"790.056516ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:23:30.086299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.279322Z","time spent":"806.902007ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:23:30.086328Z","caller":"traceutil/trace.go:171","msg":"trace[295958950] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"797.222343ms","start":"2026-07-31T07:23:29.289088Z","end":"2026-07-31T07:23:30.086311Z","steps":["trace[295958950] 'process raft request' (duration: 796.632658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:30.086378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.289073Z","time spent":"797.279135ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:23:30.086373Z","caller":"traceutil/trace.go:171","msg":"trace[1338445010] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"671.766612ms","start":"2026-07-31T07:23:29.414578Z","end":"2026-07-31T07:23:30.086345Z","steps":["trace[1338445010] 'process raft request' (duration: 671.359422ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.086197Z","caller":"traceutil/trace.go:171","msg":"trace[1763144092] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"790.550808ms","start":"2026-07-31T07:23:29.295455Z","end":"2026-07-31T07:23:30.086006Z","steps":["trace[1763144092] 'process raft request' (duration: 790.309631ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.086463Z","caller":"traceutil/trace.go:171","msg":"trace[1245094905] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"807.162084ms","start":"2026-07-31T07:23:29.27928Z","end":"2026-07-31T07:23:30.086442Z","steps":["trace[1245094905] 'process raft request' (duration: 806.362323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:30.086507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.414556Z","time spent":"671.888876ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:23:30.086557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.279268Z","time spent":"807.249036ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:23:30.086076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.27528Z","time spent":"810.599724ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:23:30.086635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.295446Z","time spent":"791.024091ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:23:30.086663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.271286Z","time spent":"814.933218ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:23:30.086877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:29.253665Z","time spent":"832.029737ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:23:30.179938Z","caller":"traceutil/trace.go:171","msg":"trace[863660576] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"142.270352ms","start":"2026-07-31T07:23:30.037634Z","end":"2026-07-31T07:23:30.179904Z","steps":["trace[863660576] 'process raft request' (duration: 142.113727ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.180141Z","caller":"traceutil/trace.go:171","msg":"trace[1562381230] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"141.555653ms","start":"2026-07-31T07:23:30.038551Z","end":"2026-07-31T07:23:30.180107Z","steps":["trace[1562381230] 'process raft request' (duration: 141.258895ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.180029Z","caller":"traceutil/trace.go:171","msg":"trace[1298850024] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"144.13404ms","start":"2026-07-31T07:23:30.035842Z","end":"2026-07-31T07:23:30.179976Z","steps":["trace[1298850024] 'process raft request' (duration: 110.292592ms)","trace[1298850024] 'compare' (duration: 33.398797ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:23:30.209331Z","caller":"traceutil/trace.go:171","msg":"trace[207398051] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"107.12548ms","start":"2026-07-31T07:23:30.102174Z","end":"2026-07-31T07:23:30.2093Z","steps":["trace[207398051] 'process raft request' (duration: 106.871943ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.209369Z","caller":"traceutil/trace.go:171","msg":"trace[509823516] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"111.122425ms","start":"2026-07-31T07:23:30.097528Z","end":"2026-07-31T07:23:30.208651Z","steps":["trace[509823516] 'process raft request' (duration: 110.863958ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.209666Z","caller":"traceutil/trace.go:171","msg":"trace[931008689] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"107.321755ms","start":"2026-07-31T07:23:30.102095Z","end":"2026-07-31T07:23:30.209417Z","steps":["trace[931008689] 'process raft request' (duration: 106.490343ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.209348Z","caller":"traceutil/trace.go:171","msg":"trace[856804772] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"111.504055ms","start":"2026-07-31T07:23:30.097658Z","end":"2026-07-31T07:23:30.209162Z","steps":["trace[856804772] 'process raft request' (duration: 110.89536ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.211024Z","caller":"traceutil/trace.go:171","msg":"trace[496398754] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"105.013905ms","start":"2026-07-31T07:23:30.105979Z","end":"2026-07-31T07:23:30.210993Z","steps":["trace[496398754] 'process raft request' (duration: 104.407518ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.211829Z","caller":"traceutil/trace.go:171","msg":"trace[1029593444] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"101.840582ms","start":"2026-07-31T07:23:30.109741Z","end":"2026-07-31T07:23:30.211581Z","steps":["trace[1029593444] 'process raft request' (duration: 101.04121ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:30.212966Z","caller":"traceutil/trace.go:171","msg":"trace[650606258] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"101.217535ms","start":"2026-07-31T07:23:30.111719Z","end":"2026-07-31T07:23:30.212936Z","steps":["trace[650606258] 'process raft request' (duration: 99.927312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.73841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280040779s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:23:31.73872Z","caller":"traceutil/trace.go:171","msg":"trace[1027641538] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.538739445s","start":"2026-07-31T07:23:30.199962Z","end":"2026-07-31T07:23:31.738702Z","steps":["trace[1027641538] 'process raft request' (duration: 1.538610492s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.738819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.199931Z","time spent":"1.538844257s","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:31.739206Z","caller":"traceutil/trace.go:171","msg":"trace[518186009] transaction","detail":"{read_only:false; number_of_response:1; response_revision:528; }","duration":"1.549325073s","start":"2026-07-31T07:23:30.18986Z","end":"2026-07-31T07:23:31.739185Z","steps":["trace[518186009] 'process raft request' (duration: 1.548644715s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:31.739278Z","caller":"traceutil/trace.go:171","msg":"trace[1779286312] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.577209664s","start":"2026-07-31T07:23:30.162015Z","end":"2026-07-31T07:23:31.739225Z","steps":["trace[1779286312] 'process raft request' (duration: 296.291503ms)","trace[1779286312] 'compare' (duration: 1.279786742s)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:23:31.739321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.189833Z","time spent":"1.549427246s","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10111,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:23:31.739394Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.280557582s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T07:23:31.739426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.161989Z","time spent":"1.577367488s","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:23:31.73983Z","caller":"traceutil/trace.go:171","msg":"trace[1245446053] transaction","detail":"{read_only:false; number_of_response:0; response_revision:529; }","duration":"1.52218597s","start":"2026-07-31T07:23:30.217631Z","end":"2026-07-31T07:23:31.739817Z","steps":["trace[1245446053] 'process raft request' (duration: 1.521874942s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:31.739875Z","caller":"traceutil/trace.go:171","msg":"trace[1551370098] transaction","detail":"{read_only:false; number_of_response:0; response_revision:529; }","duration":"1.52216822s","start":"2026-07-31T07:23:30.217687Z","end":"2026-07-31T07:23:31.739855Z","steps":["trace[1551370098] 'process raft request' (duration: 1.521933314s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.739891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.217612Z","time spent":"1.522252423s","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:23:31.739978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.21766Z","time spent":"1.522262662s","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:23:31.73998Z","caller":"traceutil/trace.go:171","msg":"trace[747462288] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.50844612s","start":"2026-07-31T07:23:30.2315Z","end":"2026-07-31T07:23:31.739946Z","steps":["trace[747462288] 'process raft request' (duration: 1.508169503s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.74014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.231459Z","time spent":"1.508592713s","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:31.740622Z","caller":"traceutil/trace.go:171","msg":"trace[576157490] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.506677885s","start":"2026-07-31T07:23:30.233921Z","end":"2026-07-31T07:23:31.740599Z","steps":["trace[576157490] 'process raft request' (duration: 1.505914044s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.744034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.233901Z","time spent":"1.510087343s","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:31.741152Z","caller":"traceutil/trace.go:171","msg":"trace[286083278] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:547; }","duration":"1.355396606s","start":"2026-07-31T07:23:30.385739Z","end":"2026-07-31T07:23:31.741135Z","steps":["trace[286083278] 'read index received' (duration: 72.729609ms)","trace[286083278] 'applied index is now lower than readState.Index' (duration: 1.282666127s)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:23:31.741302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35555808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:23:31.744148Z","caller":"traceutil/trace.go:171","msg":"trace[1284296012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.358456466s","start":"2026-07-31T07:23:30.38568Z","end":"2026-07-31T07:23:31.744136Z","steps":["trace[1284296012] 'agreement among raft nodes before linearized reading' (duration: 1.355523679s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.744171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.385587Z","time spent":"1.35857824s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T07:23:31.744302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21489541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-31T07:23:31.744323Z","caller":"traceutil/trace.go:171","msg":"trace[1955685191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"1.214952392s","start":"2026-07-31T07:23:30.529365Z","end":"2026-07-31T07:23:31.744317Z","steps":["trace[1955685191] 'agreement among raft nodes before linearized reading' (duration: 1.21490399s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.744342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.529345Z","time spent":"1.214993272s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T07:23:31.741579Z","caller":"traceutil/trace.go:171","msg":"trace[1172004787] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.470480214s","start":"2026-07-31T07:23:30.271082Z","end":"2026-07-31T07:23:31.741562Z","steps":["trace[1172004787] 'process raft request' (duration: 1.468950434s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.74445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.271049Z","time spent":"1.473379711s","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:23:31.744747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04566019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T07:23:31.744766Z","caller":"traceutil/trace.go:171","msg":"trace[482260018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.045721921s","start":"2026-07-31T07:23:30.699038Z","end":"2026-07-31T07:23:31.74476Z","steps":["trace[482260018] 'agreement among raft nodes before linearized reading' (duration: 1.04568053s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.744783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.699018Z","time spent":"1.045761313s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:23:31.744866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085845815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T07:23:31.744885Z","caller":"traceutil/trace.go:171","msg":"trace[2001441820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"1.085909936s","start":"2026-07-31T07:23:30.65897Z","end":"2026-07-31T07:23:31.74488Z","steps":["trace[2001441820] 'agreement among raft nodes before linearized reading' (duration: 1.085878595s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:31.744901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:30.658926Z","time spent":"1.085971308s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T07:23:38.562857Z","caller":"traceutil/trace.go:171","msg":"trace[694309331] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"554.869886ms","start":"2026-07-31T07:23:38.007953Z","end":"2026-07-31T07:23:38.562823Z","steps":["trace[694309331] 'process raft request' (duration: 554.22242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:38.563222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:38.007914Z","time spent":"555.069782ms","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:23:40.055227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437558791s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:23:40.055406Z","caller":"traceutil/trace.go:171","msg":"trace[529074776] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"1.766256364s","start":"2026-07-31T07:23:38.289132Z","end":"2026-07-31T07:23:40.055388Z","steps":["trace[529074776] 'read index received' (duration: 273.201898ms)","trace[529074776] 'applied index is now lower than readState.Index' (duration: 1.493053386s)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:23:40.055491Z","caller":"traceutil/trace.go:171","msg":"trace[1729421434] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.915795567s","start":"2026-07-31T07:23:38.13968Z","end":"2026-07-31T07:23:40.055476Z","steps":["trace[1729421434] 'process raft request' (duration: 477.916767ms)","trace[1729421434] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-node-lease/instance; req_size:517; } (duration: 1.437073288s)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:23:40.055545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:38.139653Z","time spent":"1.915863278s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:40.055608Z","caller":"traceutil/trace.go:171","msg":"trace[1471739230] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.440015195s","start":"2026-07-31T07:23:38.615563Z","end":"2026-07-31T07:23:40.055578Z","steps":["trace[1471739230] 'process raft request' (duration: 1.439783209s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:40.055722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042072486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:23:40.05575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:38.615541Z","time spent":"1.440145489s","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:23:40.055783Z","caller":"traceutil/trace.go:171","msg":"trace[1636240823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"1.042178519s","start":"2026-07-31T07:23:39.013587Z","end":"2026-07-31T07:23:40.055765Z","steps":["trace[1636240823] 'agreement among raft nodes before linearized reading' (duration: 1.042032885s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:40.055825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:39.013559Z","time spent":"1.042255791s","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:23:40.056023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.76689415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jdh9x\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-31T07:23:40.056059Z","caller":"traceutil/trace.go:171","msg":"trace[928069006] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jdh9x; range_end:; response_count:1; response_revision:620; }","duration":"1.766972872s","start":"2026-07-31T07:23:38.289075Z","end":"2026-07-31T07:23:40.056048Z","steps":["trace[928069006] 'agreement among raft nodes before linearized reading' (duration: 1.766819938s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:40.056089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:38.289055Z","time spent":"1.767027124s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-jdh9x\" "} {"level":"warn","ts":"2026-07-31T07:23:40.056062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.69893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-31T07:23:40.056117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.868071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:23:40.056149Z","caller":"traceutil/trace.go:171","msg":"trace[2000966181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"212.838873ms","start":"2026-07-31T07:23:39.84328Z","end":"2026-07-31T07:23:40.056119Z","steps":["trace[2000966181] 'agreement among raft nodes before linearized reading' (duration: 212.605917ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:40.056166Z","caller":"traceutil/trace.go:171","msg":"trace[1611661390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"226.948243ms","start":"2026-07-31T07:23:39.829204Z","end":"2026-07-31T07:23:40.056153Z","steps":["trace[1611661390] 'agreement among raft nodes before linearized reading' (duration: 226.778269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:40.056264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.647593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-31T07:23:40.056278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.149325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T07:23:40.056306Z","caller":"traceutil/trace.go:171","msg":"trace[990305816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"196.197557ms","start":"2026-07-31T07:23:39.860101Z","end":"2026-07-31T07:23:40.056298Z","steps":["trace[990305816] 'agreement among raft nodes before linearized reading' (duration: 196.115255ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:40.056313Z","caller":"traceutil/trace.go:171","msg":"trace[1881460238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"256.732575ms","start":"2026-07-31T07:23:39.799563Z","end":"2026-07-31T07:23:40.056296Z","steps":["trace[1881460238] 'agreement among raft nodes before linearized reading' (duration: 256.603782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:40.564464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505763116,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T07:23:41.049984Z","caller":"traceutil/trace.go:171","msg":"trace[177168675] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"986.878667ms","start":"2026-07-31T07:23:40.06308Z","end":"2026-07-31T07:23:41.049959Z","steps":["trace[177168675] 'process raft request' (duration: 981.172366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:41.050161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:40.063064Z","time spent":"986.975311ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:41.051593Z","caller":"traceutil/trace.go:171","msg":"trace[1789662431] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:645; }","duration":"988.094069ms","start":"2026-07-31T07:23:40.063475Z","end":"2026-07-31T07:23:41.051569Z","steps":["trace[1789662431] 'read index received' (duration: 980.817026ms)","trace[1789662431] 'applied index is now lower than readState.Index' (duration: 7.276063ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:23:41.051828Z","caller":"traceutil/trace.go:171","msg":"trace[99530057] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"988.518091ms","start":"2026-07-31T07:23:40.063298Z","end":"2026-07-31T07:23:41.051816Z","steps":["trace[99530057] 'process raft request' (duration: 988.069359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:41.051953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:40.063289Z","time spent":"988.576832ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:41.052146Z","caller":"traceutil/trace.go:171","msg":"trace[201333982] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"988.52356ms","start":"2026-07-31T07:23:40.063593Z","end":"2026-07-31T07:23:41.052117Z","steps":["trace[201333982] 'process raft request' (duration: 987.897984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:41.05218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.698184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-31T07:23:41.052222Z","caller":"traceutil/trace.go:171","msg":"trace[1861808271] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:625; }","duration":"988.776407ms","start":"2026-07-31T07:23:40.063432Z","end":"2026-07-31T07:23:41.052208Z","steps":["trace[1861808271] 'agreement among raft nodes before linearized reading' (duration: 988.703705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:41.05226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:40.063418Z","time spent":"988.832509ms","remote":"127.0.0.1:58278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-31T07:23:41.052284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:40.063572Z","time spent":"988.641673ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:41.052393Z","caller":"traceutil/trace.go:171","msg":"trace[1529735932] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"988.88016ms","start":"2026-07-31T07:23:40.063504Z","end":"2026-07-31T07:23:41.052384Z","steps":["trace[1529735932] 'process raft request' (duration: 987.951555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:41.052442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:40.063497Z","time spent":"988.916232ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:41.052145Z","caller":"traceutil/trace.go:171","msg":"trace[382680888] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"979.33703ms","start":"2026-07-31T07:23:40.072766Z","end":"2026-07-31T07:23:41.052103Z","steps":["trace[382680888] 'process raft request' (duration: 978.755074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:41.052501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:40.072749Z","time spent":"979.73566ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:23:41.052599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.837634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:23:41.052719Z","caller":"traceutil/trace.go:171","msg":"trace[1598992417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"979.988227ms","start":"2026-07-31T07:23:40.072707Z","end":"2026-07-31T07:23:41.052696Z","steps":["trace[1598992417] 'agreement among raft nodes before linearized reading' (duration: 979.687279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:41.052824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:40.072687Z","time spent":"980.119641ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:23:42.826269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.270692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-31T07:23:42.826378Z","caller":"traceutil/trace.go:171","msg":"trace[1385557682] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:654; }","duration":"288.438298ms","start":"2026-07-31T07:23:42.537917Z","end":"2026-07-31T07:23:42.826355Z","steps":["trace[1385557682] 'range keys from in-memory index tree' (duration: 288.012616ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:42.82798Z","caller":"traceutil/trace.go:171","msg":"trace[1550415103] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"269.931437ms","start":"2026-07-31T07:23:42.558026Z","end":"2026-07-31T07:23:42.827958Z","steps":["trace[1550415103] 'process raft request' (duration: 269.789404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:56.930423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505763439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:23:57.430929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505763439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:23:57.932154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505763439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:23:58.278459Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.871550106s","expected-duration":"1s"} {"level":"info","ts":"2026-07-31T07:23:58.278893Z","caller":"traceutil/trace.go:171","msg":"trace[671982290] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"1.849285147s","start":"2026-07-31T07:23:56.42958Z","end":"2026-07-31T07:23:58.278865Z","steps":["trace[671982290] 'read index received' (duration: 1.84901737s)","trace[671982290] 'applied index is now lower than readState.Index' (duration: 266.457µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:23:58.279059Z","caller":"traceutil/trace.go:171","msg":"trace[622132035] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"1.872266615s","start":"2026-07-31T07:23:56.406762Z","end":"2026-07-31T07:23:58.279029Z","steps":["trace[622132035] 'process raft request' (duration: 1.871918046s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:58.279189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267561744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T07:23:58.279208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:56.406738Z","time spent":"1.872374458s","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:23:58.279278Z","caller":"traceutil/trace.go:171","msg":"trace[278348694] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:786; }","duration":"1.267703898s","start":"2026-07-31T07:23:57.011552Z","end":"2026-07-31T07:23:58.279256Z","steps":["trace[278348694] 'agreement among raft nodes before linearized reading' (duration: 1.267519903s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:58.279293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016822004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-31T07:23:58.279354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:57.011531Z","time spent":"1.26780163s","remote":"127.0.0.1:58432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:23:58.279366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.849725328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-31T07:23:58.27945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.127028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:23:58.279489Z","caller":"traceutil/trace.go:171","msg":"trace[1049458636] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:786; }","duration":"243.164249ms","start":"2026-07-31T07:23:58.036312Z","end":"2026-07-31T07:23:58.279476Z","steps":["trace[1049458636] 'agreement among raft nodes before linearized reading' (duration: 243.116717ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:58.279478Z","caller":"traceutil/trace.go:171","msg":"trace[870680180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"1.849923023s","start":"2026-07-31T07:23:56.429537Z","end":"2026-07-31T07:23:58.27946Z","steps":["trace[870680180] 'agreement among raft nodes before linearized reading' (duration: 1.849523033s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:58.279537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:56.429513Z","time spent":"1.850008806s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T07:23:58.279545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017173773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-31T07:23:58.279521Z","caller":"traceutil/trace.go:171","msg":"trace[2106105088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"1.017111161s","start":"2026-07-31T07:23:57.262388Z","end":"2026-07-31T07:23:58.279499Z","steps":["trace[2106105088] 'agreement among raft nodes before linearized reading' (duration: 1.01670169s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:58.27969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019181657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-31T07:23:58.279741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.294555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:23:58.279614Z","caller":"traceutil/trace.go:171","msg":"trace[29871617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"1.017256746s","start":"2026-07-31T07:23:57.262337Z","end":"2026-07-31T07:23:58.279593Z","steps":["trace[29871617] 'agreement among raft nodes before linearized reading' (duration: 1.016969458s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:58.279819Z","caller":"traceutil/trace.go:171","msg":"trace[1803428810] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:786; }","duration":"472.41409ms","start":"2026-07-31T07:23:57.807388Z","end":"2026-07-31T07:23:58.279802Z","steps":["trace[1803428810] 'agreement among raft nodes before linearized reading' (duration: 472.316427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:58.279841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:57.262329Z","time spent":"1.017498772s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:23:58.279877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:57.807367Z","time spent":"472.494362ms","remote":"127.0.0.1:58220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:23:58.279367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.883056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:23:58.279662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061875067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-31T07:23:58.279983Z","caller":"traceutil/trace.go:171","msg":"trace[468863290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"267.525603ms","start":"2026-07-31T07:23:58.012439Z","end":"2026-07-31T07:23:58.279965Z","steps":["trace[468863290] 'agreement among raft nodes before linearized reading' (duration: 266.829925ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:23:58.280014Z","caller":"traceutil/trace.go:171","msg":"trace[512785099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"1.062275327s","start":"2026-07-31T07:23:57.21773Z","end":"2026-07-31T07:23:58.280005Z","steps":["trace[512785099] 'agreement among raft nodes before linearized reading' (duration: 1.061825996s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:58.28004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:57.217705Z","time spent":"1.062327039s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T07:23:58.279919Z","caller":"traceutil/trace.go:171","msg":"trace[911726605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"1.019360852s","start":"2026-07-31T07:23:57.260422Z","end":"2026-07-31T07:23:58.279783Z","steps":["trace[911726605] 'agreement among raft nodes before linearized reading' (duration: 1.018965991s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:23:58.279838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:57.262374Z","time spent":"1.017348088s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T07:23:58.280166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:23:57.260405Z","time spent":"1.019739431s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T07:24:34.095415Z","caller":"traceutil/trace.go:171","msg":"trace[1108498533] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"748.93414ms","start":"2026-07-31T07:24:33.34645Z","end":"2026-07-31T07:24:34.095384Z","steps":["trace[1108498533] 'process raft request' (duration: 748.739355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:24:34.095601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:33.346421Z","time spent":"749.089835ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:24:35.327186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:34.217805Z","time spent":"1.109376995s","remote":"127.0.0.1:47532","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-31T07:24:35.327295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.54933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-31T07:24:35.327295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.352179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-31T07:24:35.327293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.485683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T07:24:35.327344Z","caller":"traceutil/trace.go:171","msg":"trace[636897207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"852.442142ms","start":"2026-07-31T07:24:34.474888Z","end":"2026-07-31T07:24:35.32733Z","steps":["trace[636897207] 'range keys from in-memory index tree' (duration: 852.174504ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:24:35.327354Z","caller":"traceutil/trace.go:171","msg":"trace[1927918297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"856.629763ms","start":"2026-07-31T07:24:34.470699Z","end":"2026-07-31T07:24:35.327329Z","steps":["trace[1927918297] 'range keys from in-memory index tree' (duration: 856.428517ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:24:35.32736Z","caller":"traceutil/trace.go:171","msg":"trace[1875312303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1004; }","duration":"855.579725ms","start":"2026-07-31T07:24:34.471763Z","end":"2026-07-31T07:24:35.327342Z","steps":["trace[1875312303] 'range keys from in-memory index tree' (duration: 855.35847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:24:35.327379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:34.47069Z","time spent":"856.681964ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T07:24:35.32738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:34.474871Z","time spent":"852.500663ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T07:24:35.327393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:34.471754Z","time spent":"855.631666ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:24:35.327402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.824978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-31T07:24:35.327441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.371755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:24:35.327475Z","caller":"traceutil/trace.go:171","msg":"trace[678783258] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1004; }","duration":"808.434917ms","start":"2026-07-31T07:24:34.519028Z","end":"2026-07-31T07:24:35.327463Z","steps":["trace[678783258] 'count revisions from in-memory index tree' (duration: 808.281183ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:24:35.327471Z","caller":"traceutil/trace.go:171","msg":"trace[1010807353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1004; }","duration":"853.912751ms","start":"2026-07-31T07:24:34.473541Z","end":"2026-07-31T07:24:35.327454Z","steps":["trace[1010807353] 'range keys from in-memory index tree' (duration: 853.700895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:24:35.327498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:34.519016Z","time spent":"808.475298ms","remote":"127.0.0.1:58206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:24:35.327506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.992055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-31T07:24:35.327516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:34.473532Z","time spent":"853.974742ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-31T07:24:35.327536Z","caller":"traceutil/trace.go:171","msg":"trace[2157656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"859.064617ms","start":"2026-07-31T07:24:34.468463Z","end":"2026-07-31T07:24:35.327527Z","steps":["trace[2157656] 'range keys from in-memory index tree' (duration: 858.873502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:24:35.327563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:34.468442Z","time spent":"859.114249ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T07:24:35.327613Z","caller":"traceutil/trace.go:171","msg":"trace[103352417] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"315.396782ms","start":"2026-07-31T07:24:35.012204Z","end":"2026-07-31T07:24:35.327601Z","steps":["trace[103352417] 'read index received' (duration: 306.843855ms)","trace[103352417] 'applied index is now lower than readState.Index' (duration: 8.551697ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:24:35.327691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.486854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:24:35.327704Z","caller":"traceutil/trace.go:171","msg":"trace[175549772] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"753.505872ms","start":"2026-07-31T07:24:34.574186Z","end":"2026-07-31T07:24:35.327692Z","steps":["trace[175549772] 'process raft request' (duration: 744.910774ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:24:35.327713Z","caller":"traceutil/trace.go:171","msg":"trace[955004041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"315.544905ms","start":"2026-07-31T07:24:35.012161Z","end":"2026-07-31T07:24:35.327706Z","steps":["trace[955004041] 'agreement among raft nodes before linearized reading' (duration: 315.476864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:24:35.327739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:35.012141Z","time spent":"315.594357ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:24:35.327815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:24:34.574159Z","time spent":"753.575494ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:02.004511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505764190,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:25:02.446531Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0236369s","expected-duration":"1s"} {"level":"info","ts":"2026-07-31T07:25:02.447005Z","caller":"traceutil/trace.go:171","msg":"trace[1825750241] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"1.024250337s","start":"2026-07-31T07:25:01.422731Z","end":"2026-07-31T07:25:02.446981Z","steps":["trace[1825750241] 'process raft request' (duration: 1.024055902s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:02.447133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:01.422701Z","time spent":"1.0243598s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:03.52406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.390985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:25:03.524172Z","caller":"traceutil/trace.go:171","msg":"trace[512222668] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1132; }","duration":"2.035792214s","start":"2026-07-31T07:25:01.488363Z","end":"2026-07-31T07:25:03.524155Z","steps":["trace[512222668] 'read index received' (duration: 958.460411ms)","trace[512222668] 'applied index is now lower than readState.Index' (duration: 1.077330093s)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:25:03.524254Z","caller":"traceutil/trace.go:171","msg":"trace[1611282708] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.678610406s","start":"2026-07-31T07:25:01.845631Z","end":"2026-07-31T07:25:03.524242Z","steps":["trace[1611282708] 'process raft request' (duration: 1.577933233s)","trace[1611282708] 'compare' (duration: 100.260152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:03.524325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:01.845601Z","time spent":"1.678677828s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:03.524638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.036270537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T07:25:03.524668Z","caller":"traceutil/trace.go:171","msg":"trace[2104336691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"2.036322827s","start":"2026-07-31T07:25:01.488335Z","end":"2026-07-31T07:25:03.524657Z","steps":["trace[2104336691] 'agreement among raft nodes before linearized reading' (duration: 2.036187114s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:03.524697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:01.488322Z","time spent":"2.036368189s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T07:25:03.524753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.030592548s","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-07-31T07:25:03.524802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029846718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-31T07:25:03.524833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.193722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-31T07:25:03.524862Z","caller":"traceutil/trace.go:171","msg":"trace[1886278875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"2.029939921s","start":"2026-07-31T07:25:01.494905Z","end":"2026-07-31T07:25:03.524845Z","steps":["trace[1886278875] 'agreement among raft nodes before linearized reading' (duration: 2.029794337s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:03.52488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029021948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-31T07:25:03.524893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:01.494889Z","time spent":"2.029997832s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T07:25:03.524897Z","caller":"traceutil/trace.go:171","msg":"trace[186146917] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:1090; }","duration":"983.321615ms","start":"2026-07-31T07:25:02.541557Z","end":"2026-07-31T07:25:03.524878Z","steps":["trace[186146917] 'agreement among raft nodes before linearized reading' (duration: 983.12457ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:03.524926Z","caller":"traceutil/trace.go:171","msg":"trace[600076681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"2.02908737s","start":"2026-07-31T07:25:01.495826Z","end":"2026-07-31T07:25:03.524913Z","steps":["trace[600076681] 'agreement among raft nodes before linearized reading' (duration: 2.028976977s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:03.524935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:02.541537Z","time spent":"983.388997ms","remote":"127.0.0.1:58080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.200\" "} {"level":"warn","ts":"2026-07-31T07:25:03.524959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:01.495786Z","time spent":"2.029164712s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-31T07:25:03.52497Z","caller":"traceutil/trace.go:171","msg":"trace[2077229568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"2.03067107s","start":"2026-07-31T07:25:01.494129Z","end":"2026-07-31T07:25:03.5248Z","steps":["trace[2077229568] 'agreement among raft nodes before linearized reading' (duration: 2.030502166s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:03.525026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.030750182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-31T07:25:03.525066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.175434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:03.525098Z","caller":"traceutil/trace.go:171","msg":"trace[470924579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"2.030863266s","start":"2026-07-31T07:25:01.494217Z","end":"2026-07-31T07:25:03.52508Z","steps":["trace[470924579] 'agreement among raft nodes before linearized reading' (duration: 2.030686741s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:03.525132Z","caller":"traceutil/trace.go:171","msg":"trace[1502675373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"511.290777ms","start":"2026-07-31T07:25:03.013828Z","end":"2026-07-31T07:25:03.525119Z","steps":["trace[1502675373] 'agreement among raft nodes before linearized reading' (duration: 511.181903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:03.525146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:01.494192Z","time spent":"2.030942528s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T07:25:03.525041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:01.494122Z","time spent":"2.030903796s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:25:03.525198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:03.013813Z","time spent":"511.349349ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T07:25:03.676007Z","caller":"traceutil/trace.go:171","msg":"trace[1281722611] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"121.782019ms","start":"2026-07-31T07:25:03.554181Z","end":"2026-07-31T07:25:03.675963Z","steps":["trace[1281722611] 'process raft request' (duration: 78.415724ms)","trace[1281722611] 'compare' (duration: 43.237512ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:05.293191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.314124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:05.293266Z","caller":"traceutil/trace.go:171","msg":"trace[665131133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"280.437757ms","start":"2026-07-31T07:25:05.01281Z","end":"2026-07-31T07:25:05.293248Z","steps":["trace[665131133] 'range keys from in-memory index tree' (duration: 280.223001ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:06.489756Z","caller":"traceutil/trace.go:171","msg":"trace[1171780199] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"890.504816ms","start":"2026-07-31T07:25:05.599231Z","end":"2026-07-31T07:25:06.489736Z","steps":["trace[1171780199] 'process raft request' (duration: 890.372403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:06.489974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:05.599167Z","time spent":"890.722182ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:06.754986Z","caller":"traceutil/trace.go:171","msg":"trace[1665264809] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"1.154328593s","start":"2026-07-31T07:25:05.600599Z","end":"2026-07-31T07:25:06.754927Z","steps":["trace[1665264809] 'process raft request' (duration: 1.154089096s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:06.755144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:05.600587Z","time spent":"1.154473117s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:06.755303Z","caller":"traceutil/trace.go:171","msg":"trace[1448822418] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"1.154694953s","start":"2026-07-31T07:25:05.600597Z","end":"2026-07-31T07:25:06.755292Z","steps":["trace[1448822418] 'process raft request' (duration: 1.154275501s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:06.755363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:05.600585Z","time spent":"1.154746974s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:06.755358Z","caller":"traceutil/trace.go:171","msg":"trace[332118174] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1143; }","duration":"742.056835ms","start":"2026-07-31T07:25:06.013278Z","end":"2026-07-31T07:25:06.755335Z","steps":["trace[332118174] 'read index received' (duration: 476.32417ms)","trace[332118174] 'applied index is now lower than readState.Index' (duration: 265.731535ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:25:06.755507Z","caller":"traceutil/trace.go:171","msg":"trace[1699642182] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"1.154308212s","start":"2026-07-31T07:25:05.601186Z","end":"2026-07-31T07:25:06.755495Z","steps":["trace[1699642182] 'process raft request' (duration: 1.154065216s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:06.755532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.239269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:06.755593Z","caller":"traceutil/trace.go:171","msg":"trace[1282878573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"742.310681ms","start":"2026-07-31T07:25:06.013265Z","end":"2026-07-31T07:25:06.755576Z","steps":["trace[1282878573] 'agreement among raft nodes before linearized reading' (duration: 742.186268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:06.755652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:06.013213Z","time spent":"742.425414ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:25:06.755669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:05.601178Z","time spent":"1.154384394s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:06.755727Z","caller":"traceutil/trace.go:171","msg":"trace[682974792] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"1.1549864s","start":"2026-07-31T07:25:05.600712Z","end":"2026-07-31T07:25:06.755698Z","steps":["trace[682974792] 'process raft request' (duration: 1.154417716s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:06.755805Z","caller":"traceutil/trace.go:171","msg":"trace[598204436] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"271.366932ms","start":"2026-07-31T07:25:06.484396Z","end":"2026-07-31T07:25:06.755762Z","steps":["trace[598204436] 'process raft request' (duration: 270.89974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:06.755839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:05.600682Z","time spent":"1.155102853s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:08.681552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.056806ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T07:25:08.681638Z","caller":"traceutil/trace.go:171","msg":"trace[783328345] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"661.415222ms","start":"2026-07-31T07:25:08.020206Z","end":"2026-07-31T07:25:08.681621Z","steps":["trace[783328345] 'read index received' (duration: 265.291914ms)","trace[783328345] 'applied index is now lower than readState.Index' (duration: 396.121708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:08.681978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.771471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:08.682008Z","caller":"traceutil/trace.go:171","msg":"trace[656211497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"661.843363ms","start":"2026-07-31T07:25:08.020157Z","end":"2026-07-31T07:25:08.682Z","steps":["trace[656211497] 'agreement among raft nodes before linearized reading' (duration: 661.783432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:08.682031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:08.020136Z","time spent":"661.889965ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:25:08.682148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.090006ms","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-07-31T07:25:08.682179Z","caller":"traceutil/trace.go:171","msg":"trace[1329492446] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1104; }","duration":"653.151938ms","start":"2026-07-31T07:25:08.02902Z","end":"2026-07-31T07:25:08.682172Z","steps":["trace[1329492446] 'agreement among raft nodes before linearized reading' (duration: 653.097776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:08.682199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:08.029007Z","time spent":"653.187619ms","remote":"127.0.0.1:58546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:25:08.682304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.500601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T07:25:08.682333Z","caller":"traceutil/trace.go:171","msg":"trace[598065467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"188.567793ms","start":"2026-07-31T07:25:08.493758Z","end":"2026-07-31T07:25:08.682326Z","steps":["trace[598065467] 'agreement among raft nodes before linearized reading' (duration: 188.45749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:08.682472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.806835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:08.682774Z","caller":"traceutil/trace.go:171","msg":"trace[513238556] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1104; }","duration":"101.35064ms","start":"2026-07-31T07:25:08.581403Z","end":"2026-07-31T07:25:08.682754Z","steps":["trace[513238556] 'agreement among raft nodes before linearized reading' (duration: 100.749194ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:08.933411Z","caller":"traceutil/trace.go:171","msg":"trace[118966046] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"173.42276ms","start":"2026-07-31T07:25:08.759963Z","end":"2026-07-31T07:25:08.933386Z","steps":["trace[118966046] 'read index received' (duration: 173.276486ms)","trace[118966046] 'applied index is now lower than readState.Index' (duration: 145.034µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:25:08.933516Z","caller":"traceutil/trace.go:171","msg":"trace[1225691300] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"247.541043ms","start":"2026-07-31T07:25:08.685965Z","end":"2026-07-31T07:25:08.933506Z","steps":["trace[1225691300] 'process raft request' (duration: 247.245156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:08.933933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.944993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-31T07:25:08.934101Z","caller":"traceutil/trace.go:171","msg":"trace[951969587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"174.160269ms","start":"2026-07-31T07:25:08.759916Z","end":"2026-07-31T07:25:08.934076Z","steps":["trace[951969587] 'agreement among raft nodes before linearized reading' (duration: 173.709317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:09.955865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.740766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:25:09.956206Z","caller":"traceutil/trace.go:171","msg":"trace[812819790] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"1.017795159s","start":"2026-07-31T07:25:08.9384Z","end":"2026-07-31T07:25:09.956196Z","steps":["trace[812819790] 'process raft request' (duration: 1.017743168s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:09.956159Z","caller":"traceutil/trace.go:171","msg":"trace[1878553640] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"1.19512896s","start":"2026-07-31T07:25:08.761009Z","end":"2026-07-31T07:25:09.956138Z","steps":["trace[1878553640] 'process raft request' (duration: 426.039385ms)","trace[1878553640] 'compare' (duration: 768.628534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:09.956291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:08.938376Z","time spent":"1.017859171s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:09.956304Z","caller":"traceutil/trace.go:171","msg":"trace[1999441803] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"1.02281138s","start":"2026-07-31T07:25:08.933473Z","end":"2026-07-31T07:25:09.956285Z","steps":["trace[1999441803] 'read index received' (duration: 253.578991ms)","trace[1999441803] 'applied index is now lower than readState.Index' (duration: 769.230139ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:09.956418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195103259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-31T07:25:09.956444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195432758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T07:25:09.956454Z","caller":"traceutil/trace.go:171","msg":"trace[10271993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"1.195157701s","start":"2026-07-31T07:25:08.761285Z","end":"2026-07-31T07:25:09.956442Z","steps":["trace[10271993] 'agreement among raft nodes before linearized reading' (duration: 1.195034238s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:09.956474Z","caller":"traceutil/trace.go:171","msg":"trace[474477172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1107; }","duration":"1.19551259s","start":"2026-07-31T07:25:08.760953Z","end":"2026-07-31T07:25:09.956465Z","steps":["trace[474477172] 'agreement among raft nodes before linearized reading' (duration: 1.195422757s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:09.956482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:08.761274Z","time spent":"1.195201732s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T07:25:09.9565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:08.760928Z","time spent":"1.195564802s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:25:09.956285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:08.760988Z","time spent":"1.195261944s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:09.956652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.260355ms","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-07-31T07:25:09.956723Z","caller":"traceutil/trace.go:171","msg":"trace[240556481] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1107; }","duration":"689.387708ms","start":"2026-07-31T07:25:09.267317Z","end":"2026-07-31T07:25:09.956705Z","steps":["trace[240556481] 'agreement among raft nodes before linearized reading' (duration: 689.254125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:09.956764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:09.267297Z","time spent":"689.457249ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:25:09.95765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196766524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:25:09.957693Z","caller":"traceutil/trace.go:171","msg":"trace[2139216343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"1.196842155s","start":"2026-07-31T07:25:08.760841Z","end":"2026-07-31T07:25:09.957683Z","steps":["trace[2139216343] 'agreement among raft nodes before linearized reading' (duration: 1.196084235s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:09.95773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:08.760825Z","time spent":"1.196897617s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T07:25:10.716462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.129446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:25:10.717534Z","caller":"traceutil/trace.go:171","msg":"trace[2047835879] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"756.792627ms","start":"2026-07-31T07:25:09.960711Z","end":"2026-07-31T07:25:10.717504Z","steps":["trace[2047835879] 'process raft request' (duration: 484.515372ms)","trace[2047835879] 'compare' (duration: 270.742765ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:10.717876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:09.960695Z","time spent":"756.966291ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:11.137651Z","caller":"traceutil/trace.go:171","msg":"trace[1578485863] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1153; }","duration":"1.124129298s","start":"2026-07-31T07:25:10.013509Z","end":"2026-07-31T07:25:11.137639Z","steps":["trace[1578485863] 'read index received' (duration: 431.747902ms)","trace[1578485863] 'applied index is now lower than readState.Index' (duration: 692.380546ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:25:11.137655Z","caller":"traceutil/trace.go:171","msg":"trace[777560193] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"1.176342864s","start":"2026-07-31T07:25:09.961283Z","end":"2026-07-31T07:25:11.137626Z","steps":["trace[777560193] 'process raft request' (duration: 1.176010095s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:11.138164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.154086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:25:11.137707Z","caller":"traceutil/trace.go:171","msg":"trace[1572386266] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"1.175465041s","start":"2026-07-31T07:25:09.962215Z","end":"2026-07-31T07:25:11.13768Z","steps":["trace[1572386266] 'process raft request' (duration: 1.175367629s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:11.138208Z","caller":"traceutil/trace.go:171","msg":"trace[954990199] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1110; }","duration":"730.252218ms","start":"2026-07-31T07:25:10.407947Z","end":"2026-07-31T07:25:11.138199Z","steps":["trace[954990199] 'agreement among raft nodes before linearized reading' (duration: 730.161966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:11.138218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:09.961272Z","time spent":"1.176827926s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:11.13824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:10.407924Z","time spent":"730.30717ms","remote":"127.0.0.1:58276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:25:11.138267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:09.9622Z","time spent":"1.176021816s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:11.13778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124246762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:11.13839Z","caller":"traceutil/trace.go:171","msg":"trace[1739301995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1110; }","duration":"1.124921059s","start":"2026-07-31T07:25:10.013459Z","end":"2026-07-31T07:25:11.13838Z","steps":["trace[1739301995] 'agreement among raft nodes before linearized reading' (duration: 1.124259612s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:11.138419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:10.013416Z","time spent":"1.124996921s","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:25:11.138438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.013808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T07:25:11.138862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.173999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T07:25:11.138894Z","caller":"traceutil/trace.go:171","msg":"trace[1082092459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"201.244131ms","start":"2026-07-31T07:25:10.937641Z","end":"2026-07-31T07:25:11.138885Z","steps":["trace[1082092459] 'agreement among raft nodes before linearized reading' (duration: 200.516342ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:11.138772Z","caller":"traceutil/trace.go:171","msg":"trace[1561134041] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1110; }","duration":"351.244194ms","start":"2026-07-31T07:25:10.787314Z","end":"2026-07-31T07:25:11.138558Z","steps":["trace[1561134041] 'agreement among raft nodes before linearized reading' (duration: 350.977117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:11.139343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:10.787289Z","time spent":"351.969824ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:25:11.295506Z","caller":"traceutil/trace.go:171","msg":"trace[94029755] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"148.79044ms","start":"2026-07-31T07:25:11.146688Z","end":"2026-07-31T07:25:11.295478Z","steps":["trace[94029755] 'read index received' (duration: 137.173139ms)","trace[94029755] 'applied index is now lower than readState.Index' (duration: 11.615141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:11.295681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.966036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:11.295721Z","caller":"traceutil/trace.go:171","msg":"trace[683369701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"149.053387ms","start":"2026-07-31T07:25:11.146654Z","end":"2026-07-31T07:25:11.295708Z","steps":["trace[683369701] 'agreement among raft nodes before linearized reading' (duration: 148.946344ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:11.295698Z","caller":"traceutil/trace.go:171","msg":"trace[1069930974] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"151.128332ms","start":"2026-07-31T07:25:11.144539Z","end":"2026-07-31T07:25:11.295668Z","steps":["trace[1069930974] 'process raft request' (duration: 139.360847ms)","trace[1069930974] 'compare' (duration: 11.298633ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:25.196361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.797388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:25.196442Z","caller":"traceutil/trace.go:171","msg":"trace[1061410148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1206; }","duration":"183.923141ms","start":"2026-07-31T07:25:25.012493Z","end":"2026-07-31T07:25:25.196416Z","steps":["trace[1061410148] 'range keys from in-memory index tree' (duration: 183.721427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:27.514061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505764473,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:25:28.015383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505764473,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:25:28.51611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505764473,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:25:28.799916Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.947303137s","expected-duration":"1s"} {"level":"info","ts":"2026-07-31T07:25:28.800292Z","caller":"traceutil/trace.go:171","msg":"trace[437449627] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.94777205s","start":"2026-07-31T07:25:26.852496Z","end":"2026-07-31T07:25:28.800268Z","steps":["trace[437449627] 'process raft request' (duration: 1.947602245s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:28.800422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:26.852479Z","time spent":"1.947878432s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:28.803307Z","caller":"traceutil/trace.go:171","msg":"trace[1063923513] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1264; }","duration":"1.789455893s","start":"2026-07-31T07:25:27.01383Z","end":"2026-07-31T07:25:28.803285Z","steps":["trace[1063923513] 'read index received' (duration: 1.787086571s)","trace[1063923513] 'applied index is now lower than readState.Index' (duration: 2.368252ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:25:28.8034Z","caller":"traceutil/trace.go:171","msg":"trace[1689407914] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"785.592753ms","start":"2026-07-31T07:25:28.017796Z","end":"2026-07-31T07:25:28.803389Z","steps":["trace[1689407914] 'process raft request' (duration: 785.368918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:28.803504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:28.017779Z","time spent":"785.647425ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:28.803695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58666409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-31T07:25:28.803779Z","caller":"traceutil/trace.go:171","msg":"trace[80647327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"1.586779793s","start":"2026-07-31T07:25:27.21696Z","end":"2026-07-31T07:25:28.803739Z","steps":["trace[80647327] 'agreement among raft nodes before linearized reading' (duration: 1.586612349s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:28.803818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:27.216936Z","time spent":"1.586874165s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:25:28.803906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395748896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:28.803946Z","caller":"traceutil/trace.go:171","msg":"trace[131972773] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1214; }","duration":"1.395822228s","start":"2026-07-31T07:25:27.408113Z","end":"2026-07-31T07:25:28.803935Z","steps":["trace[131972773] 'agreement among raft nodes before linearized reading' (duration: 1.395754037s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:28.803977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:27.408098Z","time spent":"1.395872199s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:25:28.804027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435876527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T07:25:28.80406Z","caller":"traceutil/trace.go:171","msg":"trace[1354317468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1214; }","duration":"1.435942079s","start":"2026-07-31T07:25:27.368109Z","end":"2026-07-31T07:25:28.804051Z","steps":["trace[1354317468] 'agreement among raft nodes before linearized reading' (duration: 1.435858017s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:28.804087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:27.36809Z","time spent":"1.43599129s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T07:25:28.804137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790314814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:28.80417Z","caller":"traceutil/trace.go:171","msg":"trace[618322844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"1.790378996s","start":"2026-07-31T07:25:27.01378Z","end":"2026-07-31T07:25:28.804159Z","steps":["trace[618322844] 'agreement among raft nodes before linearized reading' (duration: 1.790332805s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:28.804203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:27.013759Z","time spent":"1.790435748s","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:25:28.804247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587185453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T07:25:28.804275Z","caller":"traceutil/trace.go:171","msg":"trace[659266703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1214; }","duration":"1.587238855s","start":"2026-07-31T07:25:27.217028Z","end":"2026-07-31T07:25:28.804266Z","steps":["trace[659266703] 'agreement among raft nodes before linearized reading' (duration: 1.587161693s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:28.8043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:27.217006Z","time spent":"1.587288416s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T07:25:28.804435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.887384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-31T07:25:28.804465Z","caller":"traceutil/trace.go:171","msg":"trace[1521036757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1214; }","duration":"772.975537ms","start":"2026-07-31T07:25:28.031481Z","end":"2026-07-31T07:25:28.804456Z","steps":["trace[1521036757] 'agreement among raft nodes before linearized reading' (duration: 772.863514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:28.804496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:28.031433Z","time spent":"773.056798ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T07:25:43.979138Z","caller":"traceutil/trace.go:171","msg":"trace[796640810] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"186.209871ms","start":"2026-07-31T07:25:43.792899Z","end":"2026-07-31T07:25:43.979109Z","steps":["trace[796640810] 'process raft request' (duration: 186.070917ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:45.061896Z","caller":"traceutil/trace.go:171","msg":"trace[1685979978] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"192.553657ms","start":"2026-07-31T07:25:44.869325Z","end":"2026-07-31T07:25:45.061878Z","steps":["trace[1685979978] 'read index received' (duration: 192.399783ms)","trace[1685979978] 'applied index is now lower than readState.Index' (duration: 153.104µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:25:45.062099Z","caller":"traceutil/trace.go:171","msg":"trace[254753125] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"206.338584ms","start":"2026-07-31T07:25:44.85572Z","end":"2026-07-31T07:25:45.062058Z","steps":["trace[254753125] 'process raft request' (duration: 206.056847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:45.062292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.503103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T07:25:45.062318Z","caller":"traceutil/trace.go:171","msg":"trace[1814791623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"158.558905ms","start":"2026-07-31T07:25:44.903752Z","end":"2026-07-31T07:25:45.062311Z","steps":["trace[1814791623] 'agreement among raft nodes before linearized reading' (duration: 158.452392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:45.062349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.639166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-31T07:25:45.062377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.209236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T07:25:45.062429Z","caller":"traceutil/trace.go:171","msg":"trace[1593959719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"158.714138ms","start":"2026-07-31T07:25:44.903694Z","end":"2026-07-31T07:25:45.062408Z","steps":["trace[1593959719] 'agreement among raft nodes before linearized reading' (duration: 158.527933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:45.062471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.684706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-31T07:25:45.062467Z","caller":"traceutil/trace.go:171","msg":"trace[1310845987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"158.332019ms","start":"2026-07-31T07:25:44.904112Z","end":"2026-07-31T07:25:45.062444Z","steps":["trace[1310845987] 'agreement among raft nodes before linearized reading' (duration: 158.057962ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:45.06257Z","caller":"traceutil/trace.go:171","msg":"trace[1417453643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1266; }","duration":"163.766988ms","start":"2026-07-31T07:25:44.898724Z","end":"2026-07-31T07:25:45.062491Z","steps":["trace[1417453643] 'agreement among raft nodes before linearized reading' (duration: 163.42951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:45.062199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.840244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T07:25:45.064625Z","caller":"traceutil/trace.go:171","msg":"trace[1357700846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"195.326829ms","start":"2026-07-31T07:25:44.869288Z","end":"2026-07-31T07:25:45.064615Z","steps":["trace[1357700846] 'agreement among raft nodes before linearized reading' (duration: 192.707511ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:46.223629Z","caller":"traceutil/trace.go:171","msg":"trace[1273316122] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"341.606844ms","start":"2026-07-31T07:25:45.881969Z","end":"2026-07-31T07:25:46.223576Z","steps":["trace[1273316122] 'process raft request' (duration: 341.292096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:46.223767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:45.881945Z","time spent":"341.753907ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:46.223936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.752381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:46.22399Z","caller":"traceutil/trace.go:171","msg":"trace[633839507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"207.858894ms","start":"2026-07-31T07:25:46.016119Z","end":"2026-07-31T07:25:46.223978Z","steps":["trace[633839507] 'agreement among raft nodes before linearized reading' (duration: 207.771312ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:46.223848Z","caller":"traceutil/trace.go:171","msg":"trace[1175206134] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"207.33919ms","start":"2026-07-31T07:25:46.016167Z","end":"2026-07-31T07:25:46.223506Z","steps":["trace[1175206134] 'read index received' (duration: 207.021352ms)","trace[1175206134] 'applied index is now lower than readState.Index' (duration: 316.698µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:47.359544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.599181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:25:47.359638Z","caller":"traceutil/trace.go:171","msg":"trace[245921380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1274; }","duration":"196.729184ms","start":"2026-07-31T07:25:47.16289Z","end":"2026-07-31T07:25:47.359619Z","steps":["trace[245921380] 'range keys from in-memory index tree' (duration: 196.456717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:47.359765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.398016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T07:25:47.359826Z","caller":"traceutil/trace.go:171","msg":"trace[1519079049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"196.474978ms","start":"2026-07-31T07:25:47.163334Z","end":"2026-07-31T07:25:47.359809Z","steps":["trace[1519079049] 'range keys from in-memory index tree' (duration: 196.304554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:47.35987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.069383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-31T07:25:47.359881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.522309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-31T07:25:47.359991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.142475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-31T07:25:47.360025Z","caller":"traceutil/trace.go:171","msg":"trace[324361846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"197.201016ms","start":"2026-07-31T07:25:47.162814Z","end":"2026-07-31T07:25:47.360015Z","steps":["trace[324361846] 'range keys from in-memory index tree' (duration: 197.029182ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:47.360009Z","caller":"traceutil/trace.go:171","msg":"trace[1778011260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"196.711134ms","start":"2026-07-31T07:25:47.163272Z","end":"2026-07-31T07:25:47.359983Z","steps":["trace[1778011260] 'range keys from in-memory index tree' (duration: 196.253332ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:47.359937Z","caller":"traceutil/trace.go:171","msg":"trace[2135872188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"197.187856ms","start":"2026-07-31T07:25:47.162732Z","end":"2026-07-31T07:25:47.35992Z","steps":["trace[2135872188] 'range keys from in-memory index tree' (duration: 196.876258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:48.007343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505764631,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T07:25:48.25686Z","caller":"traceutil/trace.go:171","msg":"trace[2105934586] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"892.118548ms","start":"2026-07-31T07:25:47.36471Z","end":"2026-07-31T07:25:48.256829Z","steps":["trace[2105934586] 'process raft request' (duration: 891.831851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:48.257077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:47.364676Z","time spent":"892.279723ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:48.257114Z","caller":"traceutil/trace.go:171","msg":"trace[67048650] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"892.063717ms","start":"2026-07-31T07:25:47.365022Z","end":"2026-07-31T07:25:48.257086Z","steps":["trace[67048650] 'process raft request' (duration: 891.740759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:48.257225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:47.365003Z","time spent":"892.160379ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:25:48.257381Z","caller":"traceutil/trace.go:171","msg":"trace[1890593769] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"892.067947ms","start":"2026-07-31T07:25:47.365303Z","end":"2026-07-31T07:25:48.257371Z","steps":["trace[1890593769] 'process raft request' (duration: 891.712378ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:48.257409Z","caller":"traceutil/trace.go:171","msg":"trace[1855878547] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"892.075607ms","start":"2026-07-31T07:25:47.365326Z","end":"2026-07-31T07:25:48.257402Z","steps":["trace[1855878547] 'process raft request' (duration: 892.007725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:48.257435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:47.365292Z","time spent":"892.118628ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:48.257449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:47.365303Z","time spent":"892.123839ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:25:48.89681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.534744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:25:48.897015Z","caller":"traceutil/trace.go:171","msg":"trace[853601705] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"1.346905559s","start":"2026-07-31T07:25:47.550072Z","end":"2026-07-31T07:25:48.896978Z","steps":["trace[853601705] 'process raft request' (duration: 1.003140819s)","trace[853601705] 'compare' (duration: 343.380301ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:48.897138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:47.550047Z","time spent":"1.347026323s","remote":"127.0.0.1:58138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:25:49.222882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.969428ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T07:25:49.223227Z","caller":"traceutil/trace.go:171","msg":"trace[375523147] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1331; }","duration":"1.716659844s","start":"2026-07-31T07:25:47.506545Z","end":"2026-07-31T07:25:49.223205Z","steps":["trace[375523147] 'read index received' (duration: 750.280668ms)","trace[375523147] 'applied index is now lower than readState.Index' (duration: 966.376966ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:25:49.223248Z","caller":"traceutil/trace.go:171","msg":"trace[147742380] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"322.876757ms","start":"2026-07-31T07:25:48.900348Z","end":"2026-07-31T07:25:49.223224Z","steps":["trace[147742380] 'process raft request' (duration: 322.684063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:49.223359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:48.90033Z","time spent":"322.96836ms","remote":"127.0.0.1:58138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:25:49.223394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716834098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:25:49.223417Z","caller":"traceutil/trace.go:171","msg":"trace[1407383398] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1281; }","duration":"1.71690429s","start":"2026-07-31T07:25:47.506505Z","end":"2026-07-31T07:25:49.22341Z","steps":["trace[1407383398] 'agreement among raft nodes before linearized reading' (duration: 1.716788177s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:49.22344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:47.506483Z","time spent":"1.716948932s","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:25:49.332658Z","caller":"traceutil/trace.go:171","msg":"trace[1116941641] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"109.364997ms","start":"2026-07-31T07:25:49.223271Z","end":"2026-07-31T07:25:49.332637Z","steps":["trace[1116941641] 'read index received' (duration: 109.310916ms)","trace[1116941641] 'applied index is now lower than readState.Index' (duration: 52.811µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:25:49.332837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669186671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-31T07:25:49.332864Z","caller":"traceutil/trace.go:171","msg":"trace[1747127958] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1282; }","duration":"1.669262734s","start":"2026-07-31T07:25:47.663594Z","end":"2026-07-31T07:25:49.332857Z","steps":["trace[1747127958] 'agreement among raft nodes before linearized reading' (duration: 1.66911173s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:49.332888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:47.66356Z","time spent":"1.669322406s","remote":"127.0.0.1:58248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-31T07:25:49.33321Z","caller":"traceutil/trace.go:171","msg":"trace[1107979159] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"215.522312ms","start":"2026-07-31T07:25:49.11768Z","end":"2026-07-31T07:25:49.333202Z","steps":["trace[1107979159] 'process raft request' (duration: 214.818264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:49.333328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322398565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:25:49.333354Z","caller":"traceutil/trace.go:171","msg":"trace[269463283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"1.322454487s","start":"2026-07-31T07:25:48.010894Z","end":"2026-07-31T07:25:49.333348Z","steps":["trace[269463283] 'agreement among raft nodes before linearized reading' (duration: 1.322416156s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:25:49.33337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:48.010871Z","time spent":"1.322495997s","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T07:25:49.335127Z","caller":"traceutil/trace.go:171","msg":"trace[2121650697] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"107.582002ms","start":"2026-07-31T07:25:49.227526Z","end":"2026-07-31T07:25:49.335108Z","steps":["trace[2121650697] 'process raft request' (duration: 107.503ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:53.682035Z","caller":"traceutil/trace.go:171","msg":"trace[528050205] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"126.19827ms","start":"2026-07-31T07:25:53.55581Z","end":"2026-07-31T07:25:53.682008Z","steps":["trace[528050205] 'process raft request' (duration: 126.068296ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:53.848245Z","caller":"traceutil/trace.go:171","msg":"trace[1165752462] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"146.85207ms","start":"2026-07-31T07:25:53.701369Z","end":"2026-07-31T07:25:53.848221Z","steps":["trace[1165752462] 'process raft request' (duration: 146.041449ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:53.848242Z","caller":"traceutil/trace.go:171","msg":"trace[1909189388] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"121.749826ms","start":"2026-07-31T07:25:53.726465Z","end":"2026-07-31T07:25:53.848215Z","steps":["trace[1909189388] 'process raft request' (duration: 121.690574ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:25:59.475502Z","caller":"traceutil/trace.go:171","msg":"trace[45182828] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"257.133542ms","start":"2026-07-31T07:25:59.218344Z","end":"2026-07-31T07:25:59.475477Z","steps":["trace[45182828] 'process raft request' (duration: 256.956727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:00.271137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.368599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:26:00.271335Z","caller":"traceutil/trace.go:171","msg":"trace[1920576418] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1431; }","duration":"812.522031ms","start":"2026-07-31T07:25:59.458722Z","end":"2026-07-31T07:26:00.271244Z","steps":["trace[1920576418] 'read index received' (duration: 16.585426ms)","trace[1920576418] 'applied index is now lower than readState.Index' (duration: 795.934585ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:26:00.271408Z","caller":"traceutil/trace.go:171","msg":"trace[177372758] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"903.983929ms","start":"2026-07-31T07:25:59.3674Z","end":"2026-07-31T07:26:00.271384Z","steps":["trace[177372758] 'process raft request' (duration: 570.267421ms)","trace[177372758] 'compare' (duration: 333.103773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:26:00.271483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.446338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:26:00.271522Z","caller":"traceutil/trace.go:171","msg":"trace[116573726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"255.51017ms","start":"2026-07-31T07:26:00.015999Z","end":"2026-07-31T07:26:00.271509Z","steps":["trace[116573726] 'agreement among raft nodes before linearized reading' (duration: 255.436558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:00.271532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:59.36737Z","time spent":"904.094142ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:26:00.271524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.782148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-31T07:26:00.271575Z","caller":"traceutil/trace.go:171","msg":"trace[919353486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1375; }","duration":"812.87406ms","start":"2026-07-31T07:25:59.458689Z","end":"2026-07-31T07:26:00.271563Z","steps":["trace[919353486] 'agreement among raft nodes before linearized reading' (duration: 812.707165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:00.271603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:25:59.45867Z","time spent":"812.927781ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T07:26:00.86372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151466505764822,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:26:01.349029Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.07460334s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T07:26:01.636936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.544611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:26:01.637103Z","caller":"traceutil/trace.go:171","msg":"trace[1135544170] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"1.362765178s","start":"2026-07-31T07:26:00.274312Z","end":"2026-07-31T07:26:01.637077Z","steps":["trace[1135544170] 'process raft request' (duration: 1.074954389s)","trace[1135544170] 'compare' (duration: 287.334456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:26:01.637175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:00.274296Z","time spent":"1.36284698s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:26:01.640837Z","caller":"traceutil/trace.go:171","msg":"trace[945356059] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1433; }","duration":"1.277510029s","start":"2026-07-31T07:26:00.363307Z","end":"2026-07-31T07:26:01.640817Z","steps":["trace[945356059] 'read index received' (duration: 986.070367ms)","trace[945356059] 'applied index is now lower than readState.Index' (duration: 291.438612ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:26:01.64102Z","caller":"traceutil/trace.go:171","msg":"trace[1217026010] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"916.616083ms","start":"2026-07-31T07:26:00.724362Z","end":"2026-07-31T07:26:01.640978Z","steps":["trace[1217026010] 'process raft request' (duration: 916.363107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:01.641148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277834767s","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-07-31T07:26:01.641189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.662113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:26:01.64121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:00.724325Z","time spent":"916.739496ms","remote":"127.0.0.1:58248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6758,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:26:01.641224Z","caller":"traceutil/trace.go:171","msg":"trace[860690640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.27795697s","start":"2026-07-31T07:26:00.363254Z","end":"2026-07-31T07:26:01.641211Z","steps":["trace[860690640] 'agreement among raft nodes before linearized reading' (duration: 1.277714814s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:01.641308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:00.363236Z","time spent":"1.278060673s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:26:01.641363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172112672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-31T07:26:01.641365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271777512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T07:26:01.641446Z","caller":"traceutil/trace.go:171","msg":"trace[984192595] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1377; }","duration":"1.172232236s","start":"2026-07-31T07:26:00.469193Z","end":"2026-07-31T07:26:01.641425Z","steps":["trace[984192595] 'agreement among raft nodes before linearized reading' (duration: 1.171983689s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:26:01.641461Z","caller":"traceutil/trace.go:171","msg":"trace[183515777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.271917665s","start":"2026-07-31T07:26:00.369523Z","end":"2026-07-31T07:26:01.64144Z","steps":["trace[183515777] 'agreement among raft nodes before linearized reading' (duration: 1.271637638s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:01.641497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:00.469167Z","time spent":"1.172320108s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-31T07:26:01.641508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:00.369512Z","time spent":"1.271987197s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T07:26:01.641556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.836606ms","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:530"} {"level":"info","ts":"2026-07-31T07:26:01.6416Z","caller":"traceutil/trace.go:171","msg":"trace[846461628] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1377; }","duration":"161.923168ms","start":"2026-07-31T07:26:01.479666Z","end":"2026-07-31T07:26:01.641589Z","steps":["trace[846461628] 'agreement among raft nodes before linearized reading' (duration: 161.811075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:01.641641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.255645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:26:01.641684Z","caller":"traceutil/trace.go:171","msg":"trace[1141255553] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1377; }","duration":"629.330927ms","start":"2026-07-31T07:26:01.01234Z","end":"2026-07-31T07:26:01.641671Z","steps":["trace[1141255553] 'agreement among raft nodes before linearized reading' (duration: 629.268195ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:26:01.641255Z","caller":"traceutil/trace.go:171","msg":"trace[944578788] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1377; }","duration":"877.786246ms","start":"2026-07-31T07:26:00.763453Z","end":"2026-07-31T07:26:01.641239Z","steps":["trace[944578788] 'agreement among raft nodes before linearized reading' (duration: 877.661233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:01.641716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:01.012323Z","time spent":"629.384559ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:26:01.641794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275353274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-31T07:26:01.641771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:00.762644Z","time spent":"879.104571ms","remote":"127.0.0.1:52966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:26:01.641844Z","caller":"traceutil/trace.go:171","msg":"trace[9800809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1377; }","duration":"1.275413814s","start":"2026-07-31T07:26:00.366405Z","end":"2026-07-31T07:26:01.641819Z","steps":["trace[9800809] 'agreement among raft nodes before linearized reading' (duration: 1.275330502s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:01.641873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:00.366393Z","time spent":"1.275471417s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T07:26:01.642168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276209955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:26:01.642211Z","caller":"traceutil/trace.go:171","msg":"trace[848493339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.276280267s","start":"2026-07-31T07:26:00.365919Z","end":"2026-07-31T07:26:01.642199Z","steps":["trace[848493339] 'agreement among raft nodes before linearized reading' (duration: 1.275088517s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:26:01.642279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:26:00.365905Z","time spent":"1.276362369s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T07:30:27.506329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.83631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:30:27.506525Z","caller":"traceutil/trace.go:171","msg":"trace[1316621856] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"557.645893ms","start":"2026-07-31T07:30:26.948863Z","end":"2026-07-31T07:30:27.506509Z","steps":["trace[1316621856] 'process raft request' (duration: 557.55898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:30:27.506609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:30:26.948853Z","time spent":"557.724434ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:30:27.506675Z","caller":"traceutil/trace.go:171","msg":"trace[1532185927] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"557.880508ms","start":"2026-07-31T07:30:26.948764Z","end":"2026-07-31T07:30:27.506644Z","steps":["trace[1532185927] 'process raft request' (duration: 252.647058ms)","trace[1532185927] 'compare' (duration: 304.732557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:30:27.506839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:30:26.948686Z","time spent":"558.081913ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:30:27.507051Z","caller":"traceutil/trace.go:171","msg":"trace[1920218879] transaction","detail":"{read_only:false; response_revision:2625; number_of_response:1; }","duration":"342.379429ms","start":"2026-07-31T07:30:27.164656Z","end":"2026-07-31T07:30:27.507036Z","steps":["trace[1920218879] 'process raft request' (duration: 342.330638ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:30:27.507107Z","caller":"traceutil/trace.go:171","msg":"trace[477068451] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"557.089638ms","start":"2026-07-31T07:30:26.950008Z","end":"2026-07-31T07:30:27.507098Z","steps":["trace[477068451] 'process raft request' (duration: 556.467312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:30:27.507128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:30:27.164633Z","time spent":"342.460291ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:30:27.507166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:30:26.950002Z","time spent":"557.133049ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:30:27.507303Z","caller":"traceutil/trace.go:171","msg":"trace[166576586] linearizableReadLoop","detail":"{readStateIndex:2736; appliedIndex:2733; }","duration":"502.685949ms","start":"2026-07-31T07:30:27.004527Z","end":"2026-07-31T07:30:27.507213Z","steps":["trace[166576586] 'read index received' (duration: 196.899504ms)","trace[166576586] 'applied index is now lower than readState.Index' (duration: 305.783205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:30:27.507365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.823002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-31T07:30:27.507434Z","caller":"traceutil/trace.go:171","msg":"trace[218583718] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2625; }","duration":"502.972286ms","start":"2026-07-31T07:30:27.004445Z","end":"2026-07-31T07:30:27.507417Z","steps":["trace[218583718] 'agreement among raft nodes before linearized reading' (duration: 502.795961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:30:27.507466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:30:27.004424Z","time spent":"503.036558ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-31T07:30:27.508879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.610111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-31T07:30:27.509159Z","caller":"traceutil/trace.go:171","msg":"trace[347876561] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2625; }","duration":"325.706694ms","start":"2026-07-31T07:30:27.183222Z","end":"2026-07-31T07:30:27.508928Z","steps":["trace[347876561] 'agreement among raft nodes before linearized reading' (duration: 325.529809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:30:27.509395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:30:27.183186Z","time spent":"326.051473ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "}