{"level":"warn","ts":"2026-05-10T00:53:25.624229Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T00:53:25.624312Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-10T00:53:25.62437Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T00:53:25.624377Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-05-10T00:53:25.624393Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-10T00:53:25.624968Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-05-10T00:53:25.625038Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-10T00:53:25.629188Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.951999ms"} {"level":"info","ts":"2026-05-10T00:53:25.636967Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-05-10T00:53:25.637051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T00:53:25.637082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-05-10T00:53:25.637091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T00:53:25.6371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-05-10T00:53:25.637136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-05-10T00:53:25.650875Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T00:53:25.652489Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T00:53:25.653677Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-10T00:53:25.655664Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T00:53:25.655898Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T00:53:25.656086Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T00:53:25.656162Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T00:53:25.656182Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T00:53:25.659263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-05-10T00:53:25.659604Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-05-10T00:53:25.66047Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-10T00:53:25.660731Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-05-10T00:53:25.660761Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-05-10T00:53:25.660927Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T00:53:25.660952Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T00:53:26.437285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T00:53:26.437352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T00:53:26.437389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-05-10T00:53:26.437409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-05-10T00:53:26.437426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-05-10T00:53:26.437448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-05-10T00:53:26.437466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-05-10T00:53:26.43889Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:26.439817Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T00:53:26.439828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:53:26.439944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:53:26.440579Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:26.440707Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:26.44075Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:26.441889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T00:53:26.441942Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T00:53:26.443217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-10T00:53:26.443633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-05-10T00:53:51.035243Z","caller":"traceutil/trace.go:171","msg":"trace[1529537304] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"139.406524ms","start":"2026-05-10T00:53:50.895785Z","end":"2026-05-10T00:53:51.035191Z","steps":["trace[1529537304] 'process raft request' (duration: 139.046546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:55.551853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156361928083727,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T00:53:55.83977Z","caller":"traceutil/trace.go:171","msg":"trace[774336587] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"826.756397ms","start":"2026-05-10T00:53:55.012982Z","end":"2026-05-10T00:53:55.839738Z","steps":["trace[774336587] 'process raft request' (duration: 826.623964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:55.840317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.01296Z","time spent":"826.89351ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.023146Z","caller":"traceutil/trace.go:171","msg":"trace[1172686687] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"1.005116413s","start":"2026-05-10T00:53:55.017959Z","end":"2026-05-10T00:53:56.023075Z","steps":["trace[1172686687] 'process raft request' (duration: 1.004874567s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:56.023163Z","caller":"traceutil/trace.go:171","msg":"trace[239789017] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"1.005102512s","start":"2026-05-10T00:53:55.01801Z","end":"2026-05-10T00:53:56.023112Z","steps":["trace[239789017] 'process raft request' (duration: 1.00497047s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.023374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.01794Z","time spent":"1.005327017s","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.023649Z","caller":"traceutil/trace.go:171","msg":"trace[787863772] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"996.349565ms","start":"2026-05-10T00:53:55.027264Z","end":"2026-05-10T00:53:56.023614Z","steps":["trace[787863772] 'process raft request' (duration: 995.927255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.023812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.027236Z","time spent":"996.495638ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.0239Z","caller":"traceutil/trace.go:171","msg":"trace[1453064038] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"996.736304ms","start":"2026-05-10T00:53:55.027141Z","end":"2026-05-10T00:53:56.023878Z","steps":["trace[1453064038] 'process raft request' (duration: 995.891395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.023974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.027133Z","time spent":"996.813165ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.023995Z","caller":"traceutil/trace.go:171","msg":"trace[693642763] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"996.857936ms","start":"2026-05-10T00:53:55.027125Z","end":"2026-05-10T00:53:56.023983Z","steps":["trace[693642763] 'process raft request' (duration: 995.949696ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:56.024065Z","caller":"traceutil/trace.go:171","msg":"trace[1604371768] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"977.849206ms","start":"2026-05-10T00:53:55.046206Z","end":"2026-05-10T00:53:56.024055Z","steps":["trace[1604371768] 'process raft request' (duration: 977.17498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.024059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.02711Z","time spent":"996.917268ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:53:56.024111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.046177Z","time spent":"977.914527ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.02419Z","caller":"traceutil/trace.go:171","msg":"trace[742606439] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"990.323667ms","start":"2026-05-10T00:53:55.033854Z","end":"2026-05-10T00:53:56.024177Z","steps":["trace[742606439] 'process raft request' (duration: 989.380076ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:56.024224Z","caller":"traceutil/trace.go:171","msg":"trace[1170396178] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"956.146014ms","start":"2026-05-10T00:53:55.068054Z","end":"2026-05-10T00:53:56.0242Z","steps":["trace[1170396178] 'process raft request' (duration: 955.471509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.024261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.033836Z","time spent":"990.394929ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.024241Z","caller":"traceutil/trace.go:171","msg":"trace[1929871655] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:479; }","duration":"973.253072ms","start":"2026-05-10T00:53:55.050949Z","end":"2026-05-10T00:53:56.024202Z","steps":["trace[1929871655] 'read index received' (duration: 788.669385ms)","trace[1929871655] 'applied index is now lower than readState.Index' (duration: 184.582527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:53:56.024328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.068042Z","time spent":"956.237727ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.024463Z","caller":"traceutil/trace.go:171","msg":"trace[738643042] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"797.418932ms","start":"2026-05-10T00:53:55.227034Z","end":"2026-05-10T00:53:56.024453Z","steps":["trace[738643042] 'process raft request' (duration: 797.078645ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:56.024499Z","caller":"traceutil/trace.go:171","msg":"trace[590655658] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"978.013829ms","start":"2026-05-10T00:53:55.046475Z","end":"2026-05-10T00:53:56.024489Z","steps":["trace[590655658] 'process raft request' (duration: 977.004357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.024553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.046458Z","time spent":"978.07138ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:53:56.024569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.227007Z","time spent":"797.497875ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:53:56.024536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.574109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-10T00:53:56.024396Z","caller":"traceutil/trace.go:171","msg":"trace[1019572684] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"943.578901ms","start":"2026-05-10T00:53:55.080807Z","end":"2026-05-10T00:53:56.024386Z","steps":["trace[1019572684] 'process raft request' (duration: 942.836564ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:56.024664Z","caller":"traceutil/trace.go:171","msg":"trace[1297344732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"973.734982ms","start":"2026-05-10T00:53:55.050917Z","end":"2026-05-10T00:53:56.024652Z","steps":["trace[1297344732] 'agreement among raft nodes before linearized reading' (duration: 973.523997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.024701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.050905Z","time spent":"973.787634ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:53:56.024716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.080782Z","time spent":"943.907448ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:53:56.025142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.018005Z","time spent":"1.007073557s","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:53:56.419073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.204376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:53:56.419307Z","caller":"traceutil/trace.go:171","msg":"trace[1407100585] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"572.32336ms","start":"2026-05-10T00:53:55.846941Z","end":"2026-05-10T00:53:56.419264Z","steps":["trace[1407100585] 'process raft request' (duration: 327.627563ms)","trace[1407100585] 'compare' (duration: 244.032042ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:53:56.419972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.846902Z","time spent":"572.485874ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.420352Z","caller":"traceutil/trace.go:171","msg":"trace[218373496] transaction","detail":"{read_only:false; number_of_response:1; response_revision:478; }","duration":"570.399717ms","start":"2026-05-10T00:53:55.849923Z","end":"2026-05-10T00:53:56.420322Z","steps":["trace[218373496] 'process raft request' (duration: 569.336712ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:56.420475Z","caller":"traceutil/trace.go:171","msg":"trace[2069938238] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"379.961898ms","start":"2026-05-10T00:53:56.040256Z","end":"2026-05-10T00:53:56.420217Z","steps":["trace[2069938238] 'process raft request' (duration: 379.111108ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:56.42055Z","caller":"traceutil/trace.go:171","msg":"trace[1586202773] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"390.468286ms","start":"2026-05-10T00:53:56.030016Z","end":"2026-05-10T00:53:56.420484Z","steps":["trace[1586202773] 'process raft request' (duration: 389.295519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.42062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.040222Z","time spent":"380.352096ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.421072Z","caller":"traceutil/trace.go:171","msg":"trace[898414281] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:491; }","duration":"396.688137ms","start":"2026-05-10T00:53:56.024318Z","end":"2026-05-10T00:53:56.421006Z","steps":["trace[898414281] 'read index received' (duration: 150.375473ms)","trace[898414281] 'applied index is now lower than readState.Index' (duration: 246.047378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:53:56.421121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.849902Z","time spent":"570.53518ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:53:56.4219Z","caller":"traceutil/trace.go:171","msg":"trace[1585962976] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"381.614965ms","start":"2026-05-10T00:53:56.04026Z","end":"2026-05-10T00:53:56.421875Z","steps":["trace[1585962976] 'process raft request' (duration: 379.592349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.422001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.040231Z","time spent":"381.719027ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:53:56.422309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.029996Z","time spent":"390.66523ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:53:56.422747Z","caller":"traceutil/trace.go:171","msg":"trace[761527765] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"574.308815ms","start":"2026-05-10T00:53:55.84842Z","end":"2026-05-10T00:53:56.422728Z","steps":["trace[761527765] 'process raft request' (duration: 570.769534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.423589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320204722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:53:56.42365Z","caller":"traceutil/trace.go:171","msg":"trace[1220797668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"1.320290954s","start":"2026-05-10T00:53:55.103332Z","end":"2026-05-10T00:53:56.423623Z","steps":["trace[1220797668] 'agreement among raft nodes before linearized reading' (duration: 1.318110754s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.42373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.848389Z","time spent":"574.49562ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:53:56.423772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.10332Z","time spent":"1.320444977s","remote":"127.0.0.1:34904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:53:56.424475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369841866s","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-05-10T00:53:56.42461Z","caller":"traceutil/trace.go:171","msg":"trace[1463427160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"1.369909027s","start":"2026-05-10T00:53:55.054603Z","end":"2026-05-10T00:53:56.424512Z","steps":["trace[1463427160] 'agreement among raft nodes before linearized reading' (duration: 1.369789074s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.424658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.054594Z","time spent":"1.37005381s","remote":"127.0.0.1:35174","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":"info","ts":"2026-05-10T00:53:56.426807Z","caller":"traceutil/trace.go:171","msg":"trace[28555317] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"385.077773ms","start":"2026-05-10T00:53:56.040302Z","end":"2026-05-10T00:53:56.425379Z","steps":["trace[28555317] 'process raft request' (duration: 379.630251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.429543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.04029Z","time spent":"386.71718ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:53:56.429882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.163106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T00:53:56.42997Z","caller":"traceutil/trace.go:171","msg":"trace[1456138555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"526.259978ms","start":"2026-05-10T00:53:55.903692Z","end":"2026-05-10T00:53:56.429952Z","steps":["trace[1456138555] 'agreement among raft nodes before linearized reading' (duration: 517.379467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.430006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:55.903631Z","time spent":"526.36832ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T00:53:56.430089Z","caller":"traceutil/trace.go:171","msg":"trace[578725672] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"386.71175ms","start":"2026-05-10T00:53:56.043366Z","end":"2026-05-10T00:53:56.430078Z","steps":["trace[578725672] 'process raft request' (duration: 376.612812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.430154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.043349Z","time spent":"386.767292ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:53:56.430514Z","caller":"traceutil/trace.go:171","msg":"trace[1046180540] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"383.060647ms","start":"2026-05-10T00:53:56.047392Z","end":"2026-05-10T00:53:56.430453Z","steps":["trace[1046180540] 'process raft request' (duration: 382.577326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.430656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.047375Z","time spent":"383.176159ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:53:56.430745Z","caller":"traceutil/trace.go:171","msg":"trace[954778198] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"382.597747ms","start":"2026-05-10T00:53:56.048139Z","end":"2026-05-10T00:53:56.430736Z","steps":["trace[954778198] 'process raft request' (duration: 382.200438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.430808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.048129Z","time spent":"382.647997ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:53:56.430965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.047552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-10T00:53:56.431001Z","caller":"traceutil/trace.go:171","msg":"trace[344822338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"282.126533ms","start":"2026-05-10T00:53:56.148869Z","end":"2026-05-10T00:53:56.430996Z","steps":["trace[344822338] 'agreement among raft nodes before linearized reading' (duration: 282.064592ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:56.431049Z","caller":"traceutil/trace.go:171","msg":"trace[902074701] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"337.66327ms","start":"2026-05-10T00:53:56.093152Z","end":"2026-05-10T00:53:56.430816Z","steps":["trace[902074701] 'process raft request' (duration: 337.460436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.431138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.093119Z","time spent":"337.958986ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:53:56.431349Z","caller":"traceutil/trace.go:171","msg":"trace[1399799373] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"373.699986ms","start":"2026-05-10T00:53:56.057643Z","end":"2026-05-10T00:53:56.431343Z","steps":["trace[1399799373] 'process raft request' (duration: 372.812785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.431413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.05763Z","time spent":"373.757978ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:53:56.431494Z","caller":"traceutil/trace.go:171","msg":"trace[2062754834] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"360.312293ms","start":"2026-05-10T00:53:56.071164Z","end":"2026-05-10T00:53:56.431476Z","steps":["trace[2062754834] 'process raft request' (duration: 359.372211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:56.431669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:53:56.071139Z","time spent":"360.392705ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:54:01.855451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156361928083891,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T00:54:02.08945Z","caller":"traceutil/trace.go:171","msg":"trace[1234528503] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"867.971981ms","start":"2026-05-10T00:54:01.221439Z","end":"2026-05-10T00:54:02.089411Z","steps":["trace[1234528503] 'process raft request' (duration: 867.221704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:02.089696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:01.221397Z","time spent":"868.203346ms","remote":"127.0.0.1:35008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036217,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:54:02.090336Z","caller":"traceutil/trace.go:171","msg":"trace[973597515] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"735.224097ms","start":"2026-05-10T00:54:01.355079Z","end":"2026-05-10T00:54:02.090303Z","steps":["trace[973597515] 'read index received' (duration: 733.346065ms)","trace[973597515] 'applied index is now lower than readState.Index' (duration: 1.876902ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:54:02.090388Z","caller":"traceutil/trace.go:171","msg":"trace[1213275882] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"662.496201ms","start":"2026-05-10T00:54:01.427855Z","end":"2026-05-10T00:54:02.090351Z","steps":["trace[1213275882] 'process raft request' (duration: 662.355718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:02.090465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.36027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:02.090569Z","caller":"traceutil/trace.go:171","msg":"trace[2024012347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"735.516344ms","start":"2026-05-10T00:54:01.355026Z","end":"2026-05-10T00:54:02.090542Z","steps":["trace[2024012347] 'agreement among raft nodes before linearized reading' (duration: 735.36136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:02.090603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:01.427835Z","time spent":"662.643004ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:54:02.090615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:01.355001Z","time spent":"735.604895ms","remote":"127.0.0.1:34904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:54:02.587273Z","caller":"traceutil/trace.go:171","msg":"trace[418183365] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"135.418644ms","start":"2026-05-10T00:54:02.451822Z","end":"2026-05-10T00:54:02.587241Z","steps":["trace[418183365] 'read index received' (duration: 135.296082ms)","trace[418183365] 'applied index is now lower than readState.Index' (duration: 120.832µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:54:02.587552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.70935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:54:02.587691Z","caller":"traceutil/trace.go:171","msg":"trace[1365039138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"135.906095ms","start":"2026-05-10T00:54:02.451762Z","end":"2026-05-10T00:54:02.587668Z","steps":["trace[1365039138] 'agreement among raft nodes before linearized reading' (duration: 135.606868ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:02.58756Z","caller":"traceutil/trace.go:171","msg":"trace[409216297] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"138.101254ms","start":"2026-05-10T00:54:02.449234Z","end":"2026-05-10T00:54:02.587335Z","steps":["trace[409216297] 'process raft request' (duration: 137.795958ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:02.856054Z","caller":"traceutil/trace.go:171","msg":"trace[1858746024] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"268.614698ms","start":"2026-05-10T00:54:02.587369Z","end":"2026-05-10T00:54:02.855983Z","steps":["trace[1858746024] 'read index received' (duration: 259.755578ms)","trace[1858746024] 'applied index is now lower than readState.Index' (duration: 8.8576ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:54:02.856104Z","caller":"traceutil/trace.go:171","msg":"trace[1146549081] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"281.119021ms","start":"2026-05-10T00:54:02.574938Z","end":"2026-05-10T00:54:02.856057Z","steps":["trace[1146549081] 'process raft request' (duration: 272.641669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:02.856299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.206634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T00:54:02.856151Z","caller":"traceutil/trace.go:171","msg":"trace[194660460] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"264.519315ms","start":"2026-05-10T00:54:02.59159Z","end":"2026-05-10T00:54:02.85611Z","steps":["trace[194660460] 'process raft request' (duration: 264.369952ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:02.856345Z","caller":"traceutil/trace.go:171","msg":"trace[1311959595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"388.299416ms","start":"2026-05-10T00:54:02.468033Z","end":"2026-05-10T00:54:02.856333Z","steps":["trace[1311959595] 'agreement among raft nodes before linearized reading' (duration: 388.103922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:02.85639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:02.46802Z","time spent":"388.361318ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T00:54:02.857576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.871398ms","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-05-10T00:54:02.857635Z","caller":"traceutil/trace.go:171","msg":"trace[1876439714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"386.23288ms","start":"2026-05-10T00:54:02.471385Z","end":"2026-05-10T00:54:02.857618Z","steps":["trace[1876439714] 'agreement among raft nodes before linearized reading' (duration: 384.676254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:02.857672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:02.471332Z","time spent":"386.328411ms","remote":"127.0.0.1:35174","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-05-10T00:54:03.45946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.265801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:54:03.459711Z","caller":"traceutil/trace.go:171","msg":"trace[538855858] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:592; }","duration":"398.978317ms","start":"2026-05-10T00:54:03.060713Z","end":"2026-05-10T00:54:03.459692Z","steps":["trace[538855858] 'read index received' (duration: 202.380749ms)","trace[538855858] 'applied index is now lower than readState.Index' (duration: 196.596358ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:54:03.459832Z","caller":"traceutil/trace.go:171","msg":"trace[736429933] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"541.406441ms","start":"2026-05-10T00:54:02.918415Z","end":"2026-05-10T00:54:03.459821Z","steps":["trace[736429933] 'process raft request' (duration: 541.227026ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:03.459875Z","caller":"traceutil/trace.go:171","msg":"trace[1759874576] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"591.444824ms","start":"2026-05-10T00:54:02.868412Z","end":"2026-05-10T00:54:03.459857Z","steps":["trace[1759874576] 'process raft request' (duration: 394.666751ms)","trace[1759874576] 'compare' (duration: 196.081276ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:54:03.45989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:02.918396Z","time spent":"541.462053ms","remote":"127.0.0.1:35266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:54:03.459925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:02.868396Z","time spent":"591.501255ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:54:03.459954Z","caller":"traceutil/trace.go:171","msg":"trace[956561179] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"589.99115ms","start":"2026-05-10T00:54:02.86991Z","end":"2026-05-10T00:54:03.459901Z","steps":["trace[956561179] 'process raft request' (duration: 589.671312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:03.460008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.27543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:03.460105Z","caller":"traceutil/trace.go:171","msg":"trace[155791058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"355.435703ms","start":"2026-05-10T00:54:03.104634Z","end":"2026-05-10T00:54:03.46007Z","steps":["trace[155791058] 'agreement among raft nodes before linearized reading' (duration: 355.252449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:03.460146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:02.869901Z","time spent":"590.140864ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:54:03.460153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:03.104602Z","time spent":"355.541305ms","remote":"127.0.0.1:34904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:54:03.460196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.47998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nwv89\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-10T00:54:03.460224Z","caller":"traceutil/trace.go:171","msg":"trace[1733382366] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nwv89; range_end:; response_count:1; response_revision:575; }","duration":"399.547611ms","start":"2026-05-10T00:54:03.060668Z","end":"2026-05-10T00:54:03.460216Z","steps":["trace[1733382366] 'agreement among raft nodes before linearized reading' (duration: 399.311596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:03.460242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:03.060649Z","time spent":"399.588942ms","remote":"127.0.0.1:35104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-nwv89\" "} {"level":"warn","ts":"2026-05-10T00:54:04.606372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156361928083915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:54:05.106932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156361928083915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:54:05.281404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351477781s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:05.28153Z","caller":"traceutil/trace.go:171","msg":"trace[1279884010] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:575; }","duration":"1.351612384s","start":"2026-05-10T00:54:03.929902Z","end":"2026-05-10T00:54:05.281514Z","steps":["trace[1279884010] 'range keys from in-memory index tree' (duration: 1.351465061s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.282167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.556378617s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:54:05.282232Z","caller":"traceutil/trace.go:171","msg":"trace[1575496547] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.806880035s","start":"2026-05-10T00:54:03.475339Z","end":"2026-05-10T00:54:05.282219Z","steps":["trace[1575496547] 'process raft request' (duration: 250.387935ms)","trace[1575496547] 'compare' (duration: 1.555503718s)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:54:05.282282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:03.475293Z","time spent":"1.806967507s","remote":"127.0.0.1:35104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:54:05.337979Z","caller":"traceutil/trace.go:171","msg":"trace[1710523244] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.404847509s","start":"2026-05-10T00:54:03.933104Z","end":"2026-05-10T00:54:05.337951Z","steps":["trace[1710523244] 'process raft request' (duration: 1.403833886s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.338214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:03.933076Z","time spent":"1.404982311s","remote":"127.0.0.1:35008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:54:05.356352Z","caller":"traceutil/trace.go:171","msg":"trace[1677488060] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"1.251050858s","start":"2026-05-10T00:54:04.105277Z","end":"2026-05-10T00:54:05.356328Z","steps":["trace[1677488060] 'read index received' (duration: 1.231562607s)","trace[1677488060] 'applied index is now lower than readState.Index' (duration: 19.487041ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:54:05.356586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251296814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:05.356645Z","caller":"traceutil/trace.go:171","msg":"trace[886728489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"1.251419477s","start":"2026-05-10T00:54:04.105212Z","end":"2026-05-10T00:54:05.356632Z","steps":["trace[886728489] 'agreement among raft nodes before linearized reading' (duration: 1.251319425s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.356701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:04.10519Z","time spent":"1.251499478s","remote":"127.0.0.1:34904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:54:05.356971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.620221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T00:54:05.357033Z","caller":"traceutil/trace.go:171","msg":"trace[585292329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"764.725614ms","start":"2026-05-10T00:54:04.592298Z","end":"2026-05-10T00:54:05.357024Z","steps":["trace[585292329] 'agreement among raft nodes before linearized reading' (duration: 764.587911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.357086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:04.592278Z","time spent":"764.798405ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:54:05.357647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.462676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:05.357723Z","caller":"traceutil/trace.go:171","msg":"trace[1193856055] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"124.542628ms","start":"2026-05-10T00:54:05.233168Z","end":"2026-05-10T00:54:05.357711Z","steps":["trace[1193856055] 'agreement among raft nodes before linearized reading' (duration: 124.450656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.357893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.788228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:05.357963Z","caller":"traceutil/trace.go:171","msg":"trace[1172611306] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"137.868479ms","start":"2026-05-10T00:54:05.220086Z","end":"2026-05-10T00:54:05.357954Z","steps":["trace[1172611306] 'agreement among raft nodes before linearized reading' (duration: 137.778997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.358118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.035073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:05.358189Z","caller":"traceutil/trace.go:171","msg":"trace[768897105] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"138.118745ms","start":"2026-05-10T00:54:05.22006Z","end":"2026-05-10T00:54:05.358179Z","steps":["trace[768897105] 'agreement among raft nodes before linearized reading' (duration: 138.026933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.358339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.043629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:05.35841Z","caller":"traceutil/trace.go:171","msg":"trace[1671969276] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:577; }","duration":"163.145561ms","start":"2026-05-10T00:54:05.195255Z","end":"2026-05-10T00:54:05.3584Z","steps":["trace[1671969276] 'agreement among raft nodes before linearized reading' (duration: 163.057789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.358632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.381839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:05.358694Z","caller":"traceutil/trace.go:171","msg":"trace[1252674509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:577; }","duration":"164.48926ms","start":"2026-05-10T00:54:05.194191Z","end":"2026-05-10T00:54:05.35868Z","steps":["trace[1252674509] 'agreement among raft nodes before linearized reading' (duration: 164.358098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.358852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.652924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:05.358877Z","caller":"traceutil/trace.go:171","msg":"trace[120379569] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:577; }","duration":"164.703966ms","start":"2026-05-10T00:54:05.194166Z","end":"2026-05-10T00:54:05.35887Z","steps":["trace[120379569] 'agreement among raft nodes before linearized reading' (duration: 164.667275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.359018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.189282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:54:05.35904Z","caller":"traceutil/trace.go:171","msg":"trace[206081220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"490.248373ms","start":"2026-05-10T00:54:04.868786Z","end":"2026-05-10T00:54:05.359034Z","steps":["trace[206081220] 'agreement among raft nodes before linearized reading' (duration: 490.155741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:05.359057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:04.868765Z","time spent":"490.287234ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:54:06.47615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.895007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:06.476372Z","caller":"traceutil/trace.go:171","msg":"trace[1169720440] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:593; }","duration":"190.159253ms","start":"2026-05-10T00:54:06.286196Z","end":"2026-05-10T00:54:06.476355Z","steps":["trace[1169720440] 'count revisions from in-memory index tree' (duration: 189.840126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:06.476611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.255875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:06.476696Z","caller":"traceutil/trace.go:171","msg":"trace[1994195766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:593; }","duration":"190.373408ms","start":"2026-05-10T00:54:06.286309Z","end":"2026-05-10T00:54:06.476682Z","steps":["trace[1994195766] 'range keys from in-memory index tree' (duration: 190.142922ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:07.428202Z","caller":"traceutil/trace.go:171","msg":"trace[702027099] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"123.550866ms","start":"2026-05-10T00:54:07.304629Z","end":"2026-05-10T00:54:07.42818Z","steps":["trace[702027099] 'process raft request' (duration: 123.382072ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:21.203991Z","caller":"traceutil/trace.go:171","msg":"trace[806957282] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"103.399129ms","start":"2026-05-10T00:54:21.100568Z","end":"2026-05-10T00:54:21.203967Z","steps":["trace[806957282] 'read index received' (duration: 103.197795ms)","trace[806957282] 'applied index is now lower than readState.Index' (duration: 199.664µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:54:21.204038Z","caller":"traceutil/trace.go:171","msg":"trace[2089321291] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"232.47043ms","start":"2026-05-10T00:54:20.971534Z","end":"2026-05-10T00:54:21.204005Z","steps":["trace[2089321291] 'process raft request' (duration: 232.214424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:21.204141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.553733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:21.204188Z","caller":"traceutil/trace.go:171","msg":"trace[2032420566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"103.646345ms","start":"2026-05-10T00:54:21.100528Z","end":"2026-05-10T00:54:21.204174Z","steps":["trace[2032420566] 'agreement among raft nodes before linearized reading' (duration: 103.554753ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:21.40384Z","caller":"traceutil/trace.go:171","msg":"trace[987906939] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"198.791138ms","start":"2026-05-10T00:54:21.205027Z","end":"2026-05-10T00:54:21.403818Z","steps":["trace[987906939] 'read index received' (duration: 198.586573ms)","trace[987906939] 'applied index is now lower than readState.Index' (duration: 203.665µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:54:21.403979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.923001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:21.404051Z","caller":"traceutil/trace.go:171","msg":"trace[129024801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"199.037214ms","start":"2026-05-10T00:54:21.204997Z","end":"2026-05-10T00:54:21.404034Z","steps":["trace[129024801] 'agreement among raft nodes before linearized reading' (duration: 198.921341ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:21.404287Z","caller":"traceutil/trace.go:171","msg":"trace[870690518] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"231.970319ms","start":"2026-05-10T00:54:21.172255Z","end":"2026-05-10T00:54:21.404225Z","steps":["trace[870690518] 'process raft request' (duration: 231.420527ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:57.055074Z","caller":"traceutil/trace.go:171","msg":"trace[162092246] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"712.954644ms","start":"2026-05-10T00:54:56.342093Z","end":"2026-05-10T00:54:57.055048Z","steps":["trace[162092246] 'process raft request' (duration: 712.716189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:57.055219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:56.342074Z","time spent":"713.071517ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:54:57.688739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.500606ms","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-05-10T00:54:57.688811Z","caller":"traceutil/trace.go:171","msg":"trace[630927164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"294.620488ms","start":"2026-05-10T00:54:57.394176Z","end":"2026-05-10T00:54:57.688797Z","steps":["trace[630927164] 'range keys from in-memory index tree' (duration: 294.361632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:58.217946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156361928084763,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T00:54:58.382049Z","caller":"traceutil/trace.go:171","msg":"trace[752851892] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"689.450917ms","start":"2026-05-10T00:54:57.692543Z","end":"2026-05-10T00:54:58.381994Z","steps":["trace[752851892] 'process raft request' (duration: 689.192571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:58.38233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:57.69253Z","time spent":"689.685472ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:54:58.382512Z","caller":"traceutil/trace.go:171","msg":"trace[400190730] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:994; }","duration":"664.578938ms","start":"2026-05-10T00:54:57.717912Z","end":"2026-05-10T00:54:58.382491Z","steps":["trace[400190730] 'read index received' (duration: 663.844502ms)","trace[400190730] 'applied index is now lower than readState.Index' (duration: 733.356µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:54:58.382691Z","caller":"traceutil/trace.go:171","msg":"trace[379049516] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"690.132522ms","start":"2026-05-10T00:54:57.692547Z","end":"2026-05-10T00:54:58.38268Z","steps":["trace[379049516] 'process raft request' (duration: 689.697272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:58.382768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:57.692522Z","time spent":"690.201303ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:54:58.382874Z","caller":"traceutil/trace.go:171","msg":"trace[444358194] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"690.273515ms","start":"2026-05-10T00:54:57.692592Z","end":"2026-05-10T00:54:58.382866Z","steps":["trace[444358194] 'process raft request' (duration: 689.737553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:58.38293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:57.692568Z","time spent":"690.335456ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:54:58.383097Z","caller":"traceutil/trace.go:171","msg":"trace[1455975852] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"687.191566ms","start":"2026-05-10T00:54:57.695879Z","end":"2026-05-10T00:54:58.383071Z","steps":["trace[1455975852] 'process raft request' (duration: 686.48452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:58.383157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:57.695859Z","time spent":"687.270327ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:54:58.383421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.509178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T00:54:58.383528Z","caller":"traceutil/trace.go:171","msg":"trace[1694937232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"665.641612ms","start":"2026-05-10T00:54:57.717871Z","end":"2026-05-10T00:54:58.383513Z","steps":["trace[1694937232] 'agreement among raft nodes before linearized reading' (duration: 665.247573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:58.383588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:57.717854Z","time spent":"665.723344ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T00:54:58.383696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.979276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:58.383732Z","caller":"traceutil/trace.go:171","msg":"trace[166703069] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:965; }","duration":"575.041537ms","start":"2026-05-10T00:54:57.808683Z","end":"2026-05-10T00:54:58.383725Z","steps":["trace[166703069] 'agreement among raft nodes before linearized reading' (duration: 574.992876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:58.383766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:57.80867Z","time spent":"575.089029ms","remote":"127.0.0.1:35152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:55:20.83558Z","caller":"traceutil/trace.go:171","msg":"trace[1883224278] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"346.018392ms","start":"2026-05-10T00:55:20.489536Z","end":"2026-05-10T00:55:20.835554Z","steps":["trace[1883224278] 'read index received' (duration: 345.830307ms)","trace[1883224278] 'applied index is now lower than readState.Index' (duration: 187.145µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:55:20.835855Z","caller":"traceutil/trace.go:171","msg":"trace[121607852] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"386.282427ms","start":"2026-05-10T00:55:20.44956Z","end":"2026-05-10T00:55:20.835842Z","steps":["trace[121607852] 'process raft request' (duration: 385.868128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:20.835974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:20.44954Z","time spent":"386.354998ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:55:20.836302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.738968ms","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-05-10T00:55:20.836355Z","caller":"traceutil/trace.go:171","msg":"trace[1319561951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"346.84568ms","start":"2026-05-10T00:55:20.489498Z","end":"2026-05-10T00:55:20.836344Z","steps":["trace[1319561951] 'agreement among raft nodes before linearized reading' (duration: 346.678976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:20.836392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:20.489462Z","time spent":"346.922482ms","remote":"127.0.0.1:35174","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-05-10T00:55:20.836584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.956884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:55:20.836621Z","caller":"traceutil/trace.go:171","msg":"trace[284224781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"347.037606ms","start":"2026-05-10T00:55:20.489574Z","end":"2026-05-10T00:55:20.836612Z","steps":["trace[284224781] 'agreement among raft nodes before linearized reading' (duration: 346.950384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:20.836653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:20.48955Z","time spent":"347.092417ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:55:20.836888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.295531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T00:55:20.836925Z","caller":"traceutil/trace.go:171","msg":"trace[1147067945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"306.360223ms","start":"2026-05-10T00:55:20.530557Z","end":"2026-05-10T00:55:20.836917Z","steps":["trace[1147067945] 'agreement among raft nodes before linearized reading' (duration: 306.270151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:20.836954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:20.530543Z","time spent":"306.405913ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T00:55:20.837165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.18695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T00:55:20.837203Z","caller":"traceutil/trace.go:171","msg":"trace[199984846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1035; }","duration":"343.253231ms","start":"2026-05-10T00:55:20.493941Z","end":"2026-05-10T00:55:20.837195Z","steps":["trace[199984846] 'agreement among raft nodes before linearized reading' (duration: 343.159759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:20.83723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:20.493928Z","time spent":"343.295931ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:55:20.837367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.407334ms","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-05-10T00:55:20.837399Z","caller":"traceutil/trace.go:171","msg":"trace[2012978534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"343.449285ms","start":"2026-05-10T00:55:20.493942Z","end":"2026-05-10T00:55:20.837391Z","steps":["trace[2012978534] 'agreement among raft nodes before linearized reading' (duration: 343.350693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:20.83743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:20.493935Z","time spent":"343.488736ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-10T00:55:20.952746Z","caller":"traceutil/trace.go:171","msg":"trace[984232598] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"110.8401ms","start":"2026-05-10T00:55:20.84187Z","end":"2026-05-10T00:55:20.95271Z","steps":["trace[984232598] 'process raft request' (duration: 86.047763ms)","trace[984232598] 'compare' (duration: 24.44336ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:55:20.975525Z","caller":"traceutil/trace.go:171","msg":"trace[721111535] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"133.459268ms","start":"2026-05-10T00:55:20.842043Z","end":"2026-05-10T00:55:20.975502Z","steps":["trace[721111535] 'process raft request' (duration: 133.053429ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:20.975525Z","caller":"traceutil/trace.go:171","msg":"trace[487714965] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"133.285564ms","start":"2026-05-10T00:55:20.842207Z","end":"2026-05-10T00:55:20.975493Z","steps":["trace[487714965] 'process raft request' (duration: 133.168161ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:20.975726Z","caller":"traceutil/trace.go:171","msg":"trace[1488506313] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"133.496558ms","start":"2026-05-10T00:55:20.842217Z","end":"2026-05-10T00:55:20.975713Z","steps":["trace[1488506313] 'process raft request' (duration: 133.229362ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:20.975784Z","caller":"traceutil/trace.go:171","msg":"trace[1393305963] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"133.389326ms","start":"2026-05-10T00:55:20.842375Z","end":"2026-05-10T00:55:20.975765Z","steps":["trace[1393305963] 'process raft request' (duration: 133.203392ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:23.101611Z","caller":"traceutil/trace.go:171","msg":"trace[2070157691] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"117.437228ms","start":"2026-05-10T00:55:22.984152Z","end":"2026-05-10T00:55:23.101589Z","steps":["trace[2070157691] 'process raft request' (duration: 117.286575ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:23.10813Z","caller":"traceutil/trace.go:171","msg":"trace[2049481109] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"123.732559ms","start":"2026-05-10T00:55:22.984377Z","end":"2026-05-10T00:55:23.10811Z","steps":["trace[2049481109] 'process raft request' (duration: 123.625987ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:23.108204Z","caller":"traceutil/trace.go:171","msg":"trace[1636366257] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"122.732266ms","start":"2026-05-10T00:55:22.985429Z","end":"2026-05-10T00:55:23.108161Z","steps":["trace[1636366257] 'process raft request' (duration: 122.614314ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:23.108305Z","caller":"traceutil/trace.go:171","msg":"trace[1468541071] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"123.985295ms","start":"2026-05-10T00:55:22.984292Z","end":"2026-05-10T00:55:23.108277Z","steps":["trace[1468541071] 'process raft request' (duration: 123.584706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:35.35475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.242378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:55:35.354858Z","caller":"traceutil/trace.go:171","msg":"trace[1237750000] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"188.92763ms","start":"2026-05-10T00:55:35.165914Z","end":"2026-05-10T00:55:35.354841Z","steps":["trace[1237750000] 'read index received' (duration: 35.410256ms)","trace[1237750000] 'applied index is now lower than readState.Index' (duration: 153.516244ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:55:35.355097Z","caller":"traceutil/trace.go:171","msg":"trace[686881203] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"189.35554ms","start":"2026-05-10T00:55:35.165725Z","end":"2026-05-10T00:55:35.355081Z","steps":["trace[686881203] 'process raft request' (duration: 35.620701ms)","trace[686881203] 'compare' (duration: 152.983892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:55:35.355368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.430182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-10T00:55:35.355407Z","caller":"traceutil/trace.go:171","msg":"trace[597044426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1134; }","duration":"189.485802ms","start":"2026-05-10T00:55:35.165912Z","end":"2026-05-10T00:55:35.355398Z","steps":["trace[597044426] 'agreement among raft nodes before linearized reading' (duration: 189.35585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:35.355615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.696407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-10T00:55:35.355652Z","caller":"traceutil/trace.go:171","msg":"trace[907461888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1134; }","duration":"189.748759ms","start":"2026-05-10T00:55:35.165894Z","end":"2026-05-10T00:55:35.355643Z","steps":["trace[907461888] 'agreement among raft nodes before linearized reading' (duration: 189.658717ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:35.359717Z","caller":"traceutil/trace.go:171","msg":"trace[1909739936] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"192.390769ms","start":"2026-05-10T00:55:35.167308Z","end":"2026-05-10T00:55:35.359699Z","steps":["trace[1909739936] 'process raft request' (duration: 192.318597ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:37.28489Z","caller":"traceutil/trace.go:171","msg":"trace[626213089] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"178.171262ms","start":"2026-05-10T00:55:37.106692Z","end":"2026-05-10T00:55:37.284863Z","steps":["trace[626213089] 'process raft request' (duration: 178.006318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:37.864612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156361928085192,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T00:55:37.946784Z","caller":"traceutil/trace.go:171","msg":"trace[790583237] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"584.049267ms","start":"2026-05-10T00:55:37.362715Z","end":"2026-05-10T00:55:37.946764Z","steps":["trace[790583237] 'process raft request' (duration: 583.929285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:37.946896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:37.362695Z","time spent":"584.144329ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:55:37.948003Z","caller":"traceutil/trace.go:171","msg":"trace[1936274566] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1182; }","duration":"584.442907ms","start":"2026-05-10T00:55:37.363522Z","end":"2026-05-10T00:55:37.947965Z","steps":["trace[1936274566] 'read index received' (duration: 583.159077ms)","trace[1936274566] 'applied index is now lower than readState.Index' (duration: 1.28315ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:55:37.948253Z","caller":"traceutil/trace.go:171","msg":"trace[115102007] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"583.154578ms","start":"2026-05-10T00:55:37.365084Z","end":"2026-05-10T00:55:37.948239Z","steps":["trace[115102007] 'process raft request' (duration: 582.80776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:37.948359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:37.365063Z","time spent":"583.223559ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:55:37.948407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.881166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-10T00:55:37.948432Z","caller":"traceutil/trace.go:171","msg":"trace[1563471486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1141; }","duration":"584.947058ms","start":"2026-05-10T00:55:37.363477Z","end":"2026-05-10T00:55:37.948424Z","steps":["trace[1563471486] 'agreement among raft nodes before linearized reading' (duration: 584.861196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:37.94845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:37.363436Z","time spent":"585.010139ms","remote":"127.0.0.1:35174","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-05-10T00:55:37.948511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.495765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-10T00:55:37.948529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.807816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:55:37.948549Z","caller":"traceutil/trace.go:171","msg":"trace[1527445348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1141; }","duration":"583.546326ms","start":"2026-05-10T00:55:37.364994Z","end":"2026-05-10T00:55:37.948541Z","steps":["trace[1527445348] 'agreement among raft nodes before linearized reading' (duration: 583.333052ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:37.94855Z","caller":"traceutil/trace.go:171","msg":"trace[524415409] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1141; }","duration":"209.871137ms","start":"2026-05-10T00:55:37.738673Z","end":"2026-05-10T00:55:37.948544Z","steps":["trace[524415409] 'agreement among raft nodes before linearized reading' (duration: 209.829186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:37.948588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:37.364989Z","time spent":"583.592377ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T00:55:37.948934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.83623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-10T00:55:37.949015Z","caller":"traceutil/trace.go:171","msg":"trace[1600735473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1141; }","duration":"582.952143ms","start":"2026-05-10T00:55:37.366046Z","end":"2026-05-10T00:55:37.948999Z","steps":["trace[1600735473] 'agreement among raft nodes before linearized reading' (duration: 582.3459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:37.949349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:37.366038Z","time spent":"583.297961ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:55:54.247985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.144674ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:55:54.248092Z","caller":"traceutil/trace.go:171","msg":"trace[197825618] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1210; }","duration":"318.259857ms","start":"2026-05-10T00:55:53.929811Z","end":"2026-05-10T00:55:54.248071Z","steps":["trace[197825618] 'range keys from in-memory index tree' (duration: 318.127394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:54.248264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.434319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:55:54.249105Z","caller":"traceutil/trace.go:171","msg":"trace[431401552] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"903.831989ms","start":"2026-05-10T00:55:53.345254Z","end":"2026-05-10T00:55:54.249086Z","steps":["trace[431401552] 'read index received' (duration: 267.386627ms)","trace[431401552] 'applied index is now lower than readState.Index' (duration: 636.443452ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:55:54.249211Z","caller":"traceutil/trace.go:171","msg":"trace[30015014] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"1.06670219s","start":"2026-05-10T00:55:53.182471Z","end":"2026-05-10T00:55:54.249174Z","steps":["trace[30015014] 'process raft request' (duration: 430.273058ms)","trace[30015014] 'compare' (duration: 635.229455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:55:54.249403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:53.182461Z","time spent":"1.066842434s","remote":"127.0.0.1:35308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:55:54.249762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.508253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T00:55:54.249825Z","caller":"traceutil/trace.go:171","msg":"trace[1289969502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"904.610086ms","start":"2026-05-10T00:55:53.345201Z","end":"2026-05-10T00:55:54.249811Z","steps":["trace[1289969502] 'agreement among raft nodes before linearized reading' (duration: 903.989602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:54.249903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:53.345179Z","time spent":"904.714188ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T00:55:54.250444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.43268ms","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":"info","ts":"2026-05-10T00:55:54.250517Z","caller":"traceutil/trace.go:171","msg":"trace[561128557] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1212; }","duration":"148.550133ms","start":"2026-05-10T00:55:54.10195Z","end":"2026-05-10T00:55:54.250501Z","steps":["trace[561128557] 'agreement among raft nodes before linearized reading' (duration: 148.42594ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.250655Z","caller":"traceutil/trace.go:171","msg":"trace[1982160286] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"227.788095ms","start":"2026-05-10T00:55:54.022852Z","end":"2026-05-10T00:55:54.25064Z","steps":["trace[1982160286] 'process raft request' (duration: 227.416737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:54.250853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.736456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:55:54.250894Z","caller":"traceutil/trace.go:171","msg":"trace[309725295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"143.801607ms","start":"2026-05-10T00:55:54.107083Z","end":"2026-05-10T00:55:54.250884Z","steps":["trace[309725295] 'agreement among raft nodes before linearized reading' (duration: 143.735796ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.418611Z","caller":"traceutil/trace.go:171","msg":"trace[435379640] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"138.171822ms","start":"2026-05-10T00:55:54.280418Z","end":"2026-05-10T00:55:54.41859Z","steps":["trace[435379640] 'process raft request' (duration: 128.559999ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.466435Z","caller":"traceutil/trace.go:171","msg":"trace[622673669] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"182.429126ms","start":"2026-05-10T00:55:54.283978Z","end":"2026-05-10T00:55:54.466407Z","steps":["trace[622673669] 'process raft request' (duration: 182.070398ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.466513Z","caller":"traceutil/trace.go:171","msg":"trace[162429537] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"180.471623ms","start":"2026-05-10T00:55:54.286021Z","end":"2026-05-10T00:55:54.466493Z","steps":["trace[162429537] 'process raft request' (duration: 180.102605ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.466524Z","caller":"traceutil/trace.go:171","msg":"trace[232575594] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"180.545975ms","start":"2026-05-10T00:55:54.285929Z","end":"2026-05-10T00:55:54.466475Z","steps":["trace[232575594] 'process raft request' (duration: 180.156686ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.466531Z","caller":"traceutil/trace.go:171","msg":"trace[693074800] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"182.58058ms","start":"2026-05-10T00:55:54.283895Z","end":"2026-05-10T00:55:54.466475Z","steps":["trace[693074800] 'process raft request' (duration: 182.002797ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.527685Z","caller":"traceutil/trace.go:171","msg":"trace[1992221661] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1258; }","duration":"194.405512ms","start":"2026-05-10T00:55:54.333256Z","end":"2026-05-10T00:55:54.527662Z","steps":["trace[1992221661] 'read index received' (duration: 75.731024ms)","trace[1992221661] 'applied index is now lower than readState.Index' (duration: 118.673468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:55:54.527804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.524285ms","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-05-10T00:55:54.527831Z","caller":"traceutil/trace.go:171","msg":"trace[865521875] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1217; }","duration":"194.606927ms","start":"2026-05-10T00:55:54.333215Z","end":"2026-05-10T00:55:54.527822Z","steps":["trace[865521875] 'agreement among raft nodes before linearized reading' (duration: 194.534915ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:56.433196Z","caller":"traceutil/trace.go:171","msg":"trace[563972203] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"176.553746ms","start":"2026-05-10T00:55:56.25661Z","end":"2026-05-10T00:55:56.433163Z","steps":["trace[563972203] 'process raft request' (duration: 176.326991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:09.237109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156361928085523,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T00:56:09.379842Z","caller":"traceutil/trace.go:171","msg":"trace[997583251] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"643.480589ms","start":"2026-05-10T00:56:08.736342Z","end":"2026-05-10T00:56:09.379822Z","steps":["trace[997583251] 'read index received' (duration: 643.347346ms)","trace[997583251] 'applied index is now lower than readState.Index' (duration: 132.183µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:56:09.379995Z","caller":"traceutil/trace.go:171","msg":"trace[749795452] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"842.784001ms","start":"2026-05-10T00:56:08.537148Z","end":"2026-05-10T00:56:09.379932Z","steps":["trace[749795452] 'process raft request' (duration: 842.541066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:09.380125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.781995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T00:56:09.380174Z","caller":"traceutil/trace.go:171","msg":"trace[690579358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1306; }","duration":"643.899998ms","start":"2026-05-10T00:56:08.736261Z","end":"2026-05-10T00:56:09.380161Z","steps":["trace[690579358] 'agreement among raft nodes before linearized reading' (duration: 643.758455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:09.380212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:56:08.736215Z","time spent":"643.98897ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T00:56:09.380232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:56:08.53711Z","time spent":"843.006136ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:56:09.380572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.32375ms","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-05-10T00:56:09.380773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.836701ms","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":"warn","ts":"2026-05-10T00:56:09.380828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.725395ms","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-05-10T00:56:09.380835Z","caller":"traceutil/trace.go:171","msg":"trace[1322270719] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1306; }","duration":"636.954273ms","start":"2026-05-10T00:56:08.743863Z","end":"2026-05-10T00:56:09.380817Z","steps":["trace[1322270719] 'agreement among raft nodes before linearized reading' (duration: 636.673476ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:09.38087Z","caller":"traceutil/trace.go:171","msg":"trace[1309736235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1306; }","duration":"639.789567ms","start":"2026-05-10T00:56:08.74107Z","end":"2026-05-10T00:56:09.38086Z","steps":["trace[1309736235] 'agreement among raft nodes before linearized reading' (duration: 639.701415ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:09.380801Z","caller":"traceutil/trace.go:171","msg":"trace[2097661322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"641.586747ms","start":"2026-05-10T00:56:08.739192Z","end":"2026-05-10T00:56:09.380779Z","steps":["trace[2097661322] 'agreement among raft nodes before linearized reading' (duration: 641.026875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:09.380972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:56:08.73915Z","time spent":"641.807892ms","remote":"127.0.0.1:35174","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-05-10T00:56:09.381285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.280559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:56:09.381605Z","caller":"traceutil/trace.go:171","msg":"trace[386446105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1306; }","duration":"641.642967ms","start":"2026-05-10T00:56:08.739946Z","end":"2026-05-10T00:56:09.381589Z","steps":["trace[386446105] 'agreement among raft nodes before linearized reading' (duration: 641.268208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:09.381699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:56:08.739917Z","time spent":"641.76297ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:56:09.380906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:56:08.743851Z","time spent":"637.045075ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-10T00:56:09.380911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:56:08.741057Z","time spent":"639.842867ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T00:56:09.380749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.106473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T00:56:09.382496Z","caller":"traceutil/trace.go:171","msg":"trace[595589575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1306; }","duration":"641.883913ms","start":"2026-05-10T00:56:08.740594Z","end":"2026-05-10T00:56:09.382478Z","steps":["trace[595589575] 'agreement among raft nodes before linearized reading' (duration: 640.073542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:09.381381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.863699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:56:09.382595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:56:08.740575Z","time spent":"642.003805ms","remote":"127.0.0.1:35174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-10T00:56:09.382612Z","caller":"traceutil/trace.go:171","msg":"trace[1512268768] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1306; }","duration":"226.137418ms","start":"2026-05-10T00:56:09.156456Z","end":"2026-05-10T00:56:09.382594Z","steps":["trace[1512268768] 'agreement among raft nodes before linearized reading' (duration: 224.87545ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:09.632406Z","caller":"traceutil/trace.go:171","msg":"trace[1057809685] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"244.579629ms","start":"2026-05-10T00:56:09.387804Z","end":"2026-05-10T00:56:09.632384Z","steps":["trace[1057809685] 'process raft request' (duration: 244.410905ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:09.632942Z","caller":"traceutil/trace.go:171","msg":"trace[324709323] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"245.010888ms","start":"2026-05-10T00:56:09.387909Z","end":"2026-05-10T00:56:09.63292Z","steps":["trace[324709323] 'process raft request' (duration: 244.440785ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:09.633227Z","caller":"traceutil/trace.go:171","msg":"trace[203006982] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"245.247763ms","start":"2026-05-10T00:56:09.38796Z","end":"2026-05-10T00:56:09.633208Z","steps":["trace[203006982] 'process raft request' (duration: 245.061999ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:09.633511Z","caller":"traceutil/trace.go:171","msg":"trace[1739654002] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"244.791964ms","start":"2026-05-10T00:56:09.38871Z","end":"2026-05-10T00:56:09.633502Z","steps":["trace[1739654002] 'process raft request' (duration: 244.461506ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:09.633572Z","caller":"traceutil/trace.go:171","msg":"trace[2020631980] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"241.310877ms","start":"2026-05-10T00:56:09.392248Z","end":"2026-05-10T00:56:09.633559Z","steps":["trace[2020631980] 'process raft request' (duration: 241.274145ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:09.633638Z","caller":"traceutil/trace.go:171","msg":"trace[186549398] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"243.203918ms","start":"2026-05-10T00:56:09.390427Z","end":"2026-05-10T00:56:09.633631Z","steps":["trace[186549398] 'process raft request' (duration: 242.941062ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:32.128998Z","caller":"traceutil/trace.go:171","msg":"trace[126513933] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"142.97944ms","start":"2026-05-10T00:56:31.985992Z","end":"2026-05-10T00:56:32.128972Z","steps":["trace[126513933] 'process raft request' (duration: 142.837487ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:32.129428Z","caller":"traceutil/trace.go:171","msg":"trace[652888207] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"139.151515ms","start":"2026-05-10T00:56:31.990228Z","end":"2026-05-10T00:56:32.12938Z","steps":["trace[652888207] 'process raft request' (duration: 138.706895ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:32.130139Z","caller":"traceutil/trace.go:171","msg":"trace[1562077148] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"138.237613ms","start":"2026-05-10T00:56:31.99132Z","end":"2026-05-10T00:56:32.129557Z","steps":["trace[1562077148] 'process raft request' (duration: 137.854485ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:59:06.901712Z","caller":"traceutil/trace.go:171","msg":"trace[363683211] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"103.505392ms","start":"2026-05-10T00:59:06.798179Z","end":"2026-05-10T00:59:06.901685Z","steps":["trace[363683211] 'process raft request' (duration: 103.41182ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:59:06.902036Z","caller":"traceutil/trace.go:171","msg":"trace[1500746085] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"123.752304ms","start":"2026-05-10T00:59:06.778267Z","end":"2026-05-10T00:59:06.902019Z","steps":["trace[1500746085] 'process raft request' (duration: 81.960894ms)","trace[1500746085] 'compare' (duration: 41.225406ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:59:18.167789Z","caller":"traceutil/trace.go:171","msg":"trace[910553488] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"103.627095ms","start":"2026-05-10T00:59:18.064135Z","end":"2026-05-10T00:59:18.167762Z","steps":["trace[910553488] 'process raft request' (duration: 103.205246ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:59:18.236409Z","caller":"traceutil/trace.go:171","msg":"trace[1893282171] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"120.990882ms","start":"2026-05-10T00:59:18.115393Z","end":"2026-05-10T00:59:18.236384Z","steps":["trace[1893282171] 'process raft request' (duration: 120.785808ms)"],"step_count":1}