{"level":"warn","ts":"2026-08-12T10:58:19.853604Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T10:58:19.853717Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-08-12T10:58:19.85534Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T10:58:19.855366Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-08-12T10:58:19.855403Z","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-08-12T10:58:19.861145Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-08-12T10:58:19.861388Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-08-12T10:58:19.866239Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.573535ms"} {"level":"info","ts":"2026-08-12T10:58:19.878536Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-08-12T10:58:19.878631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T10:58:19.878666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-08-12T10:58:19.878683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T10:58:19.878697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-08-12T10:58:19.878749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-08-12T10:58:19.886483Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T10:58:19.888839Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T10:58:19.890217Z","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-08-12T10:58:19.892742Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T10:58:19.89293Z","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-08-12T10:58:19.893094Z","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-08-12T10:58:19.89311Z","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-08-12T10:58:19.893072Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T10:58:19.89401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-08-12T10:58:19.89417Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-08-12T10:58:19.897072Z","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-08-12T10:58:19.897138Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-08-12T10:58:19.897197Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-08-12T10:58:19.89977Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T10:58:19.899825Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T10:58:20.678962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T10:58:20.679047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T10:58:20.6791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-08-12T10:58:20.679123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-08-12T10:58:20.679134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-08-12T10:58:20.679151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-08-12T10:58:20.679167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-08-12T10:58:20.680481Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T10:58:20.680733Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T10:58:20.680815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T10:58:20.680884Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T10:58:20.681299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T10:58:20.681388Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T10:58:20.682838Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T10:58:20.682977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T10:58:20.683091Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T10:58:20.686017Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-08-12T10:58:20.687787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T10:59:45.384139Z","caller":"traceutil/trace.go:171","msg":"trace[946527783] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"186.652706ms","start":"2026-08-12T10:59:45.197467Z","end":"2026-08-12T10:59:45.38412Z","steps":["trace[946527783] 'read index received' (duration: 186.498761ms)","trace[946527783] 'applied index is now lower than readState.Index' (duration: 153.275µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:59:45.384273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.78372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:45.38431Z","caller":"traceutil/trace.go:171","msg":"trace[610325696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"186.878563ms","start":"2026-08-12T10:59:45.197424Z","end":"2026-08-12T10:59:45.384303Z","steps":["trace[610325696] 'agreement among raft nodes before linearized reading' (duration: 186.786061ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:45.384567Z","caller":"traceutil/trace.go:171","msg":"trace[77839668] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"221.303035ms","start":"2026-08-12T10:59:45.163256Z","end":"2026-08-12T10:59:45.384559Z","steps":["trace[77839668] 'process raft request' (duration: 220.761699ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.052596Z","caller":"traceutil/trace.go:171","msg":"trace[425037375] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"186.992476ms","start":"2026-08-12T10:59:49.865577Z","end":"2026-08-12T10:59:50.052569Z","steps":["trace[425037375] 'process raft request' (duration: 186.261606ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.058221Z","caller":"traceutil/trace.go:171","msg":"trace[2067991417] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"173.991964ms","start":"2026-08-12T10:59:49.884195Z","end":"2026-08-12T10:59:50.058187Z","steps":["trace[2067991417] 'process raft request' (duration: 173.715456ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.058595Z","caller":"traceutil/trace.go:171","msg":"trace[670924794] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"191.926151ms","start":"2026-08-12T10:59:49.866653Z","end":"2026-08-12T10:59:50.058579Z","steps":["trace[670924794] 'process raft request' (duration: 191.108848ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.058633Z","caller":"traceutil/trace.go:171","msg":"trace[1202735885] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"172.664976ms","start":"2026-08-12T10:59:49.885948Z","end":"2026-08-12T10:59:50.058613Z","steps":["trace[1202735885] 'process raft request' (duration: 172.000996ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.05887Z","caller":"traceutil/trace.go:171","msg":"trace[2094110582] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"182.200386ms","start":"2026-08-12T10:59:49.876656Z","end":"2026-08-12T10:59:50.058857Z","steps":["trace[2094110582] 'process raft request' (duration: 181.197647ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.05899Z","caller":"traceutil/trace.go:171","msg":"trace[1213674259] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"170.46286ms","start":"2026-08-12T10:59:49.888518Z","end":"2026-08-12T10:59:50.058981Z","steps":["trace[1213674259] 'process raft request' (duration: 169.454331ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.059154Z","caller":"traceutil/trace.go:171","msg":"trace[1286353141] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"170.450089ms","start":"2026-08-12T10:59:49.888681Z","end":"2026-08-12T10:59:50.059131Z","steps":["trace[1286353141] 'process raft request' (duration: 169.318587ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.059279Z","caller":"traceutil/trace.go:171","msg":"trace[1755254456] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"178.608221ms","start":"2026-08-12T10:59:49.880656Z","end":"2026-08-12T10:59:50.059264Z","steps":["trace[1755254456] 'process raft request' (duration: 177.2279ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.059393Z","caller":"traceutil/trace.go:171","msg":"trace[1666067674] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"151.999238ms","start":"2026-08-12T10:59:49.907385Z","end":"2026-08-12T10:59:50.059384Z","steps":["trace[1666067674] 'process raft request' (duration: 150.659619ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.059608Z","caller":"traceutil/trace.go:171","msg":"trace[1768509836] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"151.677889ms","start":"2026-08-12T10:59:49.90792Z","end":"2026-08-12T10:59:50.059597Z","steps":["trace[1768509836] 'process raft request' (duration: 150.161284ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.059857Z","caller":"traceutil/trace.go:171","msg":"trace[614296406] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"130.2768ms","start":"2026-08-12T10:59:49.929572Z","end":"2026-08-12T10:59:50.059849Z","steps":["trace[614296406] 'process raft request' (duration: 128.542829ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:56.737205Z","caller":"traceutil/trace.go:171","msg":"trace[175511001] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"180.524936ms","start":"2026-08-12T10:59:56.556654Z","end":"2026-08-12T10:59:56.737179Z","steps":["trace[175511001] 'process raft request' (duration: 179.96144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:57.434652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728251352673384,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T10:59:57.602087Z","caller":"traceutil/trace.go:171","msg":"trace[840964786] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"753.988173ms","start":"2026-08-12T10:59:56.84806Z","end":"2026-08-12T10:59:57.602048Z","steps":["trace[840964786] 'process raft request' (duration: 742.834375ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:57.602133Z","caller":"traceutil/trace.go:171","msg":"trace[416527194] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"667.549004ms","start":"2026-08-12T10:59:56.934557Z","end":"2026-08-12T10:59:57.602106Z","steps":["trace[416527194] 'read index received' (duration: 656.328953ms)","trace[416527194] 'applied index is now lower than readState.Index' (duration: 11.217831ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:59:57.602439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.618934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T10:59:57.60244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.870782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9tg8p\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-12T10:59:57.602482Z","caller":"traceutil/trace.go:171","msg":"trace[1915951900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"403.722908ms","start":"2026-08-12T10:59:57.198749Z","end":"2026-08-12T10:59:57.602471Z","steps":["trace[1915951900] 'agreement among raft nodes before linearized reading' (duration: 403.624365ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:57.602502Z","caller":"traceutil/trace.go:171","msg":"trace[1235362416] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9tg8p; range_end:; response_count:1; response_revision:719; }","duration":"667.985375ms","start":"2026-08-12T10:59:56.934499Z","end":"2026-08-12T10:59:57.602484Z","steps":["trace[1235362416] 'agreement among raft nodes before linearized reading' (duration: 667.786109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:57.602514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:57.198726Z","time spent":"403.780839ms","remote":"127.0.0.1:44622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T10:59:57.602554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:56.934476Z","time spent":"668.067158ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-9tg8p\" "} {"level":"warn","ts":"2026-08-12T10:59:57.602665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:56.848034Z","time spent":"754.132558ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T10:59:57.602781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.448061ms","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-08-12T10:59:57.602883Z","caller":"traceutil/trace.go:171","msg":"trace[2125089522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"167.664379ms","start":"2026-08-12T10:59:57.43519Z","end":"2026-08-12T10:59:57.602854Z","steps":["trace[2125089522] 'agreement among raft nodes before linearized reading' (duration: 167.422071ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:59.51193Z","caller":"traceutil/trace.go:171","msg":"trace[1605871792] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"193.621571ms","start":"2026-08-12T10:59:59.318283Z","end":"2026-08-12T10:59:59.511904Z","steps":["trace[1605871792] 'read index received' (duration: 193.487878ms)","trace[1605871792] 'applied index is now lower than readState.Index' (duration: 131.983µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:59:59.512044Z","caller":"traceutil/trace.go:171","msg":"trace[250176914] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"247.347201ms","start":"2026-08-12T10:59:59.264671Z","end":"2026-08-12T10:59:59.512018Z","steps":["trace[250176914] 'process raft request' (duration: 247.022161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.512236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.852128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.512349Z","caller":"traceutil/trace.go:171","msg":"trace[1571480731] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:750; }","duration":"194.040024ms","start":"2026-08-12T10:59:59.318257Z","end":"2026-08-12T10:59:59.512297Z","steps":["trace[1571480731] 'agreement among raft nodes before linearized reading' (duration: 193.770076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.547718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.613616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T10:59:59.547748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.753208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.547802Z","caller":"traceutil/trace.go:171","msg":"trace[1117917033] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:751; }","duration":"119.711609ms","start":"2026-08-12T10:59:59.428071Z","end":"2026-08-12T10:59:59.547782Z","steps":["trace[1117917033] 'agreement among raft nodes before linearized reading' (duration: 119.553505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.547987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.661896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.548021Z","caller":"traceutil/trace.go:171","msg":"trace[1040243161] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:751; }","duration":"106.726747ms","start":"2026-08-12T10:59:59.441287Z","end":"2026-08-12T10:59:59.548014Z","steps":["trace[1040243161] 'agreement among raft nodes before linearized reading' (duration: 106.660776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.548063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.826741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.548114Z","caller":"traceutil/trace.go:171","msg":"trace[596673486] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:751; }","duration":"204.884732ms","start":"2026-08-12T10:59:59.343214Z","end":"2026-08-12T10:59:59.548098Z","steps":["trace[596673486] 'agreement among raft nodes before linearized reading' (duration: 204.801489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.54817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.136855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T10:59:59.548085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.167967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T10:59:59.548266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.028516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.548301Z","caller":"traceutil/trace.go:171","msg":"trace[1827002158] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:751; }","duration":"107.064247ms","start":"2026-08-12T10:59:59.441229Z","end":"2026-08-12T10:59:59.548293Z","steps":["trace[1827002158] 'agreement among raft nodes before linearized reading' (duration: 107.018425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.548339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.097944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.548388Z","caller":"traceutil/trace.go:171","msg":"trace[1803781493] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:751; }","duration":"229.130234ms","start":"2026-08-12T10:59:59.319232Z","end":"2026-08-12T10:59:59.548362Z","steps":["trace[1803781493] 'agreement among raft nodes before linearized reading' (duration: 229.084793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.548106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.119505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.548484Z","caller":"traceutil/trace.go:171","msg":"trace[575411185] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:751; }","duration":"180.541138ms","start":"2026-08-12T10:59:59.367935Z","end":"2026-08-12T10:59:59.548476Z","steps":["trace[575411185] 'agreement among raft nodes before linearized reading' (duration: 180.070643ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:59.548446Z","caller":"traceutil/trace.go:171","msg":"trace[1346372471] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:751; }","duration":"179.558348ms","start":"2026-08-12T10:59:59.368781Z","end":"2026-08-12T10:59:59.548339Z","steps":["trace[1346372471] 'agreement among raft nodes before linearized reading' (duration: 179.163797ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:59.547813Z","caller":"traceutil/trace.go:171","msg":"trace[1190576850] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:751; }","duration":"105.858492ms","start":"2026-08-12T10:59:59.441938Z","end":"2026-08-12T10:59:59.547797Z","steps":["trace[1190576850] 'agreement among raft nodes before linearized reading' (duration: 105.734489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.548596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.120045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.548624Z","caller":"traceutil/trace.go:171","msg":"trace[1166560987] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:751; }","duration":"230.219637ms","start":"2026-08-12T10:59:59.318397Z","end":"2026-08-12T10:59:59.548616Z","steps":["trace[1166560987] 'agreement among raft nodes before linearized reading' (duration: 230.170246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.548609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.289394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.548658Z","caller":"traceutil/trace.go:171","msg":"trace[491472224] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:751; }","duration":"206.351875ms","start":"2026-08-12T10:59:59.342299Z","end":"2026-08-12T10:59:59.548651Z","steps":["trace[491472224] 'agreement among raft nodes before linearized reading' (duration: 206.287954ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:59.548226Z","caller":"traceutil/trace.go:171","msg":"trace[1638498838] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:751; }","duration":"180.186826ms","start":"2026-08-12T10:59:59.368017Z","end":"2026-08-12T10:59:59.548204Z","steps":["trace[1638498838] 'agreement among raft nodes before linearized reading' (duration: 180.133025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.547749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.302517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.548902Z","caller":"traceutil/trace.go:171","msg":"trace[1320461665] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:751; }","duration":"121.473871ms","start":"2026-08-12T10:59:59.427405Z","end":"2026-08-12T10:59:59.548879Z","steps":["trace[1320461665] 'agreement among raft nodes before linearized reading' (duration: 120.298736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.547918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.487852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.549085Z","caller":"traceutil/trace.go:171","msg":"trace[1518942828] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:751; }","duration":"121.683847ms","start":"2026-08-12T10:59:59.427386Z","end":"2026-08-12T10:59:59.54907Z","steps":["trace[1518942828] 'agreement among raft nodes before linearized reading' (duration: 120.488192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:59.549398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.556113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:59.549435Z","caller":"traceutil/trace.go:171","msg":"trace[1324587410] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:751; }","duration":"207.003925ms","start":"2026-08-12T10:59:59.34242Z","end":"2026-08-12T10:59:59.549424Z","steps":["trace[1324587410] 'agreement among raft nodes before linearized reading' (duration: 205.545912ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:00:09.786658Z","caller":"traceutil/trace.go:171","msg":"trace[11509092] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"112.370094ms","start":"2026-08-12T11:00:09.674267Z","end":"2026-08-12T11:00:09.786637Z","steps":["trace[11509092] 'process raft request' (duration: 112.207858ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:00:25.46791Z","caller":"traceutil/trace.go:171","msg":"trace[1603172529] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"252.810838ms","start":"2026-08-12T11:00:25.215073Z","end":"2026-08-12T11:00:25.467884Z","steps":["trace[1603172529] 'process raft request' (duration: 252.602552ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:00:49.125512Z","caller":"traceutil/trace.go:171","msg":"trace[1221953631] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"139.007864ms","start":"2026-08-12T11:00:48.986482Z","end":"2026-08-12T11:00:49.12549Z","steps":["trace[1221953631] 'read index received' (duration: 138.980014ms)","trace[1221953631] 'applied index is now lower than readState.Index' (duration: 26.5µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:00:49.125621Z","caller":"traceutil/trace.go:171","msg":"trace[1689955842] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"187.337826ms","start":"2026-08-12T11:00:48.938273Z","end":"2026-08-12T11:00:49.12561Z","steps":["trace[1689955842] 'process raft request' (duration: 187.093349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:49.125813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.321453ms","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-08-12T11:00:49.125887Z","caller":"traceutil/trace.go:171","msg":"trace[425422631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"139.432426ms","start":"2026-08-12T11:00:48.98644Z","end":"2026-08-12T11:00:49.125873Z","steps":["trace[425422631] 'agreement among raft nodes before linearized reading' (duration: 139.251371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:49.523134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.382418ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T11:00:49.523409Z","caller":"traceutil/trace.go:171","msg":"trace[1091069729] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"397.797056ms","start":"2026-08-12T11:00:49.125579Z","end":"2026-08-12T11:00:49.523376Z","steps":["trace[1091069729] 'read index received' (duration: 251.254502ms)","trace[1091069729] 'applied index is now lower than readState.Index' (duration: 146.537542ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:00:49.523447Z","caller":"traceutil/trace.go:171","msg":"trace[976234450] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"393.888351ms","start":"2026-08-12T11:00:49.129541Z","end":"2026-08-12T11:00:49.523429Z","steps":["trace[976234450] 'process raft request' (duration: 393.734917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:49.523672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.094938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-12T11:00:49.523705Z","caller":"traceutil/trace.go:171","msg":"trace[855303268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"414.18177ms","start":"2026-08-12T11:00:49.109513Z","end":"2026-08-12T11:00:49.523695Z","steps":["trace[855303268] 'agreement among raft nodes before linearized reading' (duration: 414.014556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:49.523747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:49.109492Z","time spent":"414.247333ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T11:00:49.524068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.744091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:00:49.524107Z","caller":"traceutil/trace.go:171","msg":"trace[38564069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"327.829254ms","start":"2026-08-12T11:00:49.196267Z","end":"2026-08-12T11:00:49.524096Z","steps":["trace[38564069] 'agreement among raft nodes before linearized reading' (duration: 327.771153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:49.524139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:49.196243Z","time spent":"327.889896ms","remote":"127.0.0.1:44622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T11:00:49.524168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:49.129522Z","time spent":"394.16831ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:00:49.524565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.358305ms","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-08-12T11:00:49.52467Z","caller":"traceutil/trace.go:171","msg":"trace[1370725603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"415.52787ms","start":"2026-08-12T11:00:49.109122Z","end":"2026-08-12T11:00:49.52465Z","steps":["trace[1370725603] 'agreement among raft nodes before linearized reading' (duration: 415.160459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:49.524722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:49.109105Z","time spent":"415.609122ms","remote":"127.0.0.1:44908","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-08-12T11:00:50.223086Z","caller":"traceutil/trace.go:171","msg":"trace[738263705] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"205.117682ms","start":"2026-08-12T11:00:50.017944Z","end":"2026-08-12T11:00:50.223061Z","steps":["trace[738263705] 'process raft request' (duration: 204.590648ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:07.868051Z","caller":"traceutil/trace.go:171","msg":"trace[2056423703] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"141.934989ms","start":"2026-08-12T11:01:07.726083Z","end":"2026-08-12T11:01:07.868018Z","steps":["trace[2056423703] 'process raft request' (duration: 121.438744ms)","trace[2056423703] 'compare' (duration: 20.374711ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:07.881126Z","caller":"traceutil/trace.go:171","msg":"trace[730301210] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1207; }","duration":"125.469941ms","start":"2026-08-12T11:01:07.755634Z","end":"2026-08-12T11:01:07.881104Z","steps":["trace[730301210] 'read index received' (duration: 91.906537ms)","trace[730301210] 'applied index is now lower than readState.Index' (duration: 33.562384ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:07.881215Z","caller":"traceutil/trace.go:171","msg":"trace[1466147183] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"125.603916ms","start":"2026-08-12T11:01:07.75559Z","end":"2026-08-12T11:01:07.881194Z","steps":["trace[1466147183] 'process raft request' (duration: 124.768091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:07.881351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.701159ms","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-08-12T11:01:07.881381Z","caller":"traceutil/trace.go:171","msg":"trace[986450346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1160; }","duration":"125.77261ms","start":"2026-08-12T11:01:07.755601Z","end":"2026-08-12T11:01:07.881373Z","steps":["trace[986450346] 'agreement among raft nodes before linearized reading' (duration: 125.643747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:16.248017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.031156ms","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-08-12T11:01:16.248111Z","caller":"traceutil/trace.go:171","msg":"trace[2004739937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"353.18776ms","start":"2026-08-12T11:01:15.894909Z","end":"2026-08-12T11:01:16.248097Z","steps":["trace[2004739937] 'range keys from in-memory index tree' (duration: 352.84126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:16.248139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:15.894884Z","time spent":"353.249042ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T11:01:16.309955Z","caller":"traceutil/trace.go:171","msg":"trace[171823061] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"382.264905ms","start":"2026-08-12T11:01:15.927661Z","end":"2026-08-12T11:01:16.309926Z","steps":["trace[171823061] 'read index received' (duration: 382.056459ms)","trace[171823061] 'applied index is now lower than readState.Index' (duration: 207.066µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:16.309984Z","caller":"traceutil/trace.go:171","msg":"trace[1409384714] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"408.888937ms","start":"2026-08-12T11:01:15.901066Z","end":"2026-08-12T11:01:16.309955Z","steps":["trace[1409384714] 'process raft request' (duration: 408.694612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:16.310118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:15.901037Z","time spent":"409.00891ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:01:16.310138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.47912ms","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-08-12T11:01:16.310174Z","caller":"traceutil/trace.go:171","msg":"trace[1145075222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"382.565634ms","start":"2026-08-12T11:01:15.927599Z","end":"2026-08-12T11:01:16.310165Z","steps":["trace[1145075222] 'agreement among raft nodes before linearized reading' (duration: 382.425109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:16.310205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:15.927576Z","time spent":"382.624195ms","remote":"127.0.0.1:44908","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-08-12T11:01:16.324586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.962034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:01:16.324658Z","caller":"traceutil/trace.go:171","msg":"trace[1043132950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"127.069738ms","start":"2026-08-12T11:01:16.19757Z","end":"2026-08-12T11:01:16.32464Z","steps":["trace[1043132950] 'agreement among raft nodes before linearized reading' (duration: 126.949415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:18.829134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728251352674526,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T11:01:19.28628Z","caller":"traceutil/trace.go:171","msg":"trace[2076803253] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"957.958732ms","start":"2026-08-12T11:01:18.328297Z","end":"2026-08-12T11:01:19.286255Z","steps":["trace[2076803253] 'read index received' (duration: 957.806488ms)","trace[2076803253] 'applied index is now lower than readState.Index' (duration: 151.224µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:19.286374Z","caller":"traceutil/trace.go:171","msg":"trace[149489230] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"970.860737ms","start":"2026-08-12T11:01:18.315487Z","end":"2026-08-12T11:01:19.286347Z","steps":["trace[149489230] 'process raft request' (duration: 970.64136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:19.286489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:18.315459Z","time spent":"970.972579ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:01:19.286633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.310602ms","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-08-12T11:01:19.286694Z","caller":"traceutil/trace.go:171","msg":"trace[1751252955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1244; }","duration":"958.420665ms","start":"2026-08-12T11:01:18.328257Z","end":"2026-08-12T11:01:19.286677Z","steps":["trace[1751252955] 'agreement among raft nodes before linearized reading' (duration: 958.132507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:19.286725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:18.32824Z","time spent":"958.479856ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T11:01:19.369868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026721607s","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-08-12T11:01:19.36993Z","caller":"traceutil/trace.go:171","msg":"trace[469622851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"1.026821711s","start":"2026-08-12T11:01:18.343094Z","end":"2026-08-12T11:01:19.369916Z","steps":["trace[469622851] 'agreement among raft nodes before linearized reading' (duration: 1.026610865s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:19.369965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:18.34308Z","time spent":"1.026878083s","remote":"127.0.0.1:44908","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-08-12T11:01:19.369969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.984636ms","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-08-12T11:01:19.370044Z","caller":"traceutil/trace.go:171","msg":"trace[1315935511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"954.1243ms","start":"2026-08-12T11:01:18.415901Z","end":"2026-08-12T11:01:19.370026Z","steps":["trace[1315935511] 'agreement among raft nodes before linearized reading' (duration: 953.816931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:19.370101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:18.415881Z","time spent":"954.200893ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-12T11:01:35.760758Z","caller":"traceutil/trace.go:171","msg":"trace[855747673] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"318.862844ms","start":"2026-08-12T11:01:35.441876Z","end":"2026-08-12T11:01:35.760739Z","steps":["trace[855747673] 'read index received' (duration: 318.143573ms)","trace[855747673] 'applied index is now lower than readState.Index' (duration: 718.281µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:35.760859Z","caller":"traceutil/trace.go:171","msg":"trace[2098755271] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"345.613572ms","start":"2026-08-12T11:01:35.415225Z","end":"2026-08-12T11:01:35.760838Z","steps":["trace[2098755271] 'process raft request' (duration: 344.810528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:35.760973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.0881ms","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-08-12T11:01:35.760986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.41521Z","time spent":"345.709494ms","remote":"127.0.0.1:45134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:01:35.761016Z","caller":"traceutil/trace.go:171","msg":"trace[841423617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"319.165992ms","start":"2026-08-12T11:01:35.441838Z","end":"2026-08-12T11:01:35.761004Z","steps":["trace[841423617] 'agreement among raft nodes before linearized reading' (duration: 319.002058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:35.761054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.44182Z","time spent":"319.226025ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T11:01:35.761307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.35087ms","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-08-12T11:01:35.761346Z","caller":"traceutil/trace.go:171","msg":"trace[1884649937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"317.402232ms","start":"2026-08-12T11:01:35.443935Z","end":"2026-08-12T11:01:35.761338Z","steps":["trace[1884649937] 'agreement among raft nodes before linearized reading' (duration: 317.304259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:35.761373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.44393Z","time spent":"317.437284ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T11:01:35.761504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.314792ms","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-08-12T11:01:35.761538Z","caller":"traceutil/trace.go:171","msg":"trace[872396116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1317; }","duration":"314.360804ms","start":"2026-08-12T11:01:35.44717Z","end":"2026-08-12T11:01:35.76153Z","steps":["trace[872396116] 'agreement among raft nodes before linearized reading' (duration: 314.289221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:35.761564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.447163Z","time spent":"314.393454ms","remote":"127.0.0.1:44908","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-08-12T11:01:36.27936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.930238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:01:36.279447Z","caller":"traceutil/trace.go:171","msg":"trace[1067281695] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"514.5777ms","start":"2026-08-12T11:01:35.764855Z","end":"2026-08-12T11:01:36.279433Z","steps":["trace[1067281695] 'process raft request' (duration: 271.508588ms)","trace[1067281695] 'compare' (duration: 242.778874ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:36.27949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.764833Z","time spent":"514.635952ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:01:36.31048Z","caller":"traceutil/trace.go:171","msg":"trace[542701887] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"545.023485ms","start":"2026-08-12T11:01:35.765417Z","end":"2026-08-12T11:01:36.31044Z","steps":["trace[542701887] 'process raft request' (duration: 544.798509ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:36.310508Z","caller":"traceutil/trace.go:171","msg":"trace[302759462] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"544.260094ms","start":"2026-08-12T11:01:35.766189Z","end":"2026-08-12T11:01:36.310449Z","steps":["trace[302759462] 'process raft request' (duration: 544.188011ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:36.310578Z","caller":"traceutil/trace.go:171","msg":"trace[1460956752] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1374; }","duration":"477.71245ms","start":"2026-08-12T11:01:35.832855Z","end":"2026-08-12T11:01:36.310568Z","steps":["trace[1460956752] 'read index received' (duration: 203.570445ms)","trace[1460956752] 'applied index is now lower than readState.Index' (duration: 274.140685ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:36.310625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.765406Z","time spent":"545.143809ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:01:36.310666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.766181Z","time spent":"544.405127ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:01:36.310486Z","caller":"traceutil/trace.go:171","msg":"trace[865701476] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"545.012666ms","start":"2026-08-12T11:01:35.765449Z","end":"2026-08-12T11:01:36.310461Z","steps":["trace[865701476] 'process raft request' (duration: 544.893902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:36.3108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.454897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T11:01:36.31081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.938546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-12T11:01:36.310824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.765426Z","time spent":"545.371225ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:01:36.310844Z","caller":"traceutil/trace.go:171","msg":"trace[2012088955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"112.53478ms","start":"2026-08-12T11:01:36.1983Z","end":"2026-08-12T11:01:36.310835Z","steps":["trace[2012088955] 'agreement among raft nodes before linearized reading' (duration: 112.470768ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:36.310864Z","caller":"traceutil/trace.go:171","msg":"trace[1755533660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"478.031839ms","start":"2026-08-12T11:01:35.832818Z","end":"2026-08-12T11:01:36.31085Z","steps":["trace[1755533660] 'agreement among raft nodes before linearized reading' (duration: 477.881765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:36.310895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.832798Z","time spent":"478.09015ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:01:36.31099Z","caller":"traceutil/trace.go:171","msg":"trace[1330617755] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"413.316039ms","start":"2026-08-12T11:01:35.897664Z","end":"2026-08-12T11:01:36.310981Z","steps":["trace[1330617755] 'process raft request' (duration: 412.746663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:36.311059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:35.897641Z","time spent":"413.36897ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:01:37.3573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.705251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:01:37.357402Z","caller":"traceutil/trace.go:171","msg":"trace[853950229] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"441.313071ms","start":"2026-08-12T11:01:36.916074Z","end":"2026-08-12T11:01:37.357387Z","steps":["trace[853950229] 'read index received' (duration: 78.718746ms)","trace[853950229] 'applied index is now lower than readState.Index' (duration: 362.592505ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:37.35749Z","caller":"traceutil/trace.go:171","msg":"trace[400028748] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"441.702783ms","start":"2026-08-12T11:01:36.915766Z","end":"2026-08-12T11:01:37.357468Z","steps":["trace[400028748] 'process raft request' (duration: 81.695953ms)","trace[400028748] 'compare' (duration: 359.340381ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:37.357546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:36.91575Z","time spent":"441.772145ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:01:37.357646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.921891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:01:37.357721Z","caller":"traceutil/trace.go:171","msg":"trace[2061895357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"157.005942ms","start":"2026-08-12T11:01:37.200698Z","end":"2026-08-12T11:01:37.357704Z","steps":["trace[2061895357] 'agreement among raft nodes before linearized reading' (duration: 156.807327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:37.357862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.788906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:01:37.357897Z","caller":"traceutil/trace.go:171","msg":"trace[67409047] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1327; }","duration":"441.843308ms","start":"2026-08-12T11:01:36.916045Z","end":"2026-08-12T11:01:37.357888Z","steps":["trace[67409047] 'agreement among raft nodes before linearized reading' (duration: 441.791327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:37.357921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:36.916036Z","time spent":"441.878519ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-12T11:01:37.874019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.05286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:01:37.874104Z","caller":"traceutil/trace.go:171","msg":"trace[1570563968] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"511.369867ms","start":"2026-08-12T11:01:37.362718Z","end":"2026-08-12T11:01:37.874088Z","steps":["trace[1570563968] 'process raft request' (duration: 330.180563ms)","trace[1570563968] 'compare' (duration: 180.931227ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:37.87415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:37.362689Z","time spent":"511.439309ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:01:37.902465Z","caller":"traceutil/trace.go:171","msg":"trace[808822678] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"539.647479ms","start":"2026-08-12T11:01:37.362772Z","end":"2026-08-12T11:01:37.90242Z","steps":["trace[808822678] 'process raft request' (duration: 539.546406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:37.902573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:37.362752Z","time spent":"539.778983ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:01:39.352867Z","caller":"traceutil/trace.go:171","msg":"trace[1529447280] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"153.408387ms","start":"2026-08-12T11:01:39.199442Z","end":"2026-08-12T11:01:39.352851Z","steps":["trace[1529447280] 'read index received' (duration: 74.380781ms)","trace[1529447280] 'applied index is now lower than readState.Index' (duration: 79.026636ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:39.353176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.729336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:01:39.353199Z","caller":"traceutil/trace.go:171","msg":"trace[309536397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"153.784858ms","start":"2026-08-12T11:01:39.199409Z","end":"2026-08-12T11:01:39.353194Z","steps":["trace[309536397] 'agreement among raft nodes before linearized reading' (duration: 153.737477ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:45.622075Z","caller":"traceutil/trace.go:171","msg":"trace[811967907] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"106.839994ms","start":"2026-08-12T11:01:45.515208Z","end":"2026-08-12T11:01:45.622048Z","steps":["trace[811967907] 'process raft request' (duration: 61.717673ms)","trace[811967907] 'compare' (duration: 44.943575ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:45.622539Z","caller":"traceutil/trace.go:171","msg":"trace[2056139807] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"106.939697ms","start":"2026-08-12T11:01:45.515587Z","end":"2026-08-12T11:01:45.622527Z","steps":["trace[2056139807] 'process raft request' (duration: 106.407472ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:45.622675Z","caller":"traceutil/trace.go:171","msg":"trace[343405654] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"101.010375ms","start":"2026-08-12T11:01:45.521615Z","end":"2026-08-12T11:01:45.622625Z","steps":["trace[343405654] 'process raft request' (duration: 100.621503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:46.705861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.762995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-12T11:01:46.705936Z","caller":"traceutil/trace.go:171","msg":"trace[619581750] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1394; }","duration":"240.882809ms","start":"2026-08-12T11:01:46.465034Z","end":"2026-08-12T11:01:46.705917Z","steps":["trace[619581750] 'range keys from in-memory index tree' (duration: 240.616341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:46.706153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.625078ms","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-08-12T11:01:46.706225Z","caller":"traceutil/trace.go:171","msg":"trace[1710717979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1394; }","duration":"317.755182ms","start":"2026-08-12T11:01:46.388456Z","end":"2026-08-12T11:01:46.706211Z","steps":["trace[1710717979] 'range keys from in-memory index tree' (duration: 317.421452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:46.706248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.555848ms","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-08-12T11:01:46.706285Z","caller":"traceutil/trace.go:171","msg":"trace[1608673142] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1394; }","duration":"305.634561ms","start":"2026-08-12T11:01:46.400641Z","end":"2026-08-12T11:01:46.706275Z","steps":["trace[1608673142] 'range keys from in-memory index tree' (duration: 305.421154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:46.70627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:46.388436Z","time spent":"317.825294ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T11:01:46.706318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:46.400619Z","time spent":"305.691252ms","remote":"127.0.0.1:44908","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-08-12T11:01:46.706404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.243424ms","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-08-12T11:01:46.706494Z","caller":"traceutil/trace.go:171","msg":"trace[703027972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1394; }","duration":"300.354706ms","start":"2026-08-12T11:01:46.406113Z","end":"2026-08-12T11:01:46.706467Z","steps":["trace[703027972] 'range keys from in-memory index tree' (duration: 300.094789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:46.706548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:46.406102Z","time spent":"300.43406ms","remote":"127.0.0.1:44908","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-08-12T11:01:46.706621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.399219ms","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-08-12T11:01:46.707064Z","caller":"traceutil/trace.go:171","msg":"trace[2075146022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1394; }","duration":"321.033107ms","start":"2026-08-12T11:01:46.386017Z","end":"2026-08-12T11:01:46.70705Z","steps":["trace[2075146022] 'range keys from in-memory index tree' (duration: 320.292916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:46.707148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:46.385996Z","time spent":"321.140191ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T11:01:47.065705Z","caller":"traceutil/trace.go:171","msg":"trace[1424329998] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"354.484799ms","start":"2026-08-12T11:01:46.711182Z","end":"2026-08-12T11:01:47.065667Z","steps":["trace[1424329998] 'process raft request' (duration: 323.460248ms)","trace[1424329998] 'compare' (duration: 30.290911ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:47.065874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:46.711166Z","time spent":"354.618214ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:01:47.065997Z","caller":"traceutil/trace.go:171","msg":"trace[742571033] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"354.368405ms","start":"2026-08-12T11:01:46.711599Z","end":"2026-08-12T11:01:47.065967Z","steps":["trace[742571033] 'process raft request' (duration: 354.327524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.066196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:46.711578Z","time spent":"354.529231ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:01:47.066224Z","caller":"traceutil/trace.go:171","msg":"trace[1198044662] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"354.911421ms","start":"2026-08-12T11:01:46.711299Z","end":"2026-08-12T11:01:47.06621Z","steps":["trace[1198044662] 'process raft request' (duration: 354.403577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.066287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:46.711275Z","time spent":"354.978564ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:01:47.06637Z","caller":"traceutil/trace.go:171","msg":"trace[907584055] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"354.956823ms","start":"2026-08-12T11:01:46.711377Z","end":"2026-08-12T11:01:47.066334Z","steps":["trace[907584055] 'process raft request' (duration: 354.51417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.066545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:46.711359Z","time spent":"355.106648ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:04:46.06037Z","caller":"traceutil/trace.go:171","msg":"trace[1182324838] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"106.186374ms","start":"2026-08-12T11:04:45.954152Z","end":"2026-08-12T11:04:46.060338Z","steps":["trace[1182324838] 'process raft request' (duration: 106.002629ms)"],"step_count":1}