{"level":"warn","ts":"2026-04-20T09:34:25.66071Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T09:34:25.660821Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.176:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.176:2380","--initial-cluster=instance=https://199.19.213.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.176: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-04-20T09:34:25.660929Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T09:34:25.660943Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-04-20T09:34:25.660969Z","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-04-20T09:34:25.661724Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"]} {"level":"info","ts":"2026-04-20T09:34:25.661822Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.176: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-04-20T09:34:25.665766Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.696984ms"} {"level":"info","ts":"2026-04-20T09:34:25.670898Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e120e57916b9bd4","cluster-id":"e644555f29703ff6"} {"level":"info","ts":"2026-04-20T09:34:25.670981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T09:34:25.671011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 0"} {"level":"info","ts":"2026-04-20T09:34:25.671036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e120e57916b9bd4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T09:34:25.671053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 1"} {"level":"info","ts":"2026-04-20T09:34:25.671106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"warn","ts":"2026-04-20T09:34:25.679806Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T09:34:25.681391Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T09:34:25.682839Z","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-04-20T09:34:25.687748Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e120e57916b9bd4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T09:34:25.687945Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e120e57916b9bd4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T09:34:25.688093Z","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-04-20T09:34:25.688158Z","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-04-20T09:34:25.688185Z","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-04-20T09:34:25.690933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"info","ts":"2026-04-20T09:34:25.691219Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","added-peer-id":"6e120e57916b9bd4","added-peer-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-04-20T09:34:25.693701Z","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-04-20T09:34:25.693927Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e120e57916b9bd4","initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T09:34:25.693957Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T09:34:25.694023Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-04-20T09:34:25.694034Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-04-20T09:34:26.171371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T09:34:26.171446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T09:34:26.171763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgPreVoteResp from 6e120e57916b9bd4 at term 1"} {"level":"info","ts":"2026-04-20T09:34:26.171882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became candidate at term 2"} {"level":"info","ts":"2026-04-20T09:34:26.172095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgVoteResp from 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-04-20T09:34:26.17226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became leader at term 2"} {"level":"info","ts":"2026-04-20T09:34:26.172293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e120e57916b9bd4 elected leader 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-04-20T09:34:26.208112Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:34:26.210317Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e120e57916b9bd4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.176:2379]}","request-path":"/0/members/6e120e57916b9bd4/attributes","cluster-id":"e644555f29703ff6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T09:34:26.210342Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T09:34:26.210477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T09:34:26.210674Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T09:34:26.21075Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T09:34:26.211259Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:34:26.211776Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:34:26.211873Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:34:26.214976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.176:2379"} {"level":"info","ts":"2026-04-20T09:34:26.21591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T09:34:36.498238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.227722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-04-20T09:34:36.498301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.381402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-20T09:34:36.498354Z","caller":"traceutil/trace.go:171","msg":"trace[888986307] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:304; }","duration":"324.464126ms","start":"2026-04-20T09:34:36.173879Z","end":"2026-04-20T09:34:36.498343Z","steps":["trace[888986307] 'range keys from in-memory index tree' (duration: 324.169282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:36.49837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.946738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-20T09:34:36.498353Z","caller":"traceutil/trace.go:171","msg":"trace[342761822] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:304; }","duration":"425.360518ms","start":"2026-04-20T09:34:36.072976Z","end":"2026-04-20T09:34:36.498336Z","steps":["trace[342761822] 'range keys from in-memory index tree' (duration: 425.138258ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:34:36.498413Z","caller":"traceutil/trace.go:171","msg":"trace[1555217655] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:304; }","duration":"125.016062ms","start":"2026-04-20T09:34:36.37338Z","end":"2026-04-20T09:34:36.498396Z","steps":["trace[1555217655] 'range keys from in-memory index tree' (duration: 124.868274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:36.498419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:36.072927Z","time spent":"425.476535ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-04-20T09:34:36.49852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.798923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-20T09:34:36.498238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.325545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-20T09:34:36.498568Z","caller":"traceutil/trace.go:171","msg":"trace[34756849] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:304; }","duration":"374.883517ms","start":"2026-04-20T09:34:36.123673Z","end":"2026-04-20T09:34:36.498557Z","steps":["trace[34756849] 'range keys from in-memory index tree' (duration: 374.386761ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:34:36.49859Z","caller":"traceutil/trace.go:171","msg":"trace[1581282257] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:304; }","duration":"174.738586ms","start":"2026-04-20T09:34:36.323839Z","end":"2026-04-20T09:34:36.498578Z","steps":["trace[1581282257] 'range keys from in-memory index tree' (duration: 174.181759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:36.498604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:36.123627Z","time spent":"374.968582ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-04-20T09:34:36.498397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:36.173861Z","time spent":"324.526831ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-04-20T09:34:36.498452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.678112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-20T09:34:36.498728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.225027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-20T09:34:36.498779Z","caller":"traceutil/trace.go:171","msg":"trace[166903456] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:304; }","duration":"225.031108ms","start":"2026-04-20T09:34:36.273735Z","end":"2026-04-20T09:34:36.498766Z","steps":["trace[166903456] 'range keys from in-memory index tree' (duration: 224.616468ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:34:36.498948Z","caller":"traceutil/trace.go:171","msg":"trace[852166177] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:304; }","duration":"275.48728ms","start":"2026-04-20T09:34:36.223379Z","end":"2026-04-20T09:34:36.498866Z","steps":["trace[852166177] 'range keys from in-memory index tree' (duration: 275.092541ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:34:40.182014Z","caller":"traceutil/trace.go:171","msg":"trace[840543187] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"242.062531ms","start":"2026-04-20T09:34:39.939893Z","end":"2026-04-20T09:34:40.181955Z","steps":["trace[840543187] 'process raft request' (duration: 178.928635ms)","trace[840543187] 'compare' (duration: 62.563111ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:34:40.39861Z","caller":"traceutil/trace.go:171","msg":"trace[31833143] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"208.207412ms","start":"2026-04-20T09:34:40.190366Z","end":"2026-04-20T09:34:40.398573Z","steps":["trace[31833143] 'process raft request' (duration: 197.271073ms)","trace[31833143] 'compare' (duration: 10.835685ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:34:41.138487Z","caller":"traceutil/trace.go:171","msg":"trace[1051643503] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"174.651993ms","start":"2026-04-20T09:34:40.963814Z","end":"2026-04-20T09:34:41.138466Z","steps":["trace[1051643503] 'process raft request' (duration: 174.539238ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:34:41.146587Z","caller":"traceutil/trace.go:171","msg":"trace[1265469965] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"164.934595ms","start":"2026-04-20T09:34:40.981627Z","end":"2026-04-20T09:34:41.146562Z","steps":["trace[1265469965] 'process raft request' (duration: 164.720885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:42.352046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773125447191135,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:34:42.744028Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.189954057s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T09:34:42.744418Z","caller":"traceutil/trace.go:171","msg":"trace[1431390224] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"892.468264ms","start":"2026-04-20T09:34:41.85193Z","end":"2026-04-20T09:34:42.744399Z","steps":["trace[1431390224] 'read index received' (duration: 892.240835ms)","trace[1431390224] 'applied index is now lower than readState.Index' (duration: 226.889µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:34:42.744448Z","caller":"traceutil/trace.go:171","msg":"trace[1847230963] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.190476727s","start":"2026-04-20T09:34:41.553951Z","end":"2026-04-20T09:34:42.744427Z","steps":["trace[1847230963] 'process raft request' (duration: 1.190309561s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:42.744506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.564668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:34:42.744552Z","caller":"traceutil/trace.go:171","msg":"trace[1423024107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"892.63105ms","start":"2026-04-20T09:34:41.851892Z","end":"2026-04-20T09:34:42.744523Z","steps":["trace[1423024107] 'agreement among raft nodes before linearized reading' (duration: 892.569068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:42.744575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:41.851874Z","time spent":"892.696073ms","remote":"127.0.0.1:58740","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-04-20T09:34:42.744656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.516593ms","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-04-20T09:34:42.744692Z","caller":"traceutil/trace.go:171","msg":"trace[767904318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"557.594015ms","start":"2026-04-20T09:34:42.187089Z","end":"2026-04-20T09:34:42.744683Z","steps":["trace[767904318] 'agreement among raft nodes before linearized reading' (duration: 557.514392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:42.744673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.892231ms","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-04-20T09:34:42.744768Z","caller":"traceutil/trace.go:171","msg":"trace[2075505686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"811.065629ms","start":"2026-04-20T09:34:41.933687Z","end":"2026-04-20T09:34:42.744753Z","steps":["trace[2075505686] 'agreement among raft nodes before linearized reading' (duration: 810.85051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:42.744824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:41.553929Z","time spent":"1.190560731s","remote":"127.0.0.1:59036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:34:42.744847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:41.933662Z","time spent":"811.171473ms","remote":"127.0.0.1:59036","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-04-20T09:34:42.744713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:42.187064Z","time spent":"557.643267ms","remote":"127.0.0.1:59036","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-04-20T09:34:44.328705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494540829s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:34:44.328778Z","caller":"traceutil/trace.go:171","msg":"trace[1676379981] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"1.473925051s","start":"2026-04-20T09:34:42.854841Z","end":"2026-04-20T09:34:44.328766Z","steps":["trace[1676379981] 'read index received' (duration: 52.212µs)","trace[1676379981] 'applied index is now lower than readState.Index' (duration: 1.473871729s)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:34:44.328828Z","caller":"traceutil/trace.go:171","msg":"trace[1863372528] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"1.578952774s","start":"2026-04-20T09:34:42.749867Z","end":"2026-04-20T09:34:44.32882Z","steps":["trace[1863372528] 'process raft request' (duration: 84.031111ms)","trace[1863372528] 'compare' (duration: 1.494274598s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:34:44.328865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:42.749844Z","time spent":"1.579000925s","remote":"127.0.0.1:59036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:34:44.32898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474139878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:34:44.329012Z","caller":"traceutil/trace.go:171","msg":"trace[1256555866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"1.4742016s","start":"2026-04-20T09:34:42.854801Z","end":"2026-04-20T09:34:44.329002Z","steps":["trace[1256555866] 'agreement among raft nodes before linearized reading' (duration: 1.474148518s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:44.329031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:42.854731Z","time spent":"1.474296124s","remote":"127.0.0.1:58740","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-04-20T09:34:44.829663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773125447191143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:34:45.89596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.037672ms","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-04-20T09:34:45.896144Z","caller":"traceutil/trace.go:171","msg":"trace[584942027] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:372; }","duration":"887.254811ms","start":"2026-04-20T09:34:45.008864Z","end":"2026-04-20T09:34:45.896119Z","steps":["trace[584942027] 'range keys from in-memory index tree' (duration: 887.019602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:46.3301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000600367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T09:34:46.330183Z","caller":"traceutil/trace.go:171","msg":"trace[2048931219] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000717651s","start":"2026-04-20T09:34:44.329447Z","end":"2026-04-20T09:34:46.330165Z","steps":["trace[2048931219] 'agreement among raft nodes before linearized reading' (duration: 2.000614958s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:46.33023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:44.329437Z","time spent":"2.000783103s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T09:34:46.347893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402642324s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T09:34:46.348007Z","caller":"traceutil/trace.go:171","msg":"trace[1393092574] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"2.01920031s","start":"2026-04-20T09:34:44.328794Z","end":"2026-04-20T09:34:46.347994Z","steps":["trace[1393092574] 'read index received' (duration: 616.485274ms)","trace[1393092574] 'applied index is now lower than readState.Index' (duration: 1.402713926s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:34:46.348105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.448383603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-04-20T09:34:46.348124Z","caller":"traceutil/trace.go:171","msg":"trace[859977660] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:372; }","duration":"3.448450356s","start":"2026-04-20T09:34:42.899666Z","end":"2026-04-20T09:34:46.348116Z","steps":["trace[859977660] 'agreement among raft nodes before linearized reading' (duration: 3.448386534s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:46.348151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:42.899627Z","time spent":"3.448516909s","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-20T09:34:46.348166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.185642136s","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-04-20T09:34:46.348245Z","caller":"traceutil/trace.go:171","msg":"trace[1104465465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:372; }","duration":"3.185818733s","start":"2026-04-20T09:34:43.162404Z","end":"2026-04-20T09:34:46.348223Z","steps":["trace[1104465465] 'agreement among raft nodes before linearized reading' (duration: 3.185634056s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:46.3483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:43.16238Z","time spent":"3.185907176s","remote":"127.0.0.1:59036","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-04-20T09:34:46.355824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584379461s","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-04-20T09:34:46.355887Z","caller":"traceutil/trace.go:171","msg":"trace[19150648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"1.584472955s","start":"2026-04-20T09:34:44.771399Z","end":"2026-04-20T09:34:46.355872Z","steps":["trace[19150648] 'agreement among raft nodes before linearized reading' (duration: 1.58435893s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:46.355902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584411323s","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-04-20T09:34:46.355923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:44.771381Z","time spent":"1.584533717s","remote":"127.0.0.1:59036","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-04-20T09:34:46.35597Z","caller":"traceutil/trace.go:171","msg":"trace[781520208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"1.584501575s","start":"2026-04-20T09:34:44.771449Z","end":"2026-04-20T09:34:46.35595Z","steps":["trace[781520208] 'agreement among raft nodes before linearized reading' (duration: 1.58436432s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:46.356006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:44.771443Z","time spent":"1.584555568s","remote":"127.0.0.1:59036","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-04-20T09:34:47.027117Z","caller":"traceutil/trace.go:171","msg":"trace[1861122584] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"666.451277ms","start":"2026-04-20T09:34:46.360626Z","end":"2026-04-20T09:34:47.027077Z","steps":["trace[1861122584] 'process raft request' (duration: 666.187619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:47.027341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:46.360599Z","time spent":"666.631613ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:34:47.027354Z","caller":"traceutil/trace.go:171","msg":"trace[2103022060] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"666.672005ms","start":"2026-04-20T09:34:46.360665Z","end":"2026-04-20T09:34:47.027337Z","steps":["trace[2103022060] 'process raft request' (duration: 666.343704ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:34:47.027496Z","caller":"traceutil/trace.go:171","msg":"trace[1155491546] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"666.82094ms","start":"2026-04-20T09:34:46.360666Z","end":"2026-04-20T09:34:47.027487Z","steps":["trace[1155491546] 'process raft request' (duration: 666.591793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:47.027553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:46.360647Z","time spent":"666.881512ms","remote":"127.0.0.1:59036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:34:47.027726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:46.360653Z","time spent":"666.903702ms","remote":"127.0.0.1:59036","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-04-20T09:34:47.027447Z","caller":"traceutil/trace.go:171","msg":"trace[1272613615] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"666.458557ms","start":"2026-04-20T09:34:46.360966Z","end":"2026-04-20T09:34:47.027425Z","steps":["trace[1272613615] 'process raft request' (duration: 666.384105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:47.027944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:34:46.360955Z","time spent":"666.959252ms","remote":"127.0.0.1:59036","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-04-20T09:34:51.290738Z","caller":"traceutil/trace.go:171","msg":"trace[577519799] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"248.734622ms","start":"2026-04-20T09:34:51.041973Z","end":"2026-04-20T09:34:51.290707Z","steps":["trace[577519799] 'process raft request' (duration: 246.859191ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:34:51.290986Z","caller":"traceutil/trace.go:171","msg":"trace[1442496884] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"248.236368ms","start":"2026-04-20T09:34:51.04272Z","end":"2026-04-20T09:34:51.290956Z","steps":["trace[1442496884] 'process raft request' (duration: 247.942949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:03.837753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773125447191425,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T09:35:04.045273Z","caller":"traceutil/trace.go:171","msg":"trace[1450091035] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"752.930812ms","start":"2026-04-20T09:35:03.292313Z","end":"2026-04-20T09:35:04.045244Z","steps":["trace[1450091035] 'process raft request' (duration: 749.900288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:04.045532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:03.292289Z","time spent":"753.154364ms","remote":"127.0.0.1:58824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:35:04.050918Z","caller":"traceutil/trace.go:171","msg":"trace[1487928713] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"713.601302ms","start":"2026-04-20T09:35:03.33729Z","end":"2026-04-20T09:35:04.050891Z","steps":["trace[1487928713] 'read index received' (duration: 704.514171ms)","trace[1487928713] 'applied index is now lower than readState.Index' (duration: 9.086141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:35:04.05114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.833535ms","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-04-20T09:35:04.0512Z","caller":"traceutil/trace.go:171","msg":"trace[418491582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"713.926046ms","start":"2026-04-20T09:35:03.337263Z","end":"2026-04-20T09:35:04.051189Z","steps":["trace[418491582] 'agreement among raft nodes before linearized reading' (duration: 713.781875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:04.051231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:03.337249Z","time spent":"713.975308ms","remote":"127.0.0.1:59036","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-04-20T09:35:04.051199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.629503ms","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-04-20T09:35:04.051324Z","caller":"traceutil/trace.go:171","msg":"trace[724175616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"709.775044ms","start":"2026-04-20T09:35:03.341537Z","end":"2026-04-20T09:35:04.051312Z","steps":["trace[724175616] 'agreement among raft nodes before linearized reading' (duration: 709.529022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:04.051355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:03.341527Z","time spent":"709.818845ms","remote":"127.0.0.1:59036","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-04-20T09:35:04.051544Z","caller":"traceutil/trace.go:171","msg":"trace[1360426664] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"706.93381ms","start":"2026-04-20T09:35:03.344462Z","end":"2026-04-20T09:35:04.051396Z","steps":["trace[1360426664] 'process raft request' (duration: 706.159081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:04.051807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:03.344447Z","time spent":"707.223653ms","remote":"127.0.0.1:58824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:35:07.446744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.042038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:07.446814Z","caller":"traceutil/trace.go:171","msg":"trace[1783621100] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"178.137379ms","start":"2026-04-20T09:35:07.268663Z","end":"2026-04-20T09:35:07.4468Z","steps":["trace[1783621100] 'count revisions from in-memory index tree' (duration: 178.004128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:07.447003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.393232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:07.447034Z","caller":"traceutil/trace.go:171","msg":"trace[739975798] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"178.458042ms","start":"2026-04-20T09:35:07.268568Z","end":"2026-04-20T09:35:07.447026Z","steps":["trace[739975798] 'range keys from in-memory index tree' (duration: 178.265661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:07.447222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.483069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:07.447263Z","caller":"traceutil/trace.go:171","msg":"trace[78704870] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"133.544879ms","start":"2026-04-20T09:35:07.313706Z","end":"2026-04-20T09:35:07.447251Z","steps":["trace[78704870] 'count revisions from in-memory index tree' (duration: 133.424648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:07.447367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.353978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:07.447397Z","caller":"traceutil/trace.go:171","msg":"trace[825080545] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"133.401498ms","start":"2026-04-20T09:35:07.313989Z","end":"2026-04-20T09:35:07.447391Z","steps":["trace[825080545] 'range keys from in-memory index tree' (duration: 133.312777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:07.447505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.915076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:07.447535Z","caller":"traceutil/trace.go:171","msg":"trace[1027282523] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"127.969596ms","start":"2026-04-20T09:35:07.319559Z","end":"2026-04-20T09:35:07.447529Z","steps":["trace[1027282523] 'range keys from in-memory index tree' (duration: 127.847475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:07.447619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.085346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:07.447664Z","caller":"traceutil/trace.go:171","msg":"trace[425647410] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:568; }","duration":"105.119626ms","start":"2026-04-20T09:35:07.34252Z","end":"2026-04-20T09:35:07.44764Z","steps":["trace[425647410] 'count revisions from in-memory index tree' (duration: 105.057776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:07.448078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.883844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:07.448109Z","caller":"traceutil/trace.go:171","msg":"trace[1212538129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:568; }","duration":"104.925664ms","start":"2026-04-20T09:35:07.343177Z","end":"2026-04-20T09:35:07.448103Z","steps":["trace[1212538129] 'range keys from in-memory index tree' (duration: 104.861174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:07.448029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.488388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:07.448171Z","caller":"traceutil/trace.go:171","msg":"trace[1460607479] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:568; }","duration":"105.682309ms","start":"2026-04-20T09:35:07.342461Z","end":"2026-04-20T09:35:07.448144Z","steps":["trace[1460607479] 'range keys from in-memory index tree' (duration: 105.380608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:10.441308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.822105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:35:10.441683Z","caller":"traceutil/trace.go:171","msg":"trace[1351235308] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"320.11077ms","start":"2026-04-20T09:35:10.121551Z","end":"2026-04-20T09:35:10.441661Z","steps":["trace[1351235308] 'process raft request' (duration: 319.933199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:10.441822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:10.121526Z","time spent":"320.214241ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:35:10.441839Z","caller":"traceutil/trace.go:171","msg":"trace[455176959] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"332.83725ms","start":"2026-04-20T09:35:10.108971Z","end":"2026-04-20T09:35:10.441808Z","steps":["trace[455176959] 'process raft request' (duration: 106.426562ms)","trace[455176959] 'compare' (duration: 225.708185ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:35:10.442169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:10.108955Z","time spent":"332.999741ms","remote":"127.0.0.1:59036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:35:10.652345Z","caller":"traceutil/trace.go:171","msg":"trace[774570543] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"160.531879ms","start":"2026-04-20T09:35:10.49177Z","end":"2026-04-20T09:35:10.652302Z","steps":["trace[774570543] 'process raft request' (duration: 137.344769ms)","trace[774570543] 'compare' (duration: 22.965349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:35:11.171547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.339169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:11.171688Z","caller":"traceutil/trace.go:171","msg":"trace[1412674364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"316.494539ms","start":"2026-04-20T09:35:10.855142Z","end":"2026-04-20T09:35:11.171636Z","steps":["trace[1412674364] 'range keys from in-memory index tree' (duration: 316.219579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:11.171745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:10.855126Z","time spent":"316.606539ms","remote":"127.0.0.1:58740","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-04-20T09:35:11.277247Z","caller":"traceutil/trace.go:171","msg":"trace[1801265113] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"101.313126ms","start":"2026-04-20T09:35:11.175904Z","end":"2026-04-20T09:35:11.277218Z","steps":["trace[1801265113] 'process raft request' (duration: 101.134645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:12.356012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773125447191619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:35:12.857106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773125447191619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:35:13.35765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773125447191619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:35:13.712435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.932042168s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T09:35:13.844528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.623667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:35:13.844664Z","caller":"traceutil/trace.go:171","msg":"trace[2011253951] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"1.989300858s","start":"2026-04-20T09:35:11.855341Z","end":"2026-04-20T09:35:13.844641Z","steps":["trace[2011253951] 'read index received' (duration: 1.857314091s)","trace[2011253951] 'applied index is now lower than readState.Index' (duration: 131.985227ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:35:13.845125Z","caller":"traceutil/trace.go:171","msg":"trace[1194759687] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.064794347s","start":"2026-04-20T09:35:11.780314Z","end":"2026-04-20T09:35:13.845109Z","steps":["trace[1194759687] 'process raft request' (duration: 1.932468369s)","trace[1194759687] 'compare' (duration: 131.450127ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:35:13.845207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:11.780294Z","time spent":"2.064874677s","remote":"127.0.0.1:59036","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-04-20T09:35:13.845659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.990300481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:13.845702Z","caller":"traceutil/trace.go:171","msg":"trace[1668758085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"1.990392632s","start":"2026-04-20T09:35:11.855296Z","end":"2026-04-20T09:35:13.845688Z","steps":["trace[1668758085] 'agreement among raft nodes before linearized reading' (duration: 1.990299392s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:13.84574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:11.855276Z","time spent":"1.990457022s","remote":"127.0.0.1:58740","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-04-20T09:35:13.846038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709983938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T09:35:13.846043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185142066s","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-04-20T09:35:13.846116Z","caller":"traceutil/trace.go:171","msg":"trace[1501624952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"1.710107048s","start":"2026-04-20T09:35:12.13599Z","end":"2026-04-20T09:35:13.846097Z","steps":["trace[1501624952] 'agreement among raft nodes before linearized reading' (duration: 1.709898198s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:13.84611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.540005ms","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-04-20T09:35:13.846254Z","caller":"traceutil/trace.go:171","msg":"trace[2081012616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"564.685396ms","start":"2026-04-20T09:35:13.281546Z","end":"2026-04-20T09:35:13.846231Z","steps":["trace[2081012616] 'agreement among raft nodes before linearized reading' (duration: 564.410055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:13.846323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:13.281481Z","time spent":"564.830166ms","remote":"127.0.0.1:59036","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-04-20T09:35:13.846124Z","caller":"traceutil/trace.go:171","msg":"trace[811418826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"1.185268766s","start":"2026-04-20T09:35:12.660838Z","end":"2026-04-20T09:35:13.846106Z","steps":["trace[811418826] 'agreement among raft nodes before linearized reading' (duration: 1.185069186s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:13.846423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:12.660814Z","time spent":"1.185578477s","remote":"127.0.0.1:59036","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-04-20T09:35:13.846157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:12.13597Z","time spent":"1.710179388s","remote":"127.0.0.1:59036","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-04-20T09:35:13.846083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398595173s","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-04-20T09:35:13.846616Z","caller":"traceutil/trace.go:171","msg":"trace[1027146352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"1.399131715s","start":"2026-04-20T09:35:12.447435Z","end":"2026-04-20T09:35:13.846567Z","steps":["trace[1027146352] 'agreement among raft nodes before linearized reading' (duration: 1.398478374s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:13.846654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:12.447417Z","time spent":"1.399223945s","remote":"127.0.0.1:59036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T09:35:15.420134Z","caller":"traceutil/trace.go:171","msg":"trace[584558393] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"212.720011ms","start":"2026-04-20T09:35:15.207391Z","end":"2026-04-20T09:35:15.420111Z","steps":["trace[584558393] 'process raft request' (duration: 212.51469ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:35:15.435403Z","caller":"traceutil/trace.go:171","msg":"trace[1825923131] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"227.897488ms","start":"2026-04-20T09:35:15.207488Z","end":"2026-04-20T09:35:15.435385Z","steps":["trace[1825923131] 'process raft request' (duration: 227.773328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.324104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403118446s","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-04-20T09:35:29.324086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403791709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T09:35:29.324144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201954645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:35:29.324164Z","caller":"traceutil/trace.go:171","msg":"trace[51344964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"1.403198586s","start":"2026-04-20T09:35:27.920949Z","end":"2026-04-20T09:35:29.324148Z","steps":["trace[51344964] 'range keys from in-memory index tree' (duration: 1.403019167s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:35:29.324183Z","caller":"traceutil/trace.go:171","msg":"trace[671430621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"1.403936669s","start":"2026-04-20T09:35:27.920227Z","end":"2026-04-20T09:35:29.324163Z","steps":["trace[671430621] 'range keys from in-memory index tree' (duration: 1.403504731s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.324215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:27.920937Z","time spent":"1.403260355s","remote":"127.0.0.1:59036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T09:35:29.324206Z","caller":"traceutil/trace.go:171","msg":"trace[62850571] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:749; }","duration":"1.202010574s","start":"2026-04-20T09:35:28.122178Z","end":"2026-04-20T09:35:29.324188Z","steps":["trace[62850571] 'count revisions from in-memory index tree' (duration: 1.201716948s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.324228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:27.920204Z","time spent":"1.404012107s","remote":"127.0.0.1:59036","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-04-20T09:35:29.32425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:28.122118Z","time spent":"1.202122383s","remote":"127.0.0.1:58900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-20T09:35:29.324425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402536271s","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-04-20T09:35:29.324452Z","caller":"traceutil/trace.go:171","msg":"trace[1879758397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:749; }","duration":"1.402603852s","start":"2026-04-20T09:35:27.921841Z","end":"2026-04-20T09:35:29.324445Z","steps":["trace[1879758397] 'range keys from in-memory index tree' (duration: 1.402415753s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.324425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100366228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6nf57\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-20T09:35:29.324479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:27.921821Z","time spent":"1.402653491s","remote":"127.0.0.1:59036","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-04-20T09:35:29.324522Z","caller":"traceutil/trace.go:171","msg":"trace[307728194] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6nf57; range_end:; response_count:1; response_revision:749; }","duration":"1.100479887s","start":"2026-04-20T09:35:28.224003Z","end":"2026-04-20T09:35:29.324483Z","steps":["trace[307728194] 'range keys from in-memory index tree' (duration: 1.100209831s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.324078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.562884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.176\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T09:35:29.324575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:28.223988Z","time spent":"1.100577905s","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6nf57\" "} {"level":"info","ts":"2026-04-20T09:35:29.324612Z","caller":"traceutil/trace.go:171","msg":"trace[1696699604] range","detail":"{range_begin:/registry/masterleases/199.19.213.176; range_end:; response_count:1; response_revision:749; }","duration":"159.162057ms","start":"2026-04-20T09:35:29.165439Z","end":"2026-04-20T09:35:29.324601Z","steps":["trace[1696699604] 'range keys from in-memory index tree' (duration: 158.326838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.324624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39801474s","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-04-20T09:35:29.324667Z","caller":"traceutil/trace.go:171","msg":"trace[1119434284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:749; }","duration":"1.398080948s","start":"2026-04-20T09:35:27.926573Z","end":"2026-04-20T09:35:29.324654Z","steps":["trace[1119434284] 'range keys from in-memory index tree' (duration: 1.39789687s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.324704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:27.92656Z","time spent":"1.398133537s","remote":"127.0.0.1:59036","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-04-20T09:35:29.325523Z","caller":"traceutil/trace.go:171","msg":"trace[1261450139] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"153.401866ms","start":"2026-04-20T09:35:29.172102Z","end":"2026-04-20T09:35:29.325504Z","steps":["trace[1261450139] 'process raft request' (duration: 150.668989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.325239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.04613ms","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-04-20T09:35:29.326132Z","caller":"traceutil/trace.go:171","msg":"trace[410126739] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:749; }","duration":"670.438693ms","start":"2026-04-20T09:35:28.655682Z","end":"2026-04-20T09:35:29.326121Z","steps":["trace[410126739] 'count revisions from in-memory index tree' (duration: 668.742584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:29.326161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:35:28.655621Z","time spent":"670.529912ms","remote":"127.0.0.1:59016","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-04-20T09:35:43.519326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.71326ms","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-04-20T09:35:43.519597Z","caller":"traceutil/trace.go:171","msg":"trace[801171125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:913; }","duration":"116.041153ms","start":"2026-04-20T09:35:43.403524Z","end":"2026-04-20T09:35:43.519565Z","steps":["trace[801171125] 'range keys from in-memory index tree' (duration: 115.497214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:36:21.824298Z","caller":"traceutil/trace.go:171","msg":"trace[418614741] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"110.514016ms","start":"2026-04-20T09:36:21.713765Z","end":"2026-04-20T09:36:21.824279Z","steps":["trace[418614741] 'read index received' (duration: 110.429789ms)","trace[418614741] 'applied index is now lower than readState.Index' (duration: 83.167µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:36:21.824378Z","caller":"traceutil/trace.go:171","msg":"trace[1020535693] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"111.922201ms","start":"2026-04-20T09:36:21.712426Z","end":"2026-04-20T09:36:21.824348Z","steps":["trace[1020535693] 'process raft request' (duration: 111.728888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:36:21.824501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.718708ms","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-04-20T09:36:21.824535Z","caller":"traceutil/trace.go:171","msg":"trace[671501101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"110.787075ms","start":"2026-04-20T09:36:21.713736Z","end":"2026-04-20T09:36:21.824524Z","steps":["trace[671501101] 'agreement among raft nodes before linearized reading' (duration: 110.649781ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:36:21.893675Z","caller":"traceutil/trace.go:171","msg":"trace[25274876] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"178.786393ms","start":"2026-04-20T09:36:21.714867Z","end":"2026-04-20T09:36:21.893654Z","steps":["trace[25274876] 'process raft request' (duration: 177.512632ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:36:21.893805Z","caller":"traceutil/trace.go:171","msg":"trace[95309338] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"178.619459ms","start":"2026-04-20T09:36:21.715134Z","end":"2026-04-20T09:36:21.893753Z","steps":["trace[95309338] 'process raft request' (duration: 178.483084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:36:21.89389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.751481ms","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-04-20T09:36:21.895122Z","caller":"traceutil/trace.go:171","msg":"trace[1012705553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"178.025772ms","start":"2026-04-20T09:36:21.717079Z","end":"2026-04-20T09:36:21.895105Z","steps":["trace[1012705553] 'agreement among raft nodes before linearized reading' (duration: 176.668365ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:36:32.117484Z","caller":"traceutil/trace.go:171","msg":"trace[720033221] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"179.560816ms","start":"2026-04-20T09:36:31.937889Z","end":"2026-04-20T09:36:32.11745Z","steps":["trace[720033221] 'read index received' (duration: 131.465106ms)","trace[720033221] 'applied index is now lower than readState.Index' (duration: 48.09472ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:36:32.117611Z","caller":"traceutil/trace.go:171","msg":"trace[372219642] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"180.080994ms","start":"2026-04-20T09:36:31.937515Z","end":"2026-04-20T09:36:32.117596Z","steps":["trace[372219642] 'process raft request' (duration: 131.861161ms)","trace[372219642] 'compare' (duration: 47.905707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:36:32.117815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.48911ms","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-04-20T09:36:32.117884Z","caller":"traceutil/trace.go:171","msg":"trace[1329345703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"179.608055ms","start":"2026-04-20T09:36:31.938259Z","end":"2026-04-20T09:36:32.117867Z","steps":["trace[1329345703] 'agreement among raft nodes before linearized reading' (duration: 179.427003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:36:32.118036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.122142ms","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-04-20T09:36:32.118148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.699338ms","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-04-20T09:36:32.118201Z","caller":"traceutil/trace.go:171","msg":"trace[1939711455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"175.790924ms","start":"2026-04-20T09:36:31.942396Z","end":"2026-04-20T09:36:32.118187Z","steps":["trace[1939711455] 'agreement among raft nodes before linearized reading' (duration: 175.607822ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:36:32.118308Z","caller":"traceutil/trace.go:171","msg":"trace[918467344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"180.240616ms","start":"2026-04-20T09:36:31.937859Z","end":"2026-04-20T09:36:32.118099Z","steps":["trace[918467344] 'agreement among raft nodes before linearized reading' (duration: 179.998447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:37:12.592443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.078072ms","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-04-20T09:37:12.59251Z","caller":"traceutil/trace.go:171","msg":"trace[362645494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"283.192406ms","start":"2026-04-20T09:37:12.309303Z","end":"2026-04-20T09:37:12.592495Z","steps":["trace[362645494] 'range keys from in-memory index tree' (duration: 282.93942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:37:12.592608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.028505ms","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-04-20T09:37:12.592695Z","caller":"traceutil/trace.go:171","msg":"trace[1824910274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"191.234084ms","start":"2026-04-20T09:37:12.40143Z","end":"2026-04-20T09:37:12.592664Z","steps":["trace[1824910274] 'range keys from in-memory index tree' (duration: 190.913752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:37:12.592881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.875065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T09:37:12.59291Z","caller":"traceutil/trace.go:171","msg":"trace[669526055] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1238; }","duration":"225.906973ms","start":"2026-04-20T09:37:12.366996Z","end":"2026-04-20T09:37:12.592903Z","steps":["trace[669526055] 'count revisions from in-memory index tree' (duration: 225.750421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:37:12.593016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.935398ms","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-04-20T09:37:12.59303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.93685ms","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-04-20T09:37:12.593066Z","caller":"traceutil/trace.go:171","msg":"trace[35261801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"282.990617ms","start":"2026-04-20T09:37:12.310067Z","end":"2026-04-20T09:37:12.593058Z","steps":["trace[35261801] 'range keys from in-memory index tree' (duration: 282.859174ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:37:12.593053Z","caller":"traceutil/trace.go:171","msg":"trace[2055473054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"194.999656ms","start":"2026-04-20T09:37:12.398046Z","end":"2026-04-20T09:37:12.593046Z","steps":["trace[2055473054] 'range keys from in-memory index tree' (duration: 194.856133ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:37:17.074801Z","caller":"traceutil/trace.go:171","msg":"trace[987903589] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"231.362935ms","start":"2026-04-20T09:37:16.843409Z","end":"2026-04-20T09:37:17.074772Z","steps":["trace[987903589] 'process raft request' (duration: 231.225062ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:37:24.813971Z","caller":"traceutil/trace.go:171","msg":"trace[126282185] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"147.692264ms","start":"2026-04-20T09:37:24.666259Z","end":"2026-04-20T09:37:24.813951Z","steps":["trace[126282185] 'process raft request' (duration: 147.643687ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:37:24.814139Z","caller":"traceutil/trace.go:171","msg":"trace[1162709866] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"147.879714ms","start":"2026-04-20T09:37:24.666251Z","end":"2026-04-20T09:37:24.814131Z","steps":["trace[1162709866] 'process raft request' (duration: 140.730577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:37:25.238845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.980432ms","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-04-20T09:37:25.239003Z","caller":"traceutil/trace.go:171","msg":"trace[287593133] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1302; }","duration":"230.142123ms","start":"2026-04-20T09:37:25.008834Z","end":"2026-04-20T09:37:25.238976Z","steps":["trace[287593133] 'range keys from in-memory index tree' (duration: 229.962022ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:37:25.343437Z","caller":"traceutil/trace.go:171","msg":"trace[1678769544] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"243.648988ms","start":"2026-04-20T09:37:25.099759Z","end":"2026-04-20T09:37:25.343408Z","steps":["trace[1678769544] 'process raft request' (duration: 243.275937ms)"],"step_count":1}