{"level":"warn","ts":"2026-05-21T14:56:17.399517Z","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-21T14:56:17.399674Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-21T14:56:17.399778Z","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-21T14:56:17.399798Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-21T14:56:17.399851Z","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-21T14:56:17.401054Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-05-21T14:56:17.401186Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-21T14:56:17.405649Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.11304ms"} {"level":"info","ts":"2026-05-21T14:56:17.41384Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-05-21T14:56:17.413926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T14:56:17.413951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-05-21T14:56:17.413967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T14:56:17.413983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-05-21T14:56:17.414036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-05-21T14:56:17.426055Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T14:56:17.428361Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T14:56:17.43004Z","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-21T14:56:17.432745Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T14:56:17.433316Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T14:56:17.433421Z","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-21T14:56:17.433647Z","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-21T14:56:17.433672Z","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-21T14:56:17.434426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-05-21T14:56:17.435485Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-21T14:56:17.439239Z","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-21T14:56:17.439474Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T14:56:17.439524Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T14:56:17.439649Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-21T14:56:17.439684Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-21T14:56:17.614915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T14:56:17.61508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T14:56:17.615151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-05-21T14:56:17.615208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-05-21T14:56:17.61525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-21T14:56:17.615305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-05-21T14:56:17.615345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-21T14:56:17.616203Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T14:56:17.617158Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T14:56:17.617278Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T14:56:17.617334Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T14:56:17.617779Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T14:56:17.617843Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T14:56:17.618813Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T14:56:17.61897Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T14:56:17.619042Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T14:56:17.621542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T14:56:17.622801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"warn","ts":"2026-05-21T14:57:02.087136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.533578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T14:57:02.087223Z","caller":"traceutil/trace.go:171","msg":"trace[844520102] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"360.729253ms","start":"2026-05-21T14:57:01.726482Z","end":"2026-05-21T14:57:02.087212Z","steps":["trace[844520102] 'read index received' (duration: 239.768876ms)","trace[844520102] 'applied index is now lower than readState.Index' (duration: 120.959517ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:57:02.087283Z","caller":"traceutil/trace.go:171","msg":"trace[122239691] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"371.435899ms","start":"2026-05-21T14:57:01.715821Z","end":"2026-05-21T14:57:02.087257Z","steps":["trace[122239691] 'process raft request' (duration: 250.38187ms)","trace[122239691] 'compare' (duration: 120.382505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:57:02.087339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.856226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:57:02.08736Z","caller":"traceutil/trace.go:171","msg":"trace[1495185908] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:627; }","duration":"360.934898ms","start":"2026-05-21T14:57:01.726419Z","end":"2026-05-21T14:57:02.087354Z","steps":["trace[1495185908] 'agreement among raft nodes before linearized reading' (duration: 360.898868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:02.08738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:57:01.726399Z","time spent":"360.976459ms","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-21T14:57:02.087405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:57:01.7158Z","time spent":"371.543912ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:57:02.087551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.083133ms","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-21T14:57:02.087571Z","caller":"traceutil/trace.go:171","msg":"trace[1751050023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"198.122884ms","start":"2026-05-21T14:57:01.889443Z","end":"2026-05-21T14:57:02.087566Z","steps":["trace[1751050023] 'agreement among raft nodes before linearized reading' (duration: 198.052583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:02.831061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.204701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-21T14:57:02.831141Z","caller":"traceutil/trace.go:171","msg":"trace[344206787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"594.329033ms","start":"2026-05-21T14:57:02.236788Z","end":"2026-05-21T14:57:02.831117Z","steps":["trace[344206787] 'range keys from in-memory index tree' (duration: 594.057407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:02.831768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:57:02.236767Z","time spent":"594.987028ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T14:57:02.866746Z","caller":"traceutil/trace.go:171","msg":"trace[1583519256] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"171.922374ms","start":"2026-05-21T14:57:02.6948Z","end":"2026-05-21T14:57:02.866722Z","steps":["trace[1583519256] 'read index received' (duration: 171.764011ms)","trace[1583519256] 'applied index is now lower than readState.Index' (duration: 157.263µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:57:02.866806Z","caller":"traceutil/trace.go:171","msg":"trace[1255156264] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"357.493071ms","start":"2026-05-21T14:57:02.509283Z","end":"2026-05-21T14:57:02.866776Z","steps":["trace[1255156264] 'process raft request' (duration: 356.759615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:02.866968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.12231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:57:02.867078Z","caller":"traceutil/trace.go:171","msg":"trace[290080376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"172.296754ms","start":"2026-05-21T14:57:02.694751Z","end":"2026-05-21T14:57:02.867047Z","steps":["trace[290080376] 'agreement among raft nodes before linearized reading' (duration: 172.066438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:02.867259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:57:02.509244Z","time spent":"357.784838ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T14:57:18.051664Z","caller":"traceutil/trace.go:171","msg":"trace[1002175244] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"263.776558ms","start":"2026-05-21T14:57:17.787857Z","end":"2026-05-21T14:57:18.051634Z","steps":["trace[1002175244] 'process raft request' (duration: 263.553903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:53.193719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496194232618662,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T14:57:53.57253Z","caller":"traceutil/trace.go:171","msg":"trace[1065718865] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"879.885238ms","start":"2026-05-21T14:57:52.692611Z","end":"2026-05-21T14:57:53.572497Z","steps":["trace[1065718865] 'read index received' (duration: 879.662163ms)","trace[1065718865] 'applied index is now lower than readState.Index' (duration: 221.955µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:57:53.572872Z","caller":"traceutil/trace.go:171","msg":"trace[59325751] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"985.258085ms","start":"2026-05-21T14:57:52.587596Z","end":"2026-05-21T14:57:53.572854Z","steps":["trace[59325751] 'process raft request' (duration: 984.730523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:53.57299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:57:52.587561Z","time spent":"985.346017ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:57:53.573213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.607525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:57:53.57325Z","caller":"traceutil/trace.go:171","msg":"trace[1678038251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"880.640575ms","start":"2026-05-21T14:57:52.692596Z","end":"2026-05-21T14:57:53.573237Z","steps":["trace[1678038251] 'agreement among raft nodes before linearized reading' (duration: 880.585724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:53.573286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:57:52.692528Z","time spent":"880.750157ms","remote":"127.0.0.1:52168","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-21T14:57:53.57362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.064646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-21T14:57:53.573684Z","caller":"traceutil/trace.go:171","msg":"trace[1359241648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"442.17572ms","start":"2026-05-21T14:57:53.131492Z","end":"2026-05-21T14:57:53.573667Z","steps":["trace[1359241648] 'agreement among raft nodes before linearized reading' (duration: 442.002856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:57:53.573756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:57:53.131456Z","time spent":"442.288662ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T14:57:53.813928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.036393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:57:53.814015Z","caller":"traceutil/trace.go:171","msg":"trace[1300831274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"119.180597ms","start":"2026-05-21T14:57:53.694815Z","end":"2026-05-21T14:57:53.813996Z","steps":["trace[1300831274] 'range keys from in-memory index tree' (duration: 118.921011ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:58:18.680746Z","caller":"traceutil/trace.go:171","msg":"trace[1261313612] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"227.463728ms","start":"2026-05-21T14:58:18.453254Z","end":"2026-05-21T14:58:18.680718Z","steps":["trace[1261313612] 'read index received' (duration: 227.214772ms)","trace[1261313612] 'applied index is now lower than readState.Index' (duration: 247.496µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:58:18.680864Z","caller":"traceutil/trace.go:171","msg":"trace[124918586] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"290.422501ms","start":"2026-05-21T14:58:18.390398Z","end":"2026-05-21T14:58:18.680821Z","steps":["trace[124918586] 'process raft request' (duration: 290.112174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:18.681157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.326251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T14:58:18.681193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.499856ms","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-21T14:58:18.681301Z","caller":"traceutil/trace.go:171","msg":"trace[555150382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"128.652809ms","start":"2026-05-21T14:58:18.552625Z","end":"2026-05-21T14:58:18.681278Z","steps":["trace[555150382] 'agreement among raft nodes before linearized reading' (duration: 128.326321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:18.681222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.93983ms","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-21T14:58:18.681489Z","caller":"traceutil/trace.go:171","msg":"trace[1611435592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"228.275137ms","start":"2026-05-21T14:58:18.453202Z","end":"2026-05-21T14:58:18.681477Z","steps":["trace[1611435592] 'agreement among raft nodes before linearized reading' (duration: 227.628653ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:58:18.681259Z","caller":"traceutil/trace.go:171","msg":"trace[22822691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"136.489215ms","start":"2026-05-21T14:58:18.544754Z","end":"2026-05-21T14:58:18.681243Z","steps":["trace[22822691] 'agreement among raft nodes before linearized reading' (duration: 136.135967ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:58:18.928239Z","caller":"traceutil/trace.go:171","msg":"trace[1396470532] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"243.088519ms","start":"2026-05-21T14:58:18.685112Z","end":"2026-05-21T14:58:18.9282Z","steps":["trace[1396470532] 'process raft request' (duration: 175.020031ms)","trace[1396470532] 'compare' (duration: 67.408564ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:58:18.928349Z","caller":"traceutil/trace.go:171","msg":"trace[1425690286] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"241.639496ms","start":"2026-05-21T14:58:18.68668Z","end":"2026-05-21T14:58:18.92832Z","steps":["trace[1425690286] 'process raft request' (duration: 241.276418ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:58:18.92859Z","caller":"traceutil/trace.go:171","msg":"trace[430133716] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"241.177836ms","start":"2026-05-21T14:58:18.687378Z","end":"2026-05-21T14:58:18.928556Z","steps":["trace[430133716] 'process raft request' (duration: 240.673005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:20.092216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.389234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T14:58:20.092377Z","caller":"traceutil/trace.go:171","msg":"trace[1145649384] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"349.260461ms","start":"2026-05-21T14:58:19.743078Z","end":"2026-05-21T14:58:20.092338Z","steps":["trace[1145649384] 'process raft request' (duration: 52.476858ms)","trace[1145649384] 'compare' (duration: 295.926534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:58:20.092456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:19.743048Z","time spent":"349.373485ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:58:20.993706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.630574ms","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-21T14:58:20.993879Z","caller":"traceutil/trace.go:171","msg":"trace[2033485168] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1104; }","duration":"102.818148ms","start":"2026-05-21T14:58:20.891035Z","end":"2026-05-21T14:58:20.993853Z","steps":["trace[2033485168] 'range keys from in-memory index tree' (duration: 102.584123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:36.8585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.880629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:58:36.858602Z","caller":"traceutil/trace.go:171","msg":"trace[1363329107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"164.029423ms","start":"2026-05-21T14:58:36.694549Z","end":"2026-05-21T14:58:36.858578Z","steps":["trace[1363329107] 'range keys from in-memory index tree' (duration: 163.772877ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:58:37.197289Z","caller":"traceutil/trace.go:171","msg":"trace[1974333240] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"334.819318ms","start":"2026-05-21T14:58:36.862446Z","end":"2026-05-21T14:58:37.197266Z","steps":["trace[1974333240] 'process raft request' (duration: 334.666105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:37.197412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:36.862427Z","time spent":"334.92253ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T14:58:53.564698Z","caller":"traceutil/trace.go:171","msg":"trace[906501777] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"217.860602ms","start":"2026-05-21T14:58:53.346817Z","end":"2026-05-21T14:58:53.564678Z","steps":["trace[906501777] 'read index received' (duration: 191.119631ms)","trace[906501777] 'applied index is now lower than readState.Index' (duration: 26.739921ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:58:53.564784Z","caller":"traceutil/trace.go:171","msg":"trace[656589549] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"283.995707ms","start":"2026-05-21T14:58:53.280763Z","end":"2026-05-21T14:58:53.564759Z","steps":["trace[656589549] 'process raft request' (duration: 257.250987ms)","trace[656589549] 'compare' (duration: 26.391492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:58:53.564861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.387016ms","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-21T14:58:53.564884Z","caller":"traceutil/trace.go:171","msg":"trace[293631808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1284; }","duration":"284.451527ms","start":"2026-05-21T14:58:53.280426Z","end":"2026-05-21T14:58:53.564877Z","steps":["trace[293631808] 'agreement among raft nodes before linearized reading' (duration: 284.331864ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:58:53.598589Z","caller":"traceutil/trace.go:171","msg":"trace[1491429861] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"247.789214ms","start":"2026-05-21T14:58:53.350775Z","end":"2026-05-21T14:58:53.598564Z","steps":["trace[1491429861] 'process raft request' (duration: 247.668211ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:58:54.690471Z","caller":"traceutil/trace.go:171","msg":"trace[435858888] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"403.129133ms","start":"2026-05-21T14:58:54.287317Z","end":"2026-05-21T14:58:54.690446Z","steps":["trace[435858888] 'process raft request' (duration: 402.975579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:54.690644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:54.287291Z","time spent":"403.250575ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:58:55.600771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.55658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T14:58:55.600894Z","caller":"traceutil/trace.go:171","msg":"trace[1627214517] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"248.421119ms","start":"2026-05-21T14:58:55.352456Z","end":"2026-05-21T14:58:55.600877Z","steps":["trace[1627214517] 'read index received' (duration: 23.648694ms)","trace[1627214517] 'applied index is now lower than readState.Index' (duration: 224.771225ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:58:55.600963Z","caller":"traceutil/trace.go:171","msg":"trace[672299196] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"480.437698ms","start":"2026-05-21T14:58:55.120478Z","end":"2026-05-21T14:58:55.600916Z","steps":["trace[672299196] 'process raft request' (duration: 255.669923ms)","trace[672299196] 'compare' (duration: 224.08377ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:58:55.601082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.620033ms","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-21T14:58:55.601116Z","caller":"traceutil/trace.go:171","msg":"trace[100999677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1291; }","duration":"248.689865ms","start":"2026-05-21T14:58:55.352415Z","end":"2026-05-21T14:58:55.601105Z","steps":["trace[100999677] 'agreement among raft nodes before linearized reading' (duration: 248.529551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:55.601205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.120455Z","time spent":"480.649183ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:58:55.63018Z","caller":"traceutil/trace.go:171","msg":"trace[841422642] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"251.471068ms","start":"2026-05-21T14:58:55.378684Z","end":"2026-05-21T14:58:55.630155Z","steps":["trace[841422642] 'process raft request' (duration: 251.329614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.121029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.499987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T14:58:56.121208Z","caller":"traceutil/trace.go:171","msg":"trace[766030420] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"515.013808ms","start":"2026-05-21T14:58:55.606173Z","end":"2026-05-21T14:58:56.121186Z","steps":["trace[766030420] 'process raft request' (duration: 186.279086ms)","trace[766030420] 'compare' (duration: 328.257261ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:58:56.12126Z","caller":"traceutil/trace.go:171","msg":"trace[790597908] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1351; }","duration":"491.023457ms","start":"2026-05-21T14:58:55.630219Z","end":"2026-05-21T14:58:56.121242Z","steps":["trace[790597908] 'read index received' (duration: 162.238433ms)","trace[790597908] 'applied index is now lower than readState.Index' (duration: 328.783874ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:58:56.121298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.606155Z","time spent":"515.09707ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:58:56.121416Z","caller":"traceutil/trace.go:171","msg":"trace[2033881514] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"514.993067ms","start":"2026-05-21T14:58:55.606417Z","end":"2026-05-21T14:58:56.12141Z","steps":["trace[2033881514] 'process raft request' (duration: 514.721351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.121463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.606404Z","time spent":"515.027988ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:58:56.121523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.730587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-21T14:58:56.121571Z","caller":"traceutil/trace.go:171","msg":"trace[1006962482] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1294; }","duration":"516.801229ms","start":"2026-05-21T14:58:55.604755Z","end":"2026-05-21T14:58:56.121557Z","steps":["trace[1006962482] 'agreement among raft nodes before linearized reading' (duration: 516.638825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.121604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.604695Z","time spent":"516.90198ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T14:58:56.121688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.559318ms","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-21T14:58:56.121763Z","caller":"traceutil/trace.go:171","msg":"trace[97277547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1294; }","duration":"518.65855ms","start":"2026-05-21T14:58:55.603088Z","end":"2026-05-21T14:58:56.121746Z","steps":["trace[97277547] 'agreement among raft nodes before linearized reading' (duration: 518.447135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.121808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.603075Z","time spent":"518.724871ms","remote":"127.0.0.1:52442","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-21T14:58:56.149038Z","caller":"traceutil/trace.go:171","msg":"trace[1264158073] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"514.857334ms","start":"2026-05-21T14:58:55.634167Z","end":"2026-05-21T14:58:56.149025Z","steps":["trace[1264158073] 'process raft request' (duration: 514.799483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.149128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.634157Z","time spent":"514.921046ms","remote":"127.0.0.1:52442","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-21T14:58:56.149106Z","caller":"traceutil/trace.go:171","msg":"trace[115379230] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"515.070809ms","start":"2026-05-21T14:58:55.633972Z","end":"2026-05-21T14:58:56.149043Z","steps":["trace[115379230] 'process raft request' (duration: 514.922756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.149268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.633952Z","time spent":"515.239883ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T14:58:56.161438Z","caller":"traceutil/trace.go:171","msg":"trace[1167079170] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"337.807738ms","start":"2026-05-21T14:58:55.823612Z","end":"2026-05-21T14:58:56.16142Z","steps":["trace[1167079170] 'process raft request' (duration: 337.614933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.161537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.82359Z","time spent":"337.891259ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:58:56.187309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.401753ms","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-21T14:58:56.187351Z","caller":"traceutil/trace.go:171","msg":"trace[846478390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"545.469434ms","start":"2026-05-21T14:58:55.641872Z","end":"2026-05-21T14:58:56.187341Z","steps":["trace[846478390] 'agreement among raft nodes before linearized reading' (duration: 545.355242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.187368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.641865Z","time spent":"545.499965ms","remote":"127.0.0.1:52442","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-21T14:58:56.187574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.514378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T14:58:56.187595Z","caller":"traceutil/trace.go:171","msg":"trace[1831923520] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1299; }","duration":"235.598549ms","start":"2026-05-21T14:58:55.95199Z","end":"2026-05-21T14:58:56.187588Z","steps":["trace[1831923520] 'agreement among raft nodes before linearized reading' (duration: 235.565608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.18767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.899766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:58:56.187685Z","caller":"traceutil/trace.go:171","msg":"trace[1336602971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"491.943946ms","start":"2026-05-21T14:58:55.695737Z","end":"2026-05-21T14:58:56.187681Z","steps":["trace[1336602971] 'agreement among raft nodes before linearized reading' (duration: 491.920005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:58:56.187698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:58:55.695721Z","time spent":"491.974757ms","remote":"127.0.0.1:52168","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-21T14:59:06.55044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.432311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T14:59:06.550561Z","caller":"traceutil/trace.go:171","msg":"trace[887225732] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1429; }","duration":"410.253113ms","start":"2026-05-21T14:59:06.140287Z","end":"2026-05-21T14:59:06.55054Z","steps":["trace[887225732] 'read index received' (duration: 215.883374ms)","trace[887225732] 'applied index is now lower than readState.Index' (duration: 194.367939ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:59:06.551212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.112287ms","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-21T14:59:06.551257Z","caller":"traceutil/trace.go:171","msg":"trace[744064357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1369; }","duration":"386.19115ms","start":"2026-05-21T14:59:06.165054Z","end":"2026-05-21T14:59:06.551245Z","steps":["trace[744064357] 'agreement among raft nodes before linearized reading' (duration: 385.934054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:06.551294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.165041Z","time spent":"386.2421ms","remote":"127.0.0.1:52442","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-21T14:59:06.551341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.105079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-21T14:59:06.551329Z","caller":"traceutil/trace.go:171","msg":"trace[1911968058] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"357.43063ms","start":"2026-05-21T14:59:06.193869Z","end":"2026-05-21T14:59:06.5513Z","steps":["trace[1911968058] 'process raft request' (duration: 357.012341ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:59:06.551368Z","caller":"traceutil/trace.go:171","msg":"trace[46007335] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1369; }","duration":"348.16078ms","start":"2026-05-21T14:59:06.203199Z","end":"2026-05-21T14:59:06.55136Z","steps":["trace[46007335] 'agreement among raft nodes before linearized reading' (duration: 348.079408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:06.551393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.203184Z","time spent":"348.204941ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T14:59:06.55138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.728354ms","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-21T14:59:06.551439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.71119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T14:59:06.55145Z","caller":"traceutil/trace.go:171","msg":"trace[1538033772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1369; }","duration":"321.835826ms","start":"2026-05-21T14:59:06.229597Z","end":"2026-05-21T14:59:06.551433Z","steps":["trace[1538033772] 'agreement among raft nodes before linearized reading' (duration: 321.631141ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:59:06.551465Z","caller":"traceutil/trace.go:171","msg":"trace[2014603019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1369; }","duration":"362.73721ms","start":"2026-05-21T14:59:06.18872Z","end":"2026-05-21T14:59:06.551457Z","steps":["trace[2014603019] 'agreement among raft nodes before linearized reading' (duration: 362.661439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:06.551444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.331318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T14:59:06.551474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.193851Z","time spent":"357.541993ms","remote":"127.0.0.1:52442","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-21T14:59:06.551497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.188663Z","time spent":"362.828242ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T14:59:06.551496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.229581Z","time spent":"321.906858ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T14:59:06.551515Z","caller":"traceutil/trace.go:171","msg":"trace[135903055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1369; }","duration":"326.451871ms","start":"2026-05-21T14:59:06.225046Z","end":"2026-05-21T14:59:06.551498Z","steps":["trace[135903055] 'agreement among raft nodes before linearized reading' (duration: 326.248487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:06.551556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.276235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-21T14:59:06.551554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.225026Z","time spent":"326.520162ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T14:59:06.55158Z","caller":"traceutil/trace.go:171","msg":"trace[224731795] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1368; }","duration":"411.332337ms","start":"2026-05-21T14:59:06.14024Z","end":"2026-05-21T14:59:06.551573Z","steps":["trace[224731795] 'agreement among raft nodes before linearized reading' (duration: 410.395846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:06.551599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.140224Z","time spent":"411.370527ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-21T14:59:07.391542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.754948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T14:59:07.391883Z","caller":"traceutil/trace.go:171","msg":"trace[1014433346] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1432; }","duration":"696.117166ms","start":"2026-05-21T14:59:06.695748Z","end":"2026-05-21T14:59:07.391865Z","steps":["trace[1014433346] 'read index received' (duration: 47.053672ms)","trace[1014433346] 'applied index is now lower than readState.Index' (duration: 649.062434ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T14:59:07.391974Z","caller":"traceutil/trace.go:171","msg":"trace[1823261637] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"750.213907ms","start":"2026-05-21T14:59:06.64175Z","end":"2026-05-21T14:59:07.391964Z","steps":["trace[1823261637] 'process raft request' (duration: 750.081264ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:59:07.391981Z","caller":"traceutil/trace.go:171","msg":"trace[1275679690] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"835.206236ms","start":"2026-05-21T14:59:06.55674Z","end":"2026-05-21T14:59:07.391947Z","steps":["trace[1275679690] 'process raft request' (duration: 834.981491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:07.392038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.641695Z","time spent":"750.30314ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T14:59:07.392032Z","caller":"traceutil/trace.go:171","msg":"trace[248361189] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"834.617073ms","start":"2026-05-21T14:59:06.55734Z","end":"2026-05-21T14:59:07.391957Z","steps":["trace[248361189] 'process raft request' (duration: 834.44959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:07.39218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.557332Z","time spent":"834.779657ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T14:59:07.392235Z","caller":"traceutil/trace.go:171","msg":"trace[221421963] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"835.660846ms","start":"2026-05-21T14:59:06.556551Z","end":"2026-05-21T14:59:07.392212Z","steps":["trace[221421963] 'process raft request' (duration: 186.137752ms)","trace[221421963] 'compare' (duration: 648.334768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T14:59:07.392476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.556526Z","time spent":"835.747938ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:59:07.392521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.766561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:59:07.392538Z","caller":"traceutil/trace.go:171","msg":"trace[1511107133] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"835.505743ms","start":"2026-05-21T14:59:06.55702Z","end":"2026-05-21T14:59:07.392526Z","steps":["trace[1511107133] 'process raft request' (duration: 834.737296ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:59:07.392619Z","caller":"traceutil/trace.go:171","msg":"trace[1977285319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"696.841312ms","start":"2026-05-21T14:59:06.695706Z","end":"2026-05-21T14:59:07.392548Z","steps":["trace[1977285319] 'agreement among raft nodes before linearized reading' (duration: 696.570686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:07.392663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.69569Z","time spent":"696.964476ms","remote":"127.0.0.1:52168","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-21T14:59:07.392728Z","caller":"traceutil/trace.go:171","msg":"trace[510746950] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"836.047775ms","start":"2026-05-21T14:59:06.556671Z","end":"2026-05-21T14:59:07.392718Z","steps":["trace[510746950] 'process raft request' (duration: 834.966961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:07.392776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.556659Z","time spent":"836.095176ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:59:07.392621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.557009Z","time spent":"835.557454ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T14:59:07.392947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.908729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T14:59:07.392962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.438746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T14:59:07.392987Z","caller":"traceutil/trace.go:171","msg":"trace[1484102396] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1376; }","duration":"221.988621ms","start":"2026-05-21T14:59:07.170989Z","end":"2026-05-21T14:59:07.392978Z","steps":["trace[1484102396] 'agreement among raft nodes before linearized reading' (duration: 221.919729ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T14:59:07.392994Z","caller":"traceutil/trace.go:171","msg":"trace[2099092732] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1376; }","duration":"348.514208ms","start":"2026-05-21T14:59:07.044471Z","end":"2026-05-21T14:59:07.392985Z","steps":["trace[2099092732] 'agreement among raft nodes before linearized reading' (duration: 348.461787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:07.393026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:07.044444Z","time spent":"348.572259ms","remote":"127.0.0.1:52426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-21T14:59:07.392164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.556724Z","time spent":"835.34195ms","remote":"127.0.0.1:52442","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-21T14:59:07.39368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.659927ms","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-21T14:59:07.393713Z","caller":"traceutil/trace.go:171","msg":"trace[1112303229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"614.726839ms","start":"2026-05-21T14:59:06.778978Z","end":"2026-05-21T14:59:07.393705Z","steps":["trace[1112303229] 'agreement among raft nodes before linearized reading' (duration: 613.851409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T14:59:07.393735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T14:59:06.778961Z","time spent":"614.7677ms","remote":"127.0.0.1:52442","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\" "}