{"level":"warn","ts":"2026-05-14T00:51:15.515637Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T00:51:15.51575Z","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-05-14T00:51:15.51585Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T00:51:15.515869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-14T00:51:15.515897Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T00:51:15.516861Z","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-05-14T00:51:15.516967Z","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-05-14T00:51:15.523303Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.039395ms"} {"level":"info","ts":"2026-05-14T00:51:15.532367Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-05-14T00:51:15.532537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T00:51:15.532562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-05-14T00:51:15.532575Z","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-05-14T00:51:15.532581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-05-14T00:51:15.532612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-05-14T00:51:15.548502Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T00:51:15.551775Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T00:51:15.55295Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-14T00:51:15.555172Z","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-05-14T00:51:15.56084Z","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-05-14T00:51:15.560934Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T00:51:15.561495Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T00:51:15.56165Z","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-05-14T00:51:15.561679Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T00:51:15.561746Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-14T00:51:15.561762Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-14T00:51:15.561748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-05-14T00:51:15.561877Z","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-05-14T00:51:15.561081Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T00:51:15.562748Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T00:51:16.333022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T00:51:16.333082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T00:51:16.333124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-05-14T00:51:16.333145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-05-14T00:51:16.333156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-14T00:51:16.333173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-05-14T00:51:16.333187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-14T00:51:16.334557Z","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-05-14T00:51:16.334657Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T00:51:16.334763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T00:51:16.334816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T00:51:16.335561Z","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-05-14T00:51:16.335702Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T00:51:16.335753Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T00:51:16.338375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T00:51:16.338416Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-05-14T00:51:16.338778Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T00:51:16.338909Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-05-14T00:51:19.462173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.847606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-14T00:51:19.462291Z","caller":"traceutil/trace.go:171","msg":"trace[1969450878] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"810.557071ms","start":"2026-05-14T00:51:18.651719Z","end":"2026-05-14T00:51:19.462276Z","steps":["trace[1969450878] 'read index received' (duration: 217.18769ms)","trace[1969450878] 'applied index is now lower than readState.Index' (duration: 593.368151ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:51:19.462347Z","caller":"traceutil/trace.go:171","msg":"trace[1884512489] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"878.414941ms","start":"2026-05-14T00:51:18.583905Z","end":"2026-05-14T00:51:19.462319Z","steps":["trace[1884512489] 'process raft request' (duration: 284.964349ms)","trace[1884512489] 'compare' (duration: 592.710962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:51:19.462522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:18.583895Z","time spent":"878.507083ms","remote":"127.0.0.1:36296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T00:51:19.46272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.107168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-14T00:51:19.462803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.073886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T00:51:19.462846Z","caller":"traceutil/trace.go:171","msg":"trace[581392853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"811.159448ms","start":"2026-05-14T00:51:18.651675Z","end":"2026-05-14T00:51:19.462835Z","steps":["trace[581392853] 'agreement among raft nodes before linearized reading' (duration: 810.737686ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:19.462846Z","caller":"traceutil/trace.go:171","msg":"trace[188433223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"799.333324ms","start":"2026-05-14T00:51:18.663476Z","end":"2026-05-14T00:51:19.462809Z","steps":["trace[188433223] 'agreement among raft nodes before linearized reading' (duration: 798.970664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:19.462894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:18.651657Z","time spent":"811.223941ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T00:51:19.462908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:18.663443Z","time spent":"799.451948ms","remote":"127.0.0.1:35892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T00:51:20.092787Z","caller":"traceutil/trace.go:171","msg":"trace[1848093429] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"440.744467ms","start":"2026-05-14T00:51:19.652017Z","end":"2026-05-14T00:51:20.092761Z","steps":["trace[1848093429] 'read index received' (duration: 440.545932ms)","trace[1848093429] 'applied index is now lower than readState.Index' (duration: 197.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:51:20.092886Z","caller":"traceutil/trace.go:171","msg":"trace[199907037] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"562.279108ms","start":"2026-05-14T00:51:19.530559Z","end":"2026-05-14T00:51:20.092838Z","steps":["trace[199907037] 'process raft request' (duration: 562.067172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:20.093701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:19.530538Z","time spent":"562.399372ms","remote":"127.0.0.1:36296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T00:51:20.094653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.618859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-14T00:51:20.094756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.9953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T00:51:20.094759Z","caller":"traceutil/trace.go:171","msg":"trace[1576733868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"442.745022ms","start":"2026-05-14T00:51:19.651962Z","end":"2026-05-14T00:51:20.094707Z","steps":["trace[1576733868] 'agreement among raft nodes before linearized reading' (duration: 440.924853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:20.094847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:19.651942Z","time spent":"442.893717ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T00:51:20.094865Z","caller":"traceutil/trace.go:171","msg":"trace[1328813858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:77; }","duration":"227.159616ms","start":"2026-05-14T00:51:19.867665Z","end":"2026-05-14T00:51:20.094825Z","steps":["trace[1328813858] 'agreement among raft nodes before linearized reading' (duration: 225.277574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:20.096858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.687517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T00:51:20.096903Z","caller":"traceutil/trace.go:171","msg":"trace[1549073718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"432.771039ms","start":"2026-05-14T00:51:19.664118Z","end":"2026-05-14T00:51:20.096889Z","steps":["trace[1549073718] 'agreement among raft nodes before linearized reading' (duration: 428.78037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:20.09793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:19.664096Z","time spent":"433.819238ms","remote":"127.0.0.1:35892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T00:51:49.052879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726251372040346,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T00:51:49.407325Z","caller":"traceutil/trace.go:171","msg":"trace[1749402838] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"865.529587ms","start":"2026-05-14T00:51:48.541771Z","end":"2026-05-14T00:51:49.4073Z","steps":["trace[1749402838] 'process raft request' (duration: 856.298585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:49.408244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:48.54175Z","time spent":"865.646931ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:51:49.591859Z","caller":"traceutil/trace.go:171","msg":"trace[207608916] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.015969571s","start":"2026-05-14T00:51:48.575842Z","end":"2026-05-14T00:51:49.591812Z","steps":["trace[207608916] 'process raft request' (duration: 932.478613ms)","trace[207608916] 'compare' (duration: 83.19821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:51:49.59206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:48.575819Z","time spent":"1.016132335s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:51:49.598911Z","caller":"traceutil/trace.go:171","msg":"trace[672343036] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.014126449s","start":"2026-05-14T00:51:48.584758Z","end":"2026-05-14T00:51:49.598885Z","steps":["trace[672343036] 'process raft request' (duration: 1.007288832s)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:49.598954Z","caller":"traceutil/trace.go:171","msg":"trace[983665840] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:589; }","duration":"1.046104687s","start":"2026-05-14T00:51:48.55281Z","end":"2026-05-14T00:51:49.598915Z","steps":["trace[983665840] 'read index received' (duration: 845.284214ms)","trace[983665840] 'applied index is now lower than readState.Index' (duration: 200.819643ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:51:49.599122Z","caller":"traceutil/trace.go:171","msg":"trace[1517723612] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"932.563474ms","start":"2026-05-14T00:51:48.666487Z","end":"2026-05-14T00:51:49.599051Z","steps":["trace[1517723612] 'process raft request' (duration: 932.336738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:49.599158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:48.584739Z","time spent":"1.014232843s","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:51:49.599145Z","caller":"traceutil/trace.go:171","msg":"trace[664824741] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"731.801313ms","start":"2026-05-14T00:51:48.867288Z","end":"2026-05-14T00:51:49.599089Z","steps":["trace[664824741] 'process raft request' (duration: 731.588827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:49.599247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:48.666458Z","time spent":"932.72154ms","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T00:51:49.59936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.405631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:49.599439Z","caller":"traceutil/trace.go:171","msg":"trace[1093781665] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:574; }","duration":"335.516644ms","start":"2026-05-14T00:51:49.263896Z","end":"2026-05-14T00:51:49.599413Z","steps":["trace[1093781665] 'agreement among raft nodes before linearized reading' (duration: 335.36702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:49.59943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:48.867258Z","time spent":"732.074891ms","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T00:51:49.599475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:49.263883Z","time spent":"335.583696ms","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-14T00:51:49.599588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046766655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"warn","ts":"2026-05-14T00:51:49.599687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.509912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:49.599725Z","caller":"traceutil/trace.go:171","msg":"trace[261764355] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:574; }","duration":"136.551902ms","start":"2026-05-14T00:51:49.46316Z","end":"2026-05-14T00:51:49.599712Z","steps":["trace[261764355] 'agreement among raft nodes before linearized reading' (duration: 136.47695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:49.59965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.377352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:49.599836Z","caller":"traceutil/trace.go:171","msg":"trace[1333087747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"365.571959ms","start":"2026-05-14T00:51:49.234224Z","end":"2026-05-14T00:51:49.599796Z","steps":["trace[1333087747] 'agreement among raft nodes before linearized reading' (duration: 365.390004ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:49.599692Z","caller":"traceutil/trace.go:171","msg":"trace[1503834788] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:574; }","duration":"1.046894809s","start":"2026-05-14T00:51:48.55278Z","end":"2026-05-14T00:51:49.599675Z","steps":["trace[1503834788] 'agreement among raft nodes before linearized reading' (duration: 1.046433356s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:49.600044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:48.552765Z","time spent":"1.047267558s","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151260,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-14T00:51:49.599932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:49.234204Z","time spent":"365.664631ms","remote":"127.0.0.1:35900","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-05-14T00:51:49.599992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.321985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:49.600181Z","caller":"traceutil/trace.go:171","msg":"trace[1832173610] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:574; }","duration":"537.54349ms","start":"2026-05-14T00:51:49.062627Z","end":"2026-05-14T00:51:49.600171Z","steps":["trace[1832173610] 'agreement among raft nodes before linearized reading' (duration: 536.615165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:49.600213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:49.06261Z","time spent":"537.594322ms","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-05-14T00:51:49.753053Z","caller":"traceutil/trace.go:171","msg":"trace[1926129716] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"108.140353ms","start":"2026-05-14T00:51:49.64488Z","end":"2026-05-14T00:51:49.75302Z","steps":["trace[1926129716] 'process raft request' (duration: 79.152908ms)","trace[1926129716] 'compare' (duration: 28.758599ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:51:50.504543Z","caller":"traceutil/trace.go:171","msg":"trace[2081682895] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"179.217181ms","start":"2026-05-14T00:51:50.325308Z","end":"2026-05-14T00:51:50.504525Z","steps":["trace[2081682895] 'process raft request' (duration: 178.761629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:55.736144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726251372040491,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T00:51:56.237284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726251372040491,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T00:51:56.32703Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.249523841s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T00:51:56.328207Z","caller":"traceutil/trace.go:171","msg":"trace[140610452] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.252028309s","start":"2026-05-14T00:51:55.076162Z","end":"2026-05-14T00:51:56.328191Z","steps":["trace[140610452] 'process raft request' (duration: 1.251549356s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.328359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:55.076135Z","time spent":"1.252131013s","remote":"127.0.0.1:36004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T00:51:56.642768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.37188ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-14T00:51:56.642936Z","caller":"traceutil/trace.go:171","msg":"trace[181236273] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"1.406990776s","start":"2026-05-14T00:51:55.235924Z","end":"2026-05-14T00:51:56.642915Z","steps":["trace[181236273] 'read index received' (duration: 1.091550393s)","trace[181236273] 'applied index is now lower than readState.Index' (duration: 315.438503ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:51:56.64303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407116539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:56.643062Z","caller":"traceutil/trace.go:171","msg":"trace[171125323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.407194421s","start":"2026-05-14T00:51:55.235859Z","end":"2026-05-14T00:51:56.643053Z","steps":["trace[171125323] 'agreement among raft nodes before linearized reading' (duration: 1.40713742s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:55.235835Z","time spent":"1.407249172s","remote":"127.0.0.1:35900","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-05-14T00:51:56.643205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.904319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T00:51:56.643236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213226146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T00:51:56.643266Z","caller":"traceutil/trace.go:171","msg":"trace[596900818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.213283997s","start":"2026-05-14T00:51:55.429975Z","end":"2026-05-14T00:51:56.643259Z","steps":["trace[596900818] 'agreement among raft nodes before linearized reading' (duration: 1.213169194s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.205851ms","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-05-14T00:51:56.64329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:55.429959Z","time spent":"1.213324988s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T00:51:56.643301Z","caller":"traceutil/trace.go:171","msg":"trace[146104133] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"160.270311ms","start":"2026-05-14T00:51:56.483024Z","end":"2026-05-14T00:51:56.643295Z","steps":["trace[146104133] 'agreement among raft nodes before linearized reading' (duration: 160.22753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.64335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.183529ms","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-05-14T00:51:56.64342Z","caller":"traceutil/trace.go:171","msg":"trace[1484686294] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"218.23665ms","start":"2026-05-14T00:51:56.425152Z","end":"2026-05-14T00:51:56.643389Z","steps":["trace[1484686294] 'agreement among raft nodes before linearized reading' (duration: 218.140567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.946209ms","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":"warn","ts":"2026-05-14T00:51:56.643433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.775415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T00:51:56.643394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.796978ms","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-05-14T00:51:56.643453Z","caller":"traceutil/trace.go:171","msg":"trace[1636733713] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"218.993392ms","start":"2026-05-14T00:51:56.424453Z","end":"2026-05-14T00:51:56.643447Z","steps":["trace[1636733713] 'agreement among raft nodes before linearized reading' (duration: 218.95358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.862745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:56.643502Z","caller":"traceutil/trace.go:171","msg":"trace[1545169468] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:627; }","duration":"130.925158ms","start":"2026-05-14T00:51:56.512571Z","end":"2026-05-14T00:51:56.643497Z","steps":["trace[1545169468] 'agreement among raft nodes before linearized reading' (duration: 130.896067ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:56.643501Z","caller":"traceutil/trace.go:171","msg":"trace[1991507511] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"160.947281ms","start":"2026-05-14T00:51:56.482512Z","end":"2026-05-14T00:51:56.64346Z","steps":["trace[1991507511] 'agreement among raft nodes before linearized reading' (duration: 160.777016ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:56.64352Z","caller":"traceutil/trace.go:171","msg":"trace[723418313] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"262.479543ms","start":"2026-05-14T00:51:56.381028Z","end":"2026-05-14T00:51:56.643508Z","steps":["trace[723418313] 'process raft request' (duration: 261.807665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.429838ms","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-05-14T00:51:56.643618Z","caller":"traceutil/trace.go:171","msg":"trace[444884826] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"236.499591ms","start":"2026-05-14T00:51:56.407112Z","end":"2026-05-14T00:51:56.643612Z","steps":["trace[444884826] 'agreement among raft nodes before linearized reading' (duration: 236.4578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.642181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:56.64376Z","caller":"traceutil/trace.go:171","msg":"trace[938203512] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"372.697083ms","start":"2026-05-14T00:51:56.271057Z","end":"2026-05-14T00:51:56.643754Z","steps":["trace[938203512] 'agreement among raft nodes before linearized reading' (duration: 372.653472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.996729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T00:51:56.643787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:56.27104Z","time spent":"372.739914ms","remote":"127.0.0.1:34870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-14T00:51:56.64379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.736928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:56.643802Z","caller":"traceutil/trace.go:171","msg":"trace[641009334] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"355.027569ms","start":"2026-05-14T00:51:56.288767Z","end":"2026-05-14T00:51:56.643795Z","steps":["trace[641009334] 'agreement among raft nodes before linearized reading' (duration: 354.985068ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:56.643808Z","caller":"traceutil/trace.go:171","msg":"trace[326977075] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"355.768688ms","start":"2026-05-14T00:51:56.288034Z","end":"2026-05-14T00:51:56.643803Z","steps":["trace[326977075] 'agreement among raft nodes before linearized reading' (duration: 355.743638ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:56.643455Z","caller":"traceutil/trace.go:171","msg":"trace[475451419] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:627; }","duration":"130.823245ms","start":"2026-05-14T00:51:56.512625Z","end":"2026-05-14T00:51:56.643448Z","steps":["trace[475451419] 'agreement among raft nodes before linearized reading' (duration: 130.791943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.64383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:56.288752Z","time spent":"355.06892ms","remote":"127.0.0.1:34884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-14T00:51:56.643854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.83066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:56.643871Z","caller":"traceutil/trace.go:171","msg":"trace[1027490979] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"355.860923ms","start":"2026-05-14T00:51:56.288005Z","end":"2026-05-14T00:51:56.643866Z","steps":["trace[1027490979] 'agreement among raft nodes before linearized reading' (duration: 355.835521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:56.287996Z","time spent":"355.892263ms","remote":"127.0.0.1:34884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-05-14T00:51:56.643938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.259292ms","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-05-14T00:51:56.643964Z","caller":"traceutil/trace.go:171","msg":"trace[1723964128] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"237.305972ms","start":"2026-05-14T00:51:56.406651Z","end":"2026-05-14T00:51:56.643957Z","steps":["trace[1723964128] 'agreement among raft nodes before linearized reading' (duration: 237.269031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.644002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.490328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T00:51:56.644026Z","caller":"traceutil/trace.go:171","msg":"trace[1886953697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"726.578451ms","start":"2026-05-14T00:51:55.917442Z","end":"2026-05-14T00:51:56.64402Z","steps":["trace[1886953697] 'agreement among raft nodes before linearized reading' (duration: 726.463178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.644085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:55.917424Z","time spent":"726.654703ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T00:51:56.644105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.433587ms","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-05-14T00:51:56.644125Z","caller":"traceutil/trace.go:171","msg":"trace[1764697072] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"237.476547ms","start":"2026-05-14T00:51:56.406643Z","end":"2026-05-14T00:51:56.64412Z","steps":["trace[1764697072] 'agreement among raft nodes before linearized reading' (duration: 237.440796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.64412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.544389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:56.64423Z","caller":"traceutil/trace.go:171","msg":"trace[1480251034] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"372.663343ms","start":"2026-05-14T00:51:56.271553Z","end":"2026-05-14T00:51:56.644216Z","steps":["trace[1480251034] 'agreement among raft nodes before linearized reading' (duration: 372.508428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.644248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.048399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T00:51:56.644291Z","caller":"traceutil/trace.go:171","msg":"trace[575291188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"347.100252ms","start":"2026-05-14T00:51:56.297165Z","end":"2026-05-14T00:51:56.644265Z","steps":["trace[575291188] 'agreement among raft nodes before linearized reading' (duration: 347.039051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.64428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:56.271548Z","time spent":"372.716894ms","remote":"127.0.0.1:34870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-14T00:51:56.644316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:56.29715Z","time spent":"347.160584ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T00:51:56.644241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.592748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-14T00:51:56.643829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:56.288022Z","time spent":"355.80136ms","remote":"127.0.0.1:34884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-14T00:51:56.644409Z","caller":"traceutil/trace.go:171","msg":"trace[1401780999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:627; }","duration":"862.795503ms","start":"2026-05-14T00:51:55.781589Z","end":"2026-05-14T00:51:56.644385Z","steps":["trace[1401780999] 'agreement among raft nodes before linearized reading' (duration: 862.598268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.644443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:55.78157Z","time spent":"862.865665ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T00:51:56.645105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.598936ms","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-05-14T00:51:56.645144Z","caller":"traceutil/trace.go:171","msg":"trace[1187537282] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"162.654196ms","start":"2026-05-14T00:51:56.482472Z","end":"2026-05-14T00:51:56.645126Z","steps":["trace[1187537282] 'agreement among raft nodes before linearized reading' (duration: 162.599455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.643758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.564889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:51:56.645226Z","caller":"traceutil/trace.go:171","msg":"trace[1636748359] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"374.098682ms","start":"2026-05-14T00:51:56.271116Z","end":"2026-05-14T00:51:56.645214Z","steps":["trace[1636748359] 'agreement among raft nodes before linearized reading' (duration: 372.58122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:56.645255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:56.271095Z","time spent":"374.150493ms","remote":"127.0.0.1:34870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T00:51:56.643253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.702024ms","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-05-14T00:51:56.645522Z","caller":"traceutil/trace.go:171","msg":"trace[1795897327] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"221.007866ms","start":"2026-05-14T00:51:56.424506Z","end":"2026-05-14T00:51:56.645513Z","steps":["trace[1795897327] 'agreement among raft nodes before linearized reading' (duration: 218.656282ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:56.643234Z","caller":"traceutil/trace.go:171","msg":"trace[465700351] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:627; }","duration":"129.947001ms","start":"2026-05-14T00:51:56.513279Z","end":"2026-05-14T00:51:56.643226Z","steps":["trace[465700351] 'agreement among raft nodes before linearized reading' (duration: 129.897979ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:57.937286Z","caller":"traceutil/trace.go:171","msg":"trace[1642038778] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"108.326327ms","start":"2026-05-14T00:51:57.82893Z","end":"2026-05-14T00:51:57.937256Z","steps":["trace[1642038778] 'process raft request' (duration: 108.146273ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:59.097575Z","caller":"traceutil/trace.go:171","msg":"trace[1522621462] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"361.244749ms","start":"2026-05-14T00:51:58.736303Z","end":"2026-05-14T00:51:59.097548Z","steps":["trace[1522621462] 'read index received' (duration: 361.022623ms)","trace[1522621462] 'applied index is now lower than readState.Index' (duration: 221.256µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:51:59.097726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.412693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T00:51:59.097809Z","caller":"traceutil/trace.go:171","msg":"trace[1965773603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"361.546628ms","start":"2026-05-14T00:51:58.736251Z","end":"2026-05-14T00:51:59.097798Z","steps":["trace[1965773603] 'agreement among raft nodes before linearized reading' (duration: 361.422224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:59.097886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:58.736226Z","time spent":"361.64833ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T00:51:59.097937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.661349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T00:51:59.098015Z","caller":"traceutil/trace.go:171","msg":"trace[194500511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"358.769461ms","start":"2026-05-14T00:51:58.739228Z","end":"2026-05-14T00:51:59.097997Z","steps":["trace[194500511] 'agreement among raft nodes before linearized reading' (duration: 358.628217ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:59.097741Z","caller":"traceutil/trace.go:171","msg":"trace[5730190] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"362.161024ms","start":"2026-05-14T00:51:58.735457Z","end":"2026-05-14T00:51:59.097618Z","steps":["trace[5730190] 'process raft request' (duration: 361.947288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:59.098065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:58.739222Z","time spent":"358.832513ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T00:51:59.098129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.029871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T00:51:59.098558Z","caller":"traceutil/trace.go:171","msg":"trace[816257504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:642; }","duration":"358.475204ms","start":"2026-05-14T00:51:58.740068Z","end":"2026-05-14T00:51:59.098544Z","steps":["trace[816257504] 'agreement among raft nodes before linearized reading' (duration: 358.023481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:59.098601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:58.740051Z","time spent":"358.537165ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T00:51:59.098362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:58.735439Z","time spent":"362.817533ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T00:51:59.098183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.909745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T00:51:59.098821Z","caller":"traceutil/trace.go:171","msg":"trace[913403592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"359.555713ms","start":"2026-05-14T00:51:58.739256Z","end":"2026-05-14T00:51:59.098812Z","steps":["trace[913403592] 'agreement among raft nodes before linearized reading' (duration: 358.895315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:51:59.09886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:51:58.739252Z","time spent":"359.598793ms","remote":"127.0.0.1:36206","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-05-14T00:51:59.274702Z","caller":"traceutil/trace.go:171","msg":"trace[304412258] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"172.674262ms","start":"2026-05-14T00:51:59.101997Z","end":"2026-05-14T00:51:59.274671Z","steps":["trace[304412258] 'process raft request' (duration: 167.393077ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:59.276417Z","caller":"traceutil/trace.go:171","msg":"trace[1009463618] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"173.619428ms","start":"2026-05-14T00:51:59.102776Z","end":"2026-05-14T00:51:59.276395Z","steps":["trace[1009463618] 'process raft request' (duration: 173.512635ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:59.276812Z","caller":"traceutil/trace.go:171","msg":"trace[832246411] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"173.588147ms","start":"2026-05-14T00:51:59.10316Z","end":"2026-05-14T00:51:59.276749Z","steps":["trace[832246411] 'process raft request' (duration: 173.192137ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:51:59.277225Z","caller":"traceutil/trace.go:171","msg":"trace[391393351] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"174.712887ms","start":"2026-05-14T00:51:59.102475Z","end":"2026-05-14T00:51:59.277188Z","steps":["trace[391393351] 'process raft request' (duration: 173.68755ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:01.552949Z","caller":"traceutil/trace.go:171","msg":"trace[821608782] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"266.485073ms","start":"2026-05-14T00:52:01.286439Z","end":"2026-05-14T00:52:01.552924Z","steps":["trace[821608782] 'process raft request' (duration: 266.295697ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:01.553156Z","caller":"traceutil/trace.go:171","msg":"trace[1125037295] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"266.508004ms","start":"2026-05-14T00:52:01.286633Z","end":"2026-05-14T00:52:01.553141Z","steps":["trace[1125037295] 'process raft request' (duration: 266.253326ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:01.55328Z","caller":"traceutil/trace.go:171","msg":"trace[1167362464] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"264.501918ms","start":"2026-05-14T00:52:01.288769Z","end":"2026-05-14T00:52:01.553271Z","steps":["trace[1167362464] 'process raft request' (duration: 264.314843ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:07.127417Z","caller":"traceutil/trace.go:171","msg":"trace[897623730] transaction","detail":"{read_only:false; number_of_response:0; response_revision:694; }","duration":"148.431197ms","start":"2026-05-14T00:52:06.978961Z","end":"2026-05-14T00:52:07.127393Z","steps":["trace[897623730] 'process raft request' (duration: 105.287936ms)","trace[897623730] 'compare' (duration: 43.097321ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:09.184797Z","caller":"traceutil/trace.go:171","msg":"trace[626978808] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"315.290509ms","start":"2026-05-14T00:52:08.869483Z","end":"2026-05-14T00:52:09.184774Z","steps":["trace[626978808] 'process raft request' (duration: 315.133045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:09.184946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:08.869436Z","time spent":"315.434264ms","remote":"127.0.0.1:36110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:52:13.622171Z","caller":"traceutil/trace.go:171","msg":"trace[535960085] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"207.751913ms","start":"2026-05-14T00:52:13.414398Z","end":"2026-05-14T00:52:13.62215Z","steps":["trace[535960085] 'read index received' (duration: 207.63431ms)","trace[535960085] 'applied index is now lower than readState.Index' (duration: 116.383µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:13.622268Z","caller":"traceutil/trace.go:171","msg":"trace[1836375834] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"389.123123ms","start":"2026-05-14T00:52:13.233101Z","end":"2026-05-14T00:52:13.622224Z","steps":["trace[1836375834] 'process raft request' (duration: 388.921968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:13.62236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.953339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-14T00:52:13.622399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:13.23309Z","time spent":"389.239126ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:52:13.622405Z","caller":"traceutil/trace.go:171","msg":"trace[1085324530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"208.043772ms","start":"2026-05-14T00:52:13.414348Z","end":"2026-05-14T00:52:13.622392Z","steps":["trace[1085324530] 'agreement among raft nodes before linearized reading' (duration: 207.916347ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:13.743205Z","caller":"traceutil/trace.go:171","msg":"trace[669726243] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"315.388243ms","start":"2026-05-14T00:52:13.427779Z","end":"2026-05-14T00:52:13.743167Z","steps":["trace[669726243] 'process raft request' (duration: 315.235419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:13.743571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.356278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-14T00:52:13.743592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:13.427754Z","time spent":"315.541067ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:52:13.743652Z","caller":"traceutil/trace.go:171","msg":"trace[640556590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"136.552361ms","start":"2026-05-14T00:52:13.607082Z","end":"2026-05-14T00:52:13.743635Z","steps":["trace[640556590] 'agreement among raft nodes before linearized reading' (duration: 136.114489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:13.743705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.829532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T00:52:13.743166Z","caller":"traceutil/trace.go:171","msg":"trace[567418868] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"120.901423ms","start":"2026-05-14T00:52:13.62224Z","end":"2026-05-14T00:52:13.743142Z","steps":["trace[567418868] 'read index received' (duration: 120.794811ms)","trace[567418868] 'applied index is now lower than readState.Index' (duration: 105.492µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:13.743788Z","caller":"traceutil/trace.go:171","msg":"trace[1628189201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"135.968837ms","start":"2026-05-14T00:52:13.607801Z","end":"2026-05-14T00:52:13.74377Z","steps":["trace[1628189201] 'agreement among raft nodes before linearized reading' (duration: 135.375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:13.743797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.600834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T00:52:13.743871Z","caller":"traceutil/trace.go:171","msg":"trace[1686229909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"136.722986ms","start":"2026-05-14T00:52:13.607129Z","end":"2026-05-14T00:52:13.743853Z","steps":["trace[1686229909] 'agreement among raft nodes before linearized reading' (duration: 136.435199ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:13.744149Z","caller":"traceutil/trace.go:171","msg":"trace[1697045663] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"116.983806ms","start":"2026-05-14T00:52:13.627132Z","end":"2026-05-14T00:52:13.744116Z","steps":["trace[1697045663] 'process raft request' (duration: 116.73502ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:17.378191Z","caller":"traceutil/trace.go:171","msg":"trace[224325299] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"142.74422ms","start":"2026-05-14T00:52:17.235427Z","end":"2026-05-14T00:52:17.378171Z","steps":["trace[224325299] 'read index received' (duration: 142.581135ms)","trace[224325299] 'applied index is now lower than readState.Index' (duration: 161.935µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:17.378262Z","caller":"traceutil/trace.go:171","msg":"trace[312994163] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"437.825152ms","start":"2026-05-14T00:52:16.940412Z","end":"2026-05-14T00:52:17.378237Z","steps":["trace[312994163] 'process raft request' (duration: 437.658037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:17.378291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.857153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:52:17.378316Z","caller":"traceutil/trace.go:171","msg":"trace[1945184669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"142.930584ms","start":"2026-05-14T00:52:17.235377Z","end":"2026-05-14T00:52:17.378308Z","steps":["trace[1945184669] 'agreement among raft nodes before linearized reading' (duration: 142.871643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:17.378383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:16.940393Z","time spent":"437.916824ms","remote":"127.0.0.1:36170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:52:31.517312Z","caller":"traceutil/trace.go:171","msg":"trace[1109633717] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"144.107717ms","start":"2026-05-14T00:52:31.373175Z","end":"2026-05-14T00:52:31.517283Z","steps":["trace[1109633717] 'process raft request' (duration: 143.901291ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:46.275699Z","caller":"traceutil/trace.go:171","msg":"trace[1306968842] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"159.728975ms","start":"2026-05-14T00:52:46.115933Z","end":"2026-05-14T00:52:46.275662Z","steps":["trace[1306968842] 'process raft request' (duration: 67.848588ms)","trace[1306968842] 'compare' (duration: 91.688402ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:50.186593Z","caller":"traceutil/trace.go:171","msg":"trace[1184018349] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"113.663223ms","start":"2026-05-14T00:52:50.072909Z","end":"2026-05-14T00:52:50.186572Z","steps":["trace[1184018349] 'process raft request' (duration: 112.253525ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:50.186769Z","caller":"traceutil/trace.go:171","msg":"trace[104225152] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"112.768428ms","start":"2026-05-14T00:52:50.073986Z","end":"2026-05-14T00:52:50.186755Z","steps":["trace[104225152] 'process raft request' (duration: 112.552603ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:50.186773Z","caller":"traceutil/trace.go:171","msg":"trace[1171046912] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:999; }","duration":"101.89271ms","start":"2026-05-14T00:52:50.084862Z","end":"2026-05-14T00:52:50.186754Z","steps":["trace[1171046912] 'read index received' (duration: 100.265396ms)","trace[1171046912] 'applied index is now lower than readState.Index' (duration: 1.626634ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:50.186811Z","caller":"traceutil/trace.go:171","msg":"trace[757570820] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"111.70452ms","start":"2026-05-14T00:52:50.075085Z","end":"2026-05-14T00:52:50.18679Z","steps":["trace[757570820] 'process raft request' (duration: 111.609547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:50.18691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.028974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T00:52:50.186935Z","caller":"traceutil/trace.go:171","msg":"trace[1410144671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"102.086717ms","start":"2026-05-14T00:52:50.084839Z","end":"2026-05-14T00:52:50.186926Z","steps":["trace[1410144671] 'agreement among raft nodes before linearized reading' (duration: 101.984724ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:50.341588Z","caller":"traceutil/trace.go:171","msg":"trace[805021497] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"107.667629ms","start":"2026-05-14T00:52:50.233897Z","end":"2026-05-14T00:52:50.341565Z","steps":["trace[805021497] 'read index received' (duration: 82.003326ms)","trace[805021497] 'applied index is now lower than readState.Index' (duration: 25.663483ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:50.3416Z","caller":"traceutil/trace.go:171","msg":"trace[1836575236] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"133.241651ms","start":"2026-05-14T00:52:50.208329Z","end":"2026-05-14T00:52:50.341571Z","steps":["trace[1836575236] 'process raft request' (duration: 107.555916ms)","trace[1836575236] 'compare' (duration: 25.493339ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:52:50.341892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.983077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:52:50.341941Z","caller":"traceutil/trace.go:171","msg":"trace[736302815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"108.073801ms","start":"2026-05-14T00:52:50.233852Z","end":"2026-05-14T00:52:50.341926Z","steps":["trace[736302815] 'agreement among raft nodes before linearized reading' (duration: 107.791223ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:52.304223Z","caller":"traceutil/trace.go:171","msg":"trace[1653057305] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"224.369006ms","start":"2026-05-14T00:52:52.079826Z","end":"2026-05-14T00:52:52.304195Z","steps":["trace[1653057305] 'read index received' (duration: 224.131389ms)","trace[1653057305] 'applied index is now lower than readState.Index' (duration: 236.527µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:52.304386Z","caller":"traceutil/trace.go:171","msg":"trace[1971013138] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"344.805245ms","start":"2026-05-14T00:52:51.959546Z","end":"2026-05-14T00:52:52.304352Z","steps":["trace[1971013138] 'process raft request' (duration: 344.490026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:52.304534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.54678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-14T00:52:52.304535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.687934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T00:52:52.304784Z","caller":"traceutil/trace.go:171","msg":"trace[1699982448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"224.985093ms","start":"2026-05-14T00:52:52.079783Z","end":"2026-05-14T00:52:52.304768Z","steps":["trace[1699982448] 'agreement among raft nodes before linearized reading' (duration: 224.583932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:52.304556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:51.959532Z","time spent":"344.923218ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:52:52.304598Z","caller":"traceutil/trace.go:171","msg":"trace[1640242597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"113.654133ms","start":"2026-05-14T00:52:52.190927Z","end":"2026-05-14T00:52:52.304581Z","steps":["trace[1640242597] 'agreement among raft nodes before linearized reading' (duration: 113.462157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:52.3047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.525203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T00:52:52.305013Z","caller":"traceutil/trace.go:171","msg":"trace[123547011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"108.896292ms","start":"2026-05-14T00:52:52.196101Z","end":"2026-05-14T00:52:52.304998Z","steps":["trace[123547011] 'agreement among raft nodes before linearized reading' (duration: 108.496851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:52.304704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.579672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T00:52:52.30508Z","caller":"traceutil/trace.go:171","msg":"trace[822758580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"113.997573ms","start":"2026-05-14T00:52:52.191076Z","end":"2026-05-14T00:52:52.305073Z","steps":["trace[822758580] 'agreement among raft nodes before linearized reading' (duration: 113.530359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:53.229226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.030987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T00:52:53.229346Z","caller":"traceutil/trace.go:171","msg":"trace[143204292] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"920.923216ms","start":"2026-05-14T00:52:52.308399Z","end":"2026-05-14T00:52:53.229323Z","steps":["trace[143204292] 'process raft request' (duration: 562.674783ms)","trace[143204292] 'compare' (duration: 357.509833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:52:53.229418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:52.308372Z","time spent":"921.009568ms","remote":"127.0.0.1:36206","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-05-14T00:52:53.365192Z","caller":"traceutil/trace.go:171","msg":"trace[750393594] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.054237597s","start":"2026-05-14T00:52:52.310935Z","end":"2026-05-14T00:52:53.365173Z","steps":["trace[750393594] 'process raft request' (duration: 1.054168996s)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:53.365193Z","caller":"traceutil/trace.go:171","msg":"trace[342897849] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.056329625s","start":"2026-05-14T00:52:52.308822Z","end":"2026-05-14T00:52:53.365151Z","steps":["trace[342897849] 'process raft request' (duration: 1.0561697s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:53.365334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:52.310925Z","time spent":"1.05433186s","remote":"127.0.0.1:36206","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-05-14T00:52:53.365466Z","caller":"traceutil/trace.go:171","msg":"trace[1858753159] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1011; }","duration":"616.705874ms","start":"2026-05-14T00:52:52.748731Z","end":"2026-05-14T00:52:53.365437Z","steps":["trace[1858753159] 'read index received' (duration: 122.358172ms)","trace[1858753159] 'applied index is now lower than readState.Index' (duration: 494.347162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:52:53.365491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:52.308804Z","time spent":"1.056476589s","remote":"127.0.0.1:36206","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-05-14T00:52:53.365551Z","caller":"traceutil/trace.go:171","msg":"trace[479943865] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.054559447s","start":"2026-05-14T00:52:52.310982Z","end":"2026-05-14T00:52:53.365541Z","steps":["trace[479943865] 'process raft request' (duration: 1.054160945s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:53.365603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:52.310977Z","time spent":"1.054597917s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T00:52:53.3657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.97574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:52:53.36574Z","caller":"traceutil/trace.go:171","msg":"trace[1888723262] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:980; }","duration":"617.038981ms","start":"2026-05-14T00:52:52.74869Z","end":"2026-05-14T00:52:53.36573Z","steps":["trace[1888723262] 'agreement among raft nodes before linearized reading' (duration: 616.949218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:53.365771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:52.748628Z","time spent":"617.135576ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-14T00:52:53.365786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.720064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:52:53.365811Z","caller":"traceutil/trace.go:171","msg":"trace[511084937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"129.744074ms","start":"2026-05-14T00:52:53.236058Z","end":"2026-05-14T00:52:53.365802Z","steps":["trace[511084937] 'agreement among raft nodes before linearized reading' (duration: 129.704794ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:53.365905Z","caller":"traceutil/trace.go:171","msg":"trace[1474524307] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.018267092s","start":"2026-05-14T00:52:52.347629Z","end":"2026-05-14T00:52:53.365896Z","steps":["trace[1474524307] 'process raft request' (duration: 1.017720747s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:53.365962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:52.347608Z","time spent":"1.018319743s","remote":"127.0.0.1:36206","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-05-14T00:52:55.475823Z","caller":"traceutil/trace.go:171","msg":"trace[346871396] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"102.876378ms","start":"2026-05-14T00:52:55.372929Z","end":"2026-05-14T00:52:55.475805Z","steps":["trace[346871396] 'process raft request' (duration: 100.39347ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:55.47608Z","caller":"traceutil/trace.go:171","msg":"trace[1428288821] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"101.926803ms","start":"2026-05-14T00:52:55.374135Z","end":"2026-05-14T00:52:55.476062Z","steps":["trace[1428288821] 'process raft request' (duration: 101.83693ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:55.476095Z","caller":"traceutil/trace.go:171","msg":"trace[906295541] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"103.024222ms","start":"2026-05-14T00:52:55.373049Z","end":"2026-05-14T00:52:55.476074Z","steps":["trace[906295541] 'process raft request' (duration: 102.728994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:59.56223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.643564ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:52:59.562353Z","caller":"traceutil/trace.go:171","msg":"trace[898418352] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:995; }","duration":"201.785187ms","start":"2026-05-14T00:52:59.360551Z","end":"2026-05-14T00:52:59.562337Z","steps":["trace[898418352] 'range keys from in-memory index tree' (duration: 201.628533ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:52:59.771647Z","caller":"traceutil/trace.go:171","msg":"trace[1403441032] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"301.281103ms","start":"2026-05-14T00:52:59.47033Z","end":"2026-05-14T00:52:59.771611Z","steps":["trace[1403441032] 'read index received' (duration: 300.915793ms)","trace[1403441032] 'applied index is now lower than readState.Index' (duration: 364.01µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:52:59.771757Z","caller":"traceutil/trace.go:171","msg":"trace[718804992] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"331.704266ms","start":"2026-05-14T00:52:59.440022Z","end":"2026-05-14T00:52:59.771726Z","steps":["trace[718804992] 'process raft request' (duration: 331.374567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:59.771913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:59.439994Z","time spent":"331.84794ms","remote":"127.0.0.1:36206","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-05-14T00:52:59.771964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.564861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T00:52:59.772009Z","caller":"traceutil/trace.go:171","msg":"trace[780151802] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:996; }","duration":"301.718656ms","start":"2026-05-14T00:52:59.470277Z","end":"2026-05-14T00:52:59.771995Z","steps":["trace[780151802] 'agreement among raft nodes before linearized reading' (duration: 301.564631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:59.77206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:52:59.470229Z","time spent":"301.816306ms","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-14T00:52:59.772119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.231025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T00:52:59.772194Z","caller":"traceutil/trace.go:171","msg":"trace[1622282289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"269.354638ms","start":"2026-05-14T00:52:59.502822Z","end":"2026-05-14T00:52:59.772176Z","steps":["trace[1622282289] 'agreement among raft nodes before linearized reading' (duration: 269.131102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:59.772299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.171774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T00:52:59.77233Z","caller":"traceutil/trace.go:171","msg":"trace[1172930979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"265.220435ms","start":"2026-05-14T00:52:59.507101Z","end":"2026-05-14T00:52:59.772322Z","steps":["trace[1172930979] 'agreement among raft nodes before linearized reading' (duration: 265.108092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:52:59.77243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.364869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T00:52:59.772449Z","caller":"traceutil/trace.go:171","msg":"trace[654282356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"265.420411ms","start":"2026-05-14T00:52:59.50702Z","end":"2026-05-14T00:52:59.77244Z","steps":["trace[654282356] 'agreement among raft nodes before linearized reading' (duration: 265.370629ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:15.45006Z","caller":"traceutil/trace.go:171","msg":"trace[817353935] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"113.991023ms","start":"2026-05-14T00:53:15.336016Z","end":"2026-05-14T00:53:15.450008Z","steps":["trace[817353935] 'process raft request' (duration: 113.818268ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:17.692885Z","caller":"traceutil/trace.go:171","msg":"trace[2135831738] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"165.002069ms","start":"2026-05-14T00:53:17.52786Z","end":"2026-05-14T00:53:17.692862Z","steps":["trace[2135831738] 'read index received' (duration: 164.734871ms)","trace[2135831738] 'applied index is now lower than readState.Index' (duration: 266.358µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:17.692974Z","caller":"traceutil/trace.go:171","msg":"trace[2088970043] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"235.601684ms","start":"2026-05-14T00:53:17.457337Z","end":"2026-05-14T00:53:17.692938Z","steps":["trace[2088970043] 'process raft request' (duration: 235.339926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:17.693067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.194404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T00:53:17.693142Z","caller":"traceutil/trace.go:171","msg":"trace[203508762] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1055; }","duration":"165.317188ms","start":"2026-05-14T00:53:17.527806Z","end":"2026-05-14T00:53:17.693123Z","steps":["trace[203508762] 'agreement among raft nodes before linearized reading' (duration: 165.177254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:18.394845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.079282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T00:53:18.394968Z","caller":"traceutil/trace.go:171","msg":"trace[694411239] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"495.200124ms","start":"2026-05-14T00:53:17.899751Z","end":"2026-05-14T00:53:18.394951Z","steps":["trace[694411239] 'read index received' (duration: 482.736673ms)","trace[694411239] 'applied index is now lower than readState.Index' (duration: 12.462111ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:18.394988Z","caller":"traceutil/trace.go:171","msg":"trace[635688227] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"521.548887ms","start":"2026-05-14T00:53:17.873417Z","end":"2026-05-14T00:53:18.394966Z","steps":["trace[635688227] 'process raft request' (duration: 509.034164ms)","trace[635688227] 'compare' (duration: 11.975198ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:18.394997Z","caller":"traceutil/trace.go:171","msg":"trace[1855202987] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1055; }","duration":"606.268926ms","start":"2026-05-14T00:53:17.788701Z","end":"2026-05-14T00:53:18.39497Z","steps":["trace[1855202987] 'count revisions from in-memory index tree' (duration: 606.00937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:18.395062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:17.788688Z","time spent":"606.356698ms","remote":"127.0.0.1:36452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-14T00:53:18.39508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:17.873389Z","time spent":"521.637169ms","remote":"127.0.0.1:36206","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-05-14T00:53:18.395213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.43539ms","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-05-14T00:53:18.395222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.238483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T00:53:18.395271Z","caller":"traceutil/trace.go:171","msg":"trace[1365447051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"495.536774ms","start":"2026-05-14T00:53:17.899704Z","end":"2026-05-14T00:53:18.395241Z","steps":["trace[1365447051] 'agreement among raft nodes before linearized reading' (duration: 495.326029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:18.394866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.281275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T00:53:18.395289Z","caller":"traceutil/trace.go:171","msg":"trace[362786978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"469.343146ms","start":"2026-05-14T00:53:17.925928Z","end":"2026-05-14T00:53:18.395271Z","steps":["trace[362786978] 'agreement among raft nodes before linearized reading' (duration: 469.154561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:18.395306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:17.899685Z","time spent":"495.611355ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T00:53:18.395327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:17.925911Z","time spent":"469.408768ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T00:53:18.395306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.035226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:53:18.395328Z","caller":"traceutil/trace.go:171","msg":"trace[1511166510] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1055; }","duration":"549.778679ms","start":"2026-05-14T00:53:17.845528Z","end":"2026-05-14T00:53:18.395306Z","steps":["trace[1511166510] 'count revisions from in-memory index tree' (duration: 549.202944ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:18.39538Z","caller":"traceutil/trace.go:171","msg":"trace[2134788625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"159.15639ms","start":"2026-05-14T00:53:18.236205Z","end":"2026-05-14T00:53:18.395361Z","steps":["trace[2134788625] 'agreement among raft nodes before linearized reading' (duration: 159.004505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:18.395389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:17.845512Z","time spent":"549.859252ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-14T00:53:18.395475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.241864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T00:53:18.395502Z","caller":"traceutil/trace.go:171","msg":"trace[1835581087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"465.289214ms","start":"2026-05-14T00:53:17.930204Z","end":"2026-05-14T00:53:18.395493Z","steps":["trace[1835581087] 'agreement among raft nodes before linearized reading' (duration: 465.216272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:18.395526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:17.930193Z","time spent":"465.327705ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T00:53:20.665111Z","caller":"traceutil/trace.go:171","msg":"trace[295542350] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"130.227576ms","start":"2026-05-14T00:53:20.53486Z","end":"2026-05-14T00:53:20.665087Z","steps":["trace[295542350] 'process raft request' (duration: 130.085743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:21.034794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.895123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T00:53:21.034905Z","caller":"traceutil/trace.go:171","msg":"trace[793143205] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"246.066383ms","start":"2026-05-14T00:53:20.788815Z","end":"2026-05-14T00:53:21.034881Z","steps":["trace[793143205] 'process raft request' (duration: 54.002586ms)","trace[793143205] 'compare' (duration: 191.744819ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:21.720309Z","caller":"traceutil/trace.go:171","msg":"trace[1631383517] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"167.960219ms","start":"2026-05-14T00:53:21.552324Z","end":"2026-05-14T00:53:21.720284Z","steps":["trace[1631383517] 'process raft request' (duration: 167.800255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:34.734805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726251372041711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T00:53:34.970837Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.10637942s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T00:53:34.971238Z","caller":"traceutil/trace.go:171","msg":"trace[243582422] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"1.106855384s","start":"2026-05-14T00:53:33.864353Z","end":"2026-05-14T00:53:34.971208Z","steps":["trace[243582422] 'process raft request' (duration: 1.106703909s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:34.97141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:33.864329Z","time spent":"1.107002657s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:53:34.972348Z","caller":"traceutil/trace.go:171","msg":"trace[252263476] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1204; }","duration":"738.364485ms","start":"2026-05-14T00:53:34.233955Z","end":"2026-05-14T00:53:34.972319Z","steps":["trace[252263476] 'read index received' (duration: 737.196413ms)","trace[252263476] 'applied index is now lower than readState.Index' (duration: 1.166832ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:34.972492Z","caller":"traceutil/trace.go:171","msg":"trace[2043057180] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"502.771198ms","start":"2026-05-14T00:53:34.469703Z","end":"2026-05-14T00:53:34.972474Z","steps":["trace[2043057180] 'process raft request' (duration: 502.49579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:34.97261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.581777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-14T00:53:34.972626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.903337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-14T00:53:34.97265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:34.469681Z","time spent":"502.87924ms","remote":"127.0.0.1:36206","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-05-14T00:53:34.972678Z","caller":"traceutil/trace.go:171","msg":"trace[1918694514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1160; }","duration":"463.678319ms","start":"2026-05-14T00:53:34.508975Z","end":"2026-05-14T00:53:34.972654Z","steps":["trace[1918694514] 'agreement among raft nodes before linearized reading' (duration: 463.500775ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:34.972683Z","caller":"traceutil/trace.go:171","msg":"trace[489630755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1160; }","duration":"242.980038ms","start":"2026-05-14T00:53:34.729685Z","end":"2026-05-14T00:53:34.972665Z","steps":["trace[489630755] 'agreement among raft nodes before linearized reading' (duration: 242.817714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:34.972698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.079666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-14T00:53:34.972719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:34.508947Z","time spent":"463.763441ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T00:53:34.972755Z","caller":"traceutil/trace.go:171","msg":"trace[1161957224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1160; }","duration":"462.151248ms","start":"2026-05-14T00:53:34.510587Z","end":"2026-05-14T00:53:34.972738Z","steps":["trace[1161957224] 'agreement among raft nodes before linearized reading' (duration: 461.983364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:34.972799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:34.510579Z","time spent":"462.205449ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T00:53:34.972817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.869629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:53:34.972844Z","caller":"traceutil/trace.go:171","msg":"trace[1333649037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"738.93212ms","start":"2026-05-14T00:53:34.233903Z","end":"2026-05-14T00:53:34.972835Z","steps":["trace[1333649037] 'agreement among raft nodes before linearized reading' (duration: 738.88775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:34.972875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:34.233883Z","time spent":"738.986682ms","remote":"127.0.0.1:35892","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-05-14T00:53:34.972897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.437795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T00:53:34.972937Z","caller":"traceutil/trace.go:171","msg":"trace[1266652582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1160; }","duration":"462.500508ms","start":"2026-05-14T00:53:34.510426Z","end":"2026-05-14T00:53:34.972927Z","steps":["trace[1266652582] 'agreement among raft nodes before linearized reading' (duration: 462.388295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:34.972969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:34.510413Z","time spent":"462.549969ms","remote":"127.0.0.1:36206","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-05-14T00:53:47.119097Z","caller":"traceutil/trace.go:171","msg":"trace[952811891] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"100.640301ms","start":"2026-05-14T00:53:47.018439Z","end":"2026-05-14T00:53:47.119079Z","steps":["trace[952811891] 'read index received' (duration: 100.466976ms)","trace[952811891] 'applied index is now lower than readState.Index' (duration: 172.815µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:47.119396Z","caller":"traceutil/trace.go:171","msg":"trace[1656049132] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"112.168435ms","start":"2026-05-14T00:53:47.00722Z","end":"2026-05-14T00:53:47.119388Z","steps":["trace[1656049132] 'process raft request' (duration: 111.751514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:47.119591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.146025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T00:53:47.119618Z","caller":"traceutil/trace.go:171","msg":"trace[159633633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"101.215865ms","start":"2026-05-14T00:53:47.018396Z","end":"2026-05-14T00:53:47.119612Z","steps":["trace[159633633] 'agreement among raft nodes before linearized reading' (duration: 101.115682ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:50.247345Z","caller":"traceutil/trace.go:171","msg":"trace[1376439281] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"265.194566ms","start":"2026-05-14T00:53:49.982127Z","end":"2026-05-14T00:53:50.247322Z","steps":["trace[1376439281] 'read index received' (duration: 264.99955ms)","trace[1376439281] 'applied index is now lower than readState.Index' (duration: 194.186µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:50.247376Z","caller":"traceutil/trace.go:171","msg":"trace[933202534] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"372.830617ms","start":"2026-05-14T00:53:49.874523Z","end":"2026-05-14T00:53:50.247354Z","steps":["trace[933202534] 'process raft request' (duration: 372.660542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:50.247521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.36457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T00:53:50.247536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:49.874512Z","time spent":"372.9192ms","remote":"127.0.0.1:36096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:53:50.247579Z","caller":"traceutil/trace.go:171","msg":"trace[1303103660] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1225; }","duration":"265.496304ms","start":"2026-05-14T00:53:49.982068Z","end":"2026-05-14T00:53:50.247565Z","steps":["trace[1303103660] 'agreement among raft nodes before linearized reading' (duration: 265.380771ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:53.478834Z","caller":"traceutil/trace.go:171","msg":"trace[182335915] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"209.792582ms","start":"2026-05-14T00:53:53.269014Z","end":"2026-05-14T00:53:53.478806Z","steps":["trace[182335915] 'read index received' (duration: 209.499164ms)","trace[182335915] 'applied index is now lower than readState.Index' (duration: 292.138µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:53.478934Z","caller":"traceutil/trace.go:171","msg":"trace[1589792628] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"231.470265ms","start":"2026-05-14T00:53:53.24743Z","end":"2026-05-14T00:53:53.4789Z","steps":["trace[1589792628] 'process raft request' (duration: 231.168817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:53.479099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.06903ms","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-05-14T00:53:53.479134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.523077ms","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-05-14T00:53:53.47916Z","caller":"traceutil/trace.go:171","msg":"trace[1296271163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"210.170792ms","start":"2026-05-14T00:53:53.268975Z","end":"2026-05-14T00:53:53.479145Z","steps":["trace[1296271163] 'agreement among raft nodes before linearized reading' (duration: 209.987358ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:53.479186Z","caller":"traceutil/trace.go:171","msg":"trace[1446400940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"197.652191ms","start":"2026-05-14T00:53:53.28152Z","end":"2026-05-14T00:53:53.479173Z","steps":["trace[1446400940] 'agreement among raft nodes before linearized reading' (duration: 197.502276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:53.479206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.871001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T00:53:53.479289Z","caller":"traceutil/trace.go:171","msg":"trace[1647087765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"200.010715ms","start":"2026-05-14T00:53:53.279261Z","end":"2026-05-14T00:53:53.479272Z","steps":["trace[1647087765] 'agreement among raft nodes before linearized reading' (duration: 199.769588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:53.479321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.852594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-14T00:53:53.479358Z","caller":"traceutil/trace.go:171","msg":"trace[1594334603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"198.911295ms","start":"2026-05-14T00:53:53.280438Z","end":"2026-05-14T00:53:53.47935Z","steps":["trace[1594334603] 'agreement among raft nodes before linearized reading' (duration: 198.824313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:53.479362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.992427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T00:53:53.479411Z","caller":"traceutil/trace.go:171","msg":"trace[2025704726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"199.06437ms","start":"2026-05-14T00:53:53.280333Z","end":"2026-05-14T00:53:53.479398Z","steps":["trace[2025704726] 'agreement among raft nodes before linearized reading' (duration: 198.930766ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:54.003874Z","caller":"traceutil/trace.go:171","msg":"trace[654132190] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"520.671466ms","start":"2026-05-14T00:53:53.483169Z","end":"2026-05-14T00:53:54.003841Z","steps":["trace[654132190] 'process raft request' (duration: 492.586529ms)","trace[654132190] 'compare' (duration: 27.602864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:53:54.004047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:53.483153Z","time spent":"520.807559ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:53:54.076003Z","caller":"traceutil/trace.go:171","msg":"trace[1560806525] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"592.50338ms","start":"2026-05-14T00:53:53.483463Z","end":"2026-05-14T00:53:54.075967Z","steps":["trace[1560806525] 'process raft request' (duration: 592.314325ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:54.076006Z","caller":"traceutil/trace.go:171","msg":"trace[113038803] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"592.376305ms","start":"2026-05-14T00:53:53.483605Z","end":"2026-05-14T00:53:54.075982Z","steps":["trace[113038803] 'process raft request' (duration: 592.292573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:54.076168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:53.483456Z","time spent":"592.633913ms","remote":"127.0.0.1:36206","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-05-14T00:53:54.076265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:53.4836Z","time spent":"592.579321ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:53:54.076439Z","caller":"traceutil/trace.go:171","msg":"trace[1127497715] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"592.737615ms","start":"2026-05-14T00:53:53.483691Z","end":"2026-05-14T00:53:54.076429Z","steps":["trace[1127497715] 'process raft request' (duration: 592.247782ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:54.076495Z","caller":"traceutil/trace.go:171","msg":"trace[739869529] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1290; }","duration":"421.789085ms","start":"2026-05-14T00:53:53.654692Z","end":"2026-05-14T00:53:54.076481Z","steps":["trace[739869529] 'read index received' (duration: 321.050422ms)","trace[739869529] 'applied index is now lower than readState.Index' (duration: 100.737883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:53:54.076508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:53.483685Z","time spent":"592.791888ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:53:54.076572Z","caller":"traceutil/trace.go:171","msg":"trace[687397475] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"592.782568ms","start":"2026-05-14T00:53:53.483781Z","end":"2026-05-14T00:53:54.076563Z","steps":["trace[687397475] 'process raft request' (duration: 592.54499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:54.076657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.815797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T00:53:54.076717Z","caller":"traceutil/trace.go:171","msg":"trace[545030406] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1245; }","duration":"170.914149ms","start":"2026-05-14T00:53:53.905792Z","end":"2026-05-14T00:53:54.076706Z","steps":["trace[545030406] 'agreement among raft nodes before linearized reading' (duration: 170.804796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:54.076769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.079822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:53:54.076814Z","caller":"traceutil/trace.go:171","msg":"trace[1088044306] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1245; }","duration":"422.182435ms","start":"2026-05-14T00:53:53.654621Z","end":"2026-05-14T00:53:54.076803Z","steps":["trace[1088044306] 'agreement among raft nodes before linearized reading' (duration: 422.115413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:54.076851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:53.654603Z","time spent":"422.240776ms","remote":"127.0.0.1:35100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-14T00:53:54.076663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:53:53.483776Z","time spent":"592.847469ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T00:53:54.392387Z","caller":"traceutil/trace.go:171","msg":"trace[1052846820] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"127.01567ms","start":"2026-05-14T00:53:54.265339Z","end":"2026-05-14T00:53:54.392354Z","steps":["trace[1052846820] 'process raft request' (duration: 126.867496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:53:54.392608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.08249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-14T00:53:54.392674Z","caller":"traceutil/trace.go:171","msg":"trace[1567434071] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1248; }","duration":"124.195093ms","start":"2026-05-14T00:53:54.268465Z","end":"2026-05-14T00:53:54.39266Z","steps":["trace[1567434071] 'agreement among raft nodes before linearized reading' (duration: 123.958937ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:53:54.392364Z","caller":"traceutil/trace.go:171","msg":"trace[1974143053] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"123.823883ms","start":"2026-05-14T00:53:54.268496Z","end":"2026-05-14T00:53:54.39232Z","steps":["trace[1974143053] 'read index received' (duration: 123.678069ms)","trace[1974143053] 'applied index is now lower than readState.Index' (duration: 144.954µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:53:54.404719Z","caller":"traceutil/trace.go:171","msg":"trace[755050270] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"132.463239ms","start":"2026-05-14T00:53:54.27224Z","end":"2026-05-14T00:53:54.404703Z","steps":["trace[755050270] 'process raft request' (duration: 132.377006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:06.32139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726251372042050,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T00:54:06.380411Z","caller":"traceutil/trace.go:171","msg":"trace[1142015381] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"749.639233ms","start":"2026-05-14T00:54:05.630747Z","end":"2026-05-14T00:54:06.380387Z","steps":["trace[1142015381] 'process raft request' (duration: 749.438217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:06.380577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:05.630725Z","time spent":"749.759216ms","remote":"127.0.0.1:36206","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-05-14T00:54:07.606405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.124887ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-14T00:54:07.607014Z","caller":"traceutil/trace.go:171","msg":"trace[2139157642] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.382994738s","start":"2026-05-14T00:54:06.223986Z","end":"2026-05-14T00:54:07.606981Z","steps":["trace[2139157642] 'process raft request' (duration: 1.382490845s)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:54:07.607018Z","caller":"traceutil/trace.go:171","msg":"trace[1456861497] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1377; }","duration":"1.786125773s","start":"2026-05-14T00:54:05.820856Z","end":"2026-05-14T00:54:07.606982Z","steps":["trace[1456861497] 'read index received' (duration: 559.421446ms)","trace[1456861497] 'applied index is now lower than readState.Index' (duration: 1.226698057s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.607609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.223962Z","time spent":"1.383123922s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T00:54:07.644136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408672999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T00:54:07.64419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.921784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:54:07.644207Z","caller":"traceutil/trace.go:171","msg":"trace[691092217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"1.408788453s","start":"2026-05-14T00:54:06.235402Z","end":"2026-05-14T00:54:07.64419Z","steps":["trace[691092217] 'agreement among raft nodes before linearized reading' (duration: 1.372333466s)","trace[691092217] 'range keys from in-memory index tree' (duration: 36.335413ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.644246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.235387Z","time spent":"1.408850705s","remote":"127.0.0.1:35892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T00:54:07.644239Z","caller":"traceutil/trace.go:171","msg":"trace[1035524833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"957.993965ms","start":"2026-05-14T00:54:06.686227Z","end":"2026-05-14T00:54:07.644221Z","steps":["trace[1035524833] 'agreement among raft nodes before linearized reading' (duration: 921.543309ms)","trace[1035524833] 'range keys from in-memory index tree' (duration: 36.405305ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.644235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.82336173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-14T00:54:07.644282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.686207Z","time spent":"958.067589ms","remote":"127.0.0.1:35900","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-05-14T00:54:07.644322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509405822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T00:54:07.644322Z","caller":"traceutil/trace.go:171","msg":"trace[1429705704] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1327; }","duration":"1.823490104s","start":"2026-05-14T00:54:05.820808Z","end":"2026-05-14T00:54:07.644299Z","steps":["trace[1429705704] 'agreement among raft nodes before linearized reading' (duration: 1.786233996s)","trace[1429705704] 'range keys from in-memory index tree' (duration: 37.042622ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:54:07.644365Z","caller":"traceutil/trace.go:171","msg":"trace[2135139615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.509446803s","start":"2026-05-14T00:54:06.1349Z","end":"2026-05-14T00:54:07.644347Z","steps":["trace[2135139615] 'agreement among raft nodes before linearized reading' (duration: 1.472853143s)","trace[2135139615] 'range keys from in-memory index tree' (duration: 36.540238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.644369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:05.820786Z","time spent":"1.823572985s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-14T00:54:07.644393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.134894Z","time spent":"1.509494726s","remote":"127.0.0.1:36206","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-05-14T00:54:07.644248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512323663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T00:54:07.644544Z","caller":"traceutil/trace.go:171","msg":"trace[1381793435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"1.512670191s","start":"2026-05-14T00:54:06.131861Z","end":"2026-05-14T00:54:07.644532Z","steps":["trace[1381793435] 'agreement among raft nodes before linearized reading' (duration: 1.475906077s)","trace[1381793435] 'range keys from in-memory index tree' (duration: 36.361243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.644567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494664599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-14T00:54:07.644577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.131842Z","time spent":"1.512726343s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T00:54:07.644511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577714869s","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-05-14T00:54:07.644635Z","caller":"traceutil/trace.go:171","msg":"trace[1631923035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.577867713s","start":"2026-05-14T00:54:06.066753Z","end":"2026-05-14T00:54:07.644621Z","steps":["trace[1631923035] 'agreement among raft nodes before linearized reading' (duration: 1.540953124s)","trace[1631923035] 'range keys from in-memory index tree' (duration: 36.711563ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.64468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.066732Z","time spent":"1.577937065s","remote":"127.0.0.1:36206","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-05-14T00:54:07.644687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512523307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T00:54:07.644594Z","caller":"traceutil/trace.go:171","msg":"trace[792892274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1327; }","duration":"1.494726581s","start":"2026-05-14T00:54:06.149858Z","end":"2026-05-14T00:54:07.644585Z","steps":["trace[792892274] 'agreement among raft nodes before linearized reading' (duration: 1.457885435s)","trace[792892274] 'range keys from in-memory index tree' (duration: 36.780875ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:54:07.644712Z","caller":"traceutil/trace.go:171","msg":"trace[1399337662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.512560468s","start":"2026-05-14T00:54:06.132143Z","end":"2026-05-14T00:54:07.644703Z","steps":["trace[1399337662] 'agreement among raft nodes before linearized reading' (duration: 1.475618789s)","trace[1399337662] 'range keys from in-memory index tree' (duration: 36.874777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.644733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.149837Z","time spent":"1.494887876s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T00:54:07.644738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.132136Z","time spent":"1.512596939s","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T00:54:07.644129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229941827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:54:07.644828Z","caller":"traceutil/trace.go:171","msg":"trace[499977539] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1327; }","duration":"1.230719977s","start":"2026-05-14T00:54:06.414095Z","end":"2026-05-14T00:54:07.644815Z","steps":["trace[499977539] 'agreement among raft nodes before linearized reading' (duration: 1.193684595s)","trace[499977539] 'count revisions from in-memory index tree' (duration: 36.270592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.64486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.414075Z","time spent":"1.230775149s","remote":"127.0.0.1:34982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-14T00:54:07.64602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.432082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-14T00:54:07.646083Z","caller":"traceutil/trace.go:171","msg":"trace[1308056782] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1327; }","duration":"785.532413ms","start":"2026-05-14T00:54:06.86053Z","end":"2026-05-14T00:54:07.646062Z","steps":["trace[1308056782] 'agreement among raft nodes before linearized reading' (duration: 747.229147ms)","trace[1308056782] 'range keys from in-memory index tree' (duration: 38.179473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T00:54:07.646123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:54:06.860508Z","time spent":"785.607656ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-14T00:54:27.85763Z","caller":"traceutil/trace.go:171","msg":"trace[901510299] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"120.932805ms","start":"2026-05-14T00:54:27.736666Z","end":"2026-05-14T00:54:27.857599Z","steps":["trace[901510299] 'process raft request' (duration: 120.658538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:27.906501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.916285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-14T00:54:27.906537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.19862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T00:54:27.906573Z","caller":"traceutil/trace.go:171","msg":"trace[1896956429] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1421; }","duration":"128.021368ms","start":"2026-05-14T00:54:27.778536Z","end":"2026-05-14T00:54:27.906557Z","steps":["trace[1896956429] 'agreement among raft nodes before linearized reading' (duration: 127.808272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:27.906537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.244131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T00:54:27.906604Z","caller":"traceutil/trace.go:171","msg":"trace[444468482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1421; }","duration":"118.273791ms","start":"2026-05-14T00:54:27.788312Z","end":"2026-05-14T00:54:27.906586Z","steps":["trace[444468482] 'agreement among raft nodes before linearized reading' (duration: 118.084356ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:54:27.906618Z","caller":"traceutil/trace.go:171","msg":"trace[1933118121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1421; }","duration":"122.389974ms","start":"2026-05-14T00:54:27.784217Z","end":"2026-05-14T00:54:27.906607Z","steps":["trace[1933118121] 'agreement among raft nodes before linearized reading' (duration: 122.22023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:27.906654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.544888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T00:54:27.906688Z","caller":"traceutil/trace.go:171","msg":"trace[263824427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1421; }","duration":"122.61516ms","start":"2026-05-14T00:54:27.784064Z","end":"2026-05-14T00:54:27.906679Z","steps":["trace[263824427] 'agreement among raft nodes before linearized reading' (duration: 122.504527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:27.906746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.535648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T00:54:27.906829Z","caller":"traceutil/trace.go:171","msg":"trace[1060785967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1421; }","duration":"118.657832ms","start":"2026-05-14T00:54:27.788154Z","end":"2026-05-14T00:54:27.906812Z","steps":["trace[1060785967] 'agreement among raft nodes before linearized reading' (duration: 118.411985ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:54:30.91799Z","caller":"traceutil/trace.go:171","msg":"trace[287620971] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"103.720264ms","start":"2026-05-14T00:54:30.81424Z","end":"2026-05-14T00:54:30.91796Z","steps":["trace[287620971] 'process raft request' (duration: 103.53595ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:54:40.25244Z","caller":"traceutil/trace.go:171","msg":"trace[925445261] linearizableReadLoop","detail":"{readStateIndex:1538; appliedIndex:1537; }","duration":"191.966095ms","start":"2026-05-14T00:54:40.060445Z","end":"2026-05-14T00:54:40.252411Z","steps":["trace[925445261] 'read index received' (duration: 186.236149ms)","trace[925445261] 'applied index is now lower than readState.Index' (duration: 5.728626ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:54:40.252559Z","caller":"traceutil/trace.go:171","msg":"trace[1383802767] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"227.114245ms","start":"2026-05-14T00:54:40.02541Z","end":"2026-05-14T00:54:40.252524Z","steps":["trace[1383802767] 'process raft request' (duration: 221.257525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:40.252697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.233102ms","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:531"} {"level":"info","ts":"2026-05-14T00:54:40.252741Z","caller":"traceutil/trace.go:171","msg":"trace[1295901375] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1479; }","duration":"192.323175ms","start":"2026-05-14T00:54:40.060403Z","end":"2026-05-14T00:54:40.252726Z","steps":["trace[1295901375] 'agreement among raft nodes before linearized reading' (duration: 192.143081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:40.252819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.590335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-14T00:54:40.252887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.881736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T00:54:40.252918Z","caller":"traceutil/trace.go:171","msg":"trace[1979896586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1479; }","duration":"186.944199ms","start":"2026-05-14T00:54:40.065963Z","end":"2026-05-14T00:54:40.252907Z","steps":["trace[1979896586] 'agreement among raft nodes before linearized reading' (duration: 186.795955ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:54:40.252907Z","caller":"traceutil/trace.go:171","msg":"trace[44372789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1479; }","duration":"187.686618ms","start":"2026-05-14T00:54:40.065196Z","end":"2026-05-14T00:54:40.252883Z","steps":["trace[44372789] 'agreement among raft nodes before linearized reading' (duration: 187.431401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:54:40.252998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.462783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T00:54:40.253044Z","caller":"traceutil/trace.go:171","msg":"trace[1340983960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1479; }","duration":"187.523844ms","start":"2026-05-14T00:54:40.065506Z","end":"2026-05-14T00:54:40.25303Z","steps":["trace[1340983960] 'agreement among raft nodes before linearized reading' (duration: 187.407941ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:54:40.445306Z","caller":"traceutil/trace.go:171","msg":"trace[1008977455] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"185.705924ms","start":"2026-05-14T00:54:40.259564Z","end":"2026-05-14T00:54:40.44527Z","steps":["trace[1008977455] 'process raft request' (duration: 185.656903ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:54:40.445393Z","caller":"traceutil/trace.go:171","msg":"trace[986654359] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"187.931645ms","start":"2026-05-14T00:54:40.257402Z","end":"2026-05-14T00:54:40.445333Z","steps":["trace[986654359] 'process raft request' (duration: 148.887498ms)","trace[986654359] 'compare' (duration: 38.626055ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:54:40.446152Z","caller":"traceutil/trace.go:171","msg":"trace[1161561927] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"188.086568ms","start":"2026-05-14T00:54:40.258048Z","end":"2026-05-14T00:54:40.446134Z","steps":["trace[1161561927] 'process raft request' (duration: 187.072531ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:54:40.446268Z","caller":"traceutil/trace.go:171","msg":"trace[2005452708] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"188.100549ms","start":"2026-05-14T00:54:40.258137Z","end":"2026-05-14T00:54:40.446237Z","steps":["trace[2005452708] 'process raft request' (duration: 187.03842ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T00:57:20.420651Z","caller":"traceutil/trace.go:171","msg":"trace[1127974385] linearizableReadLoop","detail":"{readStateIndex:2334; appliedIndex:2333; }","duration":"256.824245ms","start":"2026-05-14T00:57:20.163799Z","end":"2026-05-14T00:57:20.420623Z","steps":["trace[1127974385] 'read index received' (duration: 256.573629ms)","trace[1127974385] 'applied index is now lower than readState.Index' (duration: 249.426µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T00:57:20.420743Z","caller":"traceutil/trace.go:171","msg":"trace[1386565015] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"482.356191ms","start":"2026-05-14T00:57:19.938354Z","end":"2026-05-14T00:57:20.420711Z","steps":["trace[1386565015] 'process raft request' (duration: 482.101123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:57:20.420935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.123703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-14T00:57:20.42098Z","caller":"traceutil/trace.go:171","msg":"trace[2016921046] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2242; }","duration":"257.214725ms","start":"2026-05-14T00:57:20.163752Z","end":"2026-05-14T00:57:20.420967Z","steps":["trace[2016921046] 'agreement among raft nodes before linearized reading' (duration: 257.065782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T00:57:20.421021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T00:57:19.938339Z","time spent":"482.586097ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T00:57:20.421235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.079596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T00:57:20.421398Z","caller":"traceutil/trace.go:171","msg":"trace[2086519691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2242; }","duration":"185.296733ms","start":"2026-05-14T00:57:20.236086Z","end":"2026-05-14T00:57:20.421382Z","steps":["trace[2086519691] 'agreement among raft nodes before linearized reading' (duration: 185.081017ms)"],"step_count":1}