{"level":"warn","ts":"2026-04-08T22:56:06.733357Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T22:56:06.733574Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-08T22:56:06.733711Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T22:56:06.733787Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-08T22:56:06.733865Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T22:56:06.734837Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-04-08T22:56:06.735131Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-08T22:56:06.742977Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.429926ms"} {"level":"info","ts":"2026-04-08T22:56:06.762914Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-04-08T22:56:06.763152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T22:56:06.763225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-04-08T22:56:06.763273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T22:56:06.763321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-04-08T22:56:06.763409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-04-08T22:56:06.780415Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T22:56:06.781766Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T22:56:06.782989Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-08T22:56:06.785308Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T22:56:06.785947Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T22:56:06.785991Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T22:56:06.786589Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T22:56:06.78691Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T22:56:06.78707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-04-08T22:56:06.787799Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-08T22:56:06.790894Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T22:56:06.791027Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-08T22:56:06.791114Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-08T22:56:06.791335Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T22:56:06.791416Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T22:56:07.764096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T22:56:07.764153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T22:56:07.764192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-04-08T22:56:07.764212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-04-08T22:56:07.764224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-08T22:56:07.76424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-04-08T22:56:07.76426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-08T22:56:07.765155Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:56:07.7659Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T22:56:07.765895Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:56:07.766275Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T22:56:07.766329Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T22:56:07.765988Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:56:07.766956Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:56:07.767094Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:56:07.767133Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:56:07.769761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T22:56:07.774288Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-04-08T22:56:37.647275Z","caller":"traceutil/trace.go:171","msg":"trace[1207832371] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"247.269062ms","start":"2026-04-08T22:56:37.399934Z","end":"2026-04-08T22:56:37.647203Z","steps":["trace[1207832371] 'process raft request' (duration: 246.844271ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.654867Z","caller":"traceutil/trace.go:171","msg":"trace[620102119] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"253.595509ms","start":"2026-04-08T22:56:37.401249Z","end":"2026-04-08T22:56:37.654845Z","steps":["trace[620102119] 'process raft request' (duration: 253.378564ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.654953Z","caller":"traceutil/trace.go:171","msg":"trace[1087045886] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"245.797683ms","start":"2026-04-08T22:56:37.409148Z","end":"2026-04-08T22:56:37.654946Z","steps":["trace[1087045886] 'process raft request' (duration: 245.6564ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.654903Z","caller":"traceutil/trace.go:171","msg":"trace[307727859] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"253.333304ms","start":"2026-04-08T22:56:37.401542Z","end":"2026-04-08T22:56:37.654875Z","steps":["trace[307727859] 'process raft request' (duration: 253.213921ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.655128Z","caller":"traceutil/trace.go:171","msg":"trace[562293422] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"243.926213ms","start":"2026-04-08T22:56:37.411141Z","end":"2026-04-08T22:56:37.655067Z","steps":["trace[562293422] 'process raft request' (duration: 243.703957ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.65516Z","caller":"traceutil/trace.go:171","msg":"trace[1030372366] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"243.501163ms","start":"2026-04-08T22:56:37.411649Z","end":"2026-04-08T22:56:37.655151Z","steps":["trace[1030372366] 'process raft request' (duration: 243.235796ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.655341Z","caller":"traceutil/trace.go:171","msg":"trace[653241197] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"235.992523ms","start":"2026-04-08T22:56:37.41934Z","end":"2026-04-08T22:56:37.655333Z","steps":["trace[653241197] 'process raft request' (duration: 235.596042ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.655555Z","caller":"traceutil/trace.go:171","msg":"trace[936280188] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"235.799298ms","start":"2026-04-08T22:56:37.419742Z","end":"2026-04-08T22:56:37.655541Z","steps":["trace[936280188] 'process raft request' (duration: 235.532731ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.65568Z","caller":"traceutil/trace.go:171","msg":"trace[879627145] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"235.936322ms","start":"2026-04-08T22:56:37.419733Z","end":"2026-04-08T22:56:37.655669Z","steps":["trace[879627145] 'process raft request' (duration: 235.415698ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.655705Z","caller":"traceutil/trace.go:171","msg":"trace[807794417] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"232.094679ms","start":"2026-04-08T22:56:37.423561Z","end":"2026-04-08T22:56:37.655655Z","steps":["trace[807794417] 'process raft request' (duration: 231.763741ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:37.65588Z","caller":"traceutil/trace.go:171","msg":"trace[583000250] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"213.437985ms","start":"2026-04-08T22:56:37.442434Z","end":"2026-04-08T22:56:37.655872Z","steps":["trace[583000250] 'process raft request' (duration: 212.959502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:40.096317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198869984178123,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:56:40.367068Z","caller":"traceutil/trace.go:171","msg":"trace[682149682] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"970.172428ms","start":"2026-04-08T22:56:39.396869Z","end":"2026-04-08T22:56:40.367041Z","steps":["trace[682149682] 'process raft request' (duration: 969.989063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:40.367634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:39.396854Z","time spent":"970.28279ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:56:40.395Z","caller":"traceutil/trace.go:171","msg":"trace[1532706853] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"799.364105ms","start":"2026-04-08T22:56:39.595607Z","end":"2026-04-08T22:56:40.394971Z","steps":["trace[1532706853] 'read index received' (duration: 771.330551ms)","trace[1532706853] 'applied index is now lower than readState.Index' (duration: 28.031914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:56:40.395343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.714425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:40.395419Z","caller":"traceutil/trace.go:171","msg":"trace[1992111702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"799.852517ms","start":"2026-04-08T22:56:39.595556Z","end":"2026-04-08T22:56:40.395408Z","steps":["trace[1992111702] 'agreement among raft nodes before linearized reading' (duration: 799.732094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:40.395499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:39.595527Z","time spent":"799.960741ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T22:56:40.395503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.446255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:56:40.395555Z","caller":"traceutil/trace.go:171","msg":"trace[1700657380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"713.532317ms","start":"2026-04-08T22:56:39.682009Z","end":"2026-04-08T22:56:40.395541Z","steps":["trace[1700657380] 'agreement among raft nodes before linearized reading' (duration: 713.392433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:40.395597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:39.681983Z","time spent":"713.606059ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T22:56:40.395508Z","caller":"traceutil/trace.go:171","msg":"trace[1267169756] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"476.187018ms","start":"2026-04-08T22:56:39.919206Z","end":"2026-04-08T22:56:40.395393Z","steps":["trace[1267169756] 'process raft request' (duration: 475.614882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:40.395771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:39.919179Z","time spent":"476.514036ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:56:40.395672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.28231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T22:56:40.395882Z","caller":"traceutil/trace.go:171","msg":"trace[1106504555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"196.577877ms","start":"2026-04-08T22:56:40.199284Z","end":"2026-04-08T22:56:40.395861Z","steps":["trace[1106504555] 'agreement among raft nodes before linearized reading' (duration: 196.128985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:44.882427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198869984178187,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:56:45.138042Z","caller":"traceutil/trace.go:171","msg":"trace[2043216594] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"756.63124ms","start":"2026-04-08T22:56:44.381382Z","end":"2026-04-08T22:56:45.138013Z","steps":["trace[2043216594] 'read index received' (duration: 755.603283ms)","trace[2043216594] 'applied index is now lower than readState.Index' (duration: 1.026797ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:56:45.138169Z","caller":"traceutil/trace.go:171","msg":"trace[548542499] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"783.796101ms","start":"2026-04-08T22:56:44.354337Z","end":"2026-04-08T22:56:45.138133Z","steps":["trace[548542499] 'process raft request' (duration: 782.884577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:45.138359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:44.354311Z","time spent":"783.956716ms","remote":"127.0.0.1:57970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:56:45.138405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.286351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:56:45.1385Z","caller":"traceutil/trace.go:171","msg":"trace[1999982595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"704.412935ms","start":"2026-04-08T22:56:44.434061Z","end":"2026-04-08T22:56:45.138474Z","steps":["trace[1999982595] 'agreement among raft nodes before linearized reading' (duration: 704.158628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:45.138489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.594637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T22:56:45.138586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:44.43405Z","time spent":"704.492946ms","remote":"127.0.0.1:58160","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-04-08T22:56:45.13849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.283437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T22:56:45.138648Z","caller":"traceutil/trace.go:171","msg":"trace[446668491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"702.769402ms","start":"2026-04-08T22:56:44.435854Z","end":"2026-04-08T22:56:45.138548Z","steps":["trace[446668491] 'agreement among raft nodes before linearized reading' (duration: 702.507135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:45.138706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:44.435844Z","time spent":"702.847504ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T22:56:45.138735Z","caller":"traceutil/trace.go:171","msg":"trace[2056813053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"425.572855ms","start":"2026-04-08T22:56:44.713108Z","end":"2026-04-08T22:56:45.138681Z","steps":["trace[2056813053] 'agreement among raft nodes before linearized reading' (duration: 425.219155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:45.138851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:44.713087Z","time spent":"425.723509ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:56:45.13888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.498453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:56:45.138936Z","caller":"traceutil/trace.go:171","msg":"trace[1306033501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"757.555825ms","start":"2026-04-08T22:56:44.381362Z","end":"2026-04-08T22:56:45.138918Z","steps":["trace[1306033501] 'agreement among raft nodes before linearized reading' (duration: 756.801195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:45.139027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:44.381291Z","time spent":"757.724229ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:56:45.139107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.459951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5pjrm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T22:56:45.139239Z","caller":"traceutil/trace.go:171","msg":"trace[1150781757] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5pjrm; range_end:; response_count:1; response_revision:616; }","duration":"581.982966ms","start":"2026-04-08T22:56:44.557234Z","end":"2026-04-08T22:56:45.139217Z","steps":["trace[1150781757] 'agreement among raft nodes before linearized reading' (duration: 581.050661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:45.13936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:44.557216Z","time spent":"582.12542ms","remote":"127.0.0.1:58056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-5pjrm\" "} {"level":"warn","ts":"2026-04-08T22:56:45.361904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.735694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:56:45.362018Z","caller":"traceutil/trace.go:171","msg":"trace[11788700] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"215.310574ms","start":"2026-04-08T22:56:45.146688Z","end":"2026-04-08T22:56:45.361999Z","steps":["trace[11788700] 'process raft request' (duration: 94.265072ms)","trace[11788700] 'compare' (duration: 120.6056ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:56:45.385378Z","caller":"traceutil/trace.go:171","msg":"trace[721804360] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"238.146949ms","start":"2026-04-08T22:56:45.147182Z","end":"2026-04-08T22:56:45.385329Z","steps":["trace[721804360] 'process raft request' (duration: 237.892392ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:45.38548Z","caller":"traceutil/trace.go:171","msg":"trace[343367852] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"194.04156ms","start":"2026-04-08T22:56:45.191412Z","end":"2026-04-08T22:56:45.385454Z","steps":["trace[343367852] 'process raft request' (duration: 193.990589ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:45.386151Z","caller":"traceutil/trace.go:171","msg":"trace[1426978477] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"238.751686ms","start":"2026-04-08T22:56:45.147386Z","end":"2026-04-08T22:56:45.386137Z","steps":["trace[1426978477] 'process raft request' (duration: 237.825382ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:45.386292Z","caller":"traceutil/trace.go:171","msg":"trace[66599252] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"238.88171ms","start":"2026-04-08T22:56:45.147398Z","end":"2026-04-08T22:56:45.38628Z","steps":["trace[66599252] 'process raft request' (duration: 237.849262ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:45.386711Z","caller":"traceutil/trace.go:171","msg":"trace[129048711] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"232.286704ms","start":"2026-04-08T22:56:45.154385Z","end":"2026-04-08T22:56:45.386672Z","steps":["trace[129048711] 'process raft request' (duration: 230.899518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.097394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110021159s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.097524Z","caller":"traceutil/trace.go:171","msg":"trace[511266670] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:622; }","duration":"1.110167043s","start":"2026-04-08T22:56:45.987338Z","end":"2026-04-08T22:56:47.097505Z","steps":["trace[511266670] 'range keys from in-memory index tree' (duration: 1.110004859s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.097743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50306001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.097805Z","caller":"traceutil/trace.go:171","msg":"trace[364531187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"1.503160783s","start":"2026-04-08T22:56:45.594632Z","end":"2026-04-08T22:56:47.097792Z","steps":["trace[364531187] 'range keys from in-memory index tree' (duration: 1.502968378s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.097837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:45.594611Z","time spent":"1.503217775s","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T22:56:47.582712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198869984178198,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:56:47.683182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.849742861s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:56:47.683987Z","caller":"traceutil/trace.go:171","msg":"trace[1161566799] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.851636832s","start":"2026-04-08T22:56:45.832331Z","end":"2026-04-08T22:56:47.683968Z","steps":["trace[1161566799] 'process raft request' (duration: 1.8511934s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.684095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:45.832303Z","time spent":"1.851735205s","remote":"127.0.0.1:57970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:56:47.725626Z","caller":"traceutil/trace.go:171","msg":"trace[1851082752] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"643.476558ms","start":"2026-04-08T22:56:47.08213Z","end":"2026-04-08T22:56:47.725606Z","steps":["trace[1851082752] 'read index received' (duration: 601.195666ms)","trace[1851082752] 'applied index is now lower than readState.Index' (duration: 42.279581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:56:47.725801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.648962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.725828Z","caller":"traceutil/trace.go:171","msg":"trace[588944519] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"643.719004ms","start":"2026-04-08T22:56:47.082101Z","end":"2026-04-08T22:56:47.72582Z","steps":["trace[588944519] 'agreement among raft nodes before linearized reading' (duration: 643.647863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.725852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.082086Z","time spent":"643.759775ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:56:47.726021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.750144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.726043Z","caller":"traceutil/trace.go:171","msg":"trace[738383863] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"643.807858ms","start":"2026-04-08T22:56:47.08223Z","end":"2026-04-08T22:56:47.726038Z","steps":["trace[738383863] 'agreement among raft nodes before linearized reading' (duration: 643.773256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.726061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.082171Z","time spent":"643.885969ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T22:56:47.726686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.865327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.726715Z","caller":"traceutil/trace.go:171","msg":"trace[1125923981] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:623; }","duration":"490.911709ms","start":"2026-04-08T22:56:47.235795Z","end":"2026-04-08T22:56:47.726707Z","steps":["trace[1125923981] 'agreement among raft nodes before linearized reading' (duration: 490.871527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.7267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.592762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:56:47.726737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.235788Z","time spent":"490.94086ms","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"info","ts":"2026-04-08T22:56:47.726764Z","caller":"traceutil/trace.go:171","msg":"trace[2022497421] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:623; }","duration":"469.685905ms","start":"2026-04-08T22:56:47.257061Z","end":"2026-04-08T22:56:47.726746Z","steps":["trace[2022497421] 'agreement among raft nodes before linearized reading' (duration: 469.560762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.726803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.257053Z","time spent":"469.742647ms","remote":"127.0.0.1:53286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T22:56:47.726842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.904665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.726865Z","caller":"traceutil/trace.go:171","msg":"trace[142360053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:623; }","duration":"491.972047ms","start":"2026-04-08T22:56:47.234887Z","end":"2026-04-08T22:56:47.726859Z","steps":["trace[142360053] 'agreement among raft nodes before linearized reading' (duration: 491.936416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.726887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.234861Z","time spent":"492.020907ms","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:56:47.72697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.395068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.726992Z","caller":"traceutil/trace.go:171","msg":"trace[1432486335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:623; }","duration":"492.465829ms","start":"2026-04-08T22:56:47.234519Z","end":"2026-04-08T22:56:47.726985Z","steps":["trace[1432486335] 'agreement among raft nodes before linearized reading' (duration: 492.434778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.2345Z","time spent":"492.508601ms","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T22:56:47.727058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.200376ms","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":"info","ts":"2026-04-08T22:56:47.727088Z","caller":"traceutil/trace.go:171","msg":"trace[29730650] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"104.246127ms","start":"2026-04-08T22:56:47.622834Z","end":"2026-04-08T22:56:47.72708Z","steps":["trace[29730650] 'agreement among raft nodes before linearized reading' (duration: 104.206546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.670431ms","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-04-08T22:56:47.72712Z","caller":"traceutil/trace.go:171","msg":"trace[317190780] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"551.708511ms","start":"2026-04-08T22:56:47.175405Z","end":"2026-04-08T22:56:47.727114Z","steps":["trace[317190780] 'agreement among raft nodes before linearized reading' (duration: 551.679361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.175395Z","time spent":"551.742343ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-08T22:56:47.727194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.282013ms","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-04-08T22:56:47.727219Z","caller":"traceutil/trace.go:171","msg":"trace[1450765605] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"105.331285ms","start":"2026-04-08T22:56:47.621882Z","end":"2026-04-08T22:56:47.727213Z","steps":["trace[1450765605] 'agreement among raft nodes before linearized reading' (duration: 105.297274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.338705ms","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-04-08T22:56:47.727264Z","caller":"traceutil/trace.go:171","msg":"trace[535297833] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"553.365376ms","start":"2026-04-08T22:56:47.173893Z","end":"2026-04-08T22:56:47.727258Z","steps":["trace[535297833] 'agreement among raft nodes before linearized reading' (duration: 553.329315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.173847Z","time spent":"553.434998ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:56:47.7273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.416718ms","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-04-08T22:56:47.727318Z","caller":"traceutil/trace.go:171","msg":"trace[995779357] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"105.451199ms","start":"2026-04-08T22:56:47.621861Z","end":"2026-04-08T22:56:47.727312Z","steps":["trace[995779357] 'agreement among raft nodes before linearized reading' (duration: 105.420418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.935471ms","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-04-08T22:56:47.727388Z","caller":"traceutil/trace.go:171","msg":"trace[1912842528] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"554.169618ms","start":"2026-04-08T22:56:47.173212Z","end":"2026-04-08T22:56:47.727382Z","steps":["trace[1912842528] 'agreement among raft nodes before linearized reading' (duration: 554.138177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.976768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:56:47.727407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.173199Z","time spent":"554.202508ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-08T22:56:47.727421Z","caller":"traceutil/trace.go:171","msg":"trace[910447188] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"168.013178ms","start":"2026-04-08T22:56:47.559402Z","end":"2026-04-08T22:56:47.727415Z","steps":["trace[910447188] 'agreement among raft nodes before linearized reading' (duration: 167.980828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.368507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.727507Z","caller":"traceutil/trace.go:171","msg":"trace[170311224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"628.421288ms","start":"2026-04-08T22:56:47.09908Z","end":"2026-04-08T22:56:47.727501Z","steps":["trace[170311224] 'agreement among raft nodes before linearized reading' (duration: 628.392257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.536303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.727545Z","caller":"traceutil/trace.go:171","msg":"trace[1117564999] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"168.580904ms","start":"2026-04-08T22:56:47.558958Z","end":"2026-04-08T22:56:47.727539Z","steps":["trace[1117564999] 'agreement among raft nodes before linearized reading' (duration: 168.546183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.779795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.727574Z","caller":"traceutil/trace.go:171","msg":"trace[2120141] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"643.835068ms","start":"2026-04-08T22:56:47.083734Z","end":"2026-04-08T22:56:47.727569Z","steps":["trace[2120141] 'agreement among raft nodes before linearized reading' (duration: 643.800116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.083714Z","time spent":"643.876198ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-08T22:56:47.727641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.657246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.727667Z","caller":"traceutil/trace.go:171","msg":"trace[787529722] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"168.700608ms","start":"2026-04-08T22:56:47.558958Z","end":"2026-04-08T22:56:47.727659Z","steps":["trace[787529722] 'agreement among raft nodes before linearized reading' (duration: 168.666526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.043768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.727773Z","caller":"traceutil/trace.go:171","msg":"trace[1555003363] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"330.08316ms","start":"2026-04-08T22:56:47.397684Z","end":"2026-04-08T22:56:47.727767Z","steps":["trace[1555003363] 'agreement among raft nodes before linearized reading' (duration: 330.051199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.397679Z","time spent":"330.107211ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:56:47.727891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.220965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.727914Z","caller":"traceutil/trace.go:171","msg":"trace[106588477] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"330.262715ms","start":"2026-04-08T22:56:47.397645Z","end":"2026-04-08T22:56:47.727908Z","steps":["trace[106588477] 'agreement among raft nodes before linearized reading' (duration: 330.208503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.727933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.397634Z","time spent":"330.294025ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T22:56:47.728084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.322703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T22:56:47.728102Z","caller":"traceutil/trace.go:171","msg":"trace[669070618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"331.361884ms","start":"2026-04-08T22:56:47.396733Z","end":"2026-04-08T22:56:47.728095Z","steps":["trace[669070618] 'agreement among raft nodes before linearized reading' (duration: 331.262511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.728129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.396668Z","time spent":"331.446496ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:56:47.728253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.500228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:56:47.728271Z","caller":"traceutil/trace.go:171","msg":"trace[1810455916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"331.530138ms","start":"2026-04-08T22:56:47.396735Z","end":"2026-04-08T22:56:47.728265Z","steps":["trace[1810455916] 'agreement among raft nodes before linearized reading' (duration: 331.475537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.728287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.396728Z","time spent":"331.555168ms","remote":"127.0.0.1:58160","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-04-08T22:56:47.728394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.678581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:56:47.728411Z","caller":"traceutil/trace.go:171","msg":"trace[2098393000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"331.721754ms","start":"2026-04-08T22:56:47.396683Z","end":"2026-04-08T22:56:47.728405Z","steps":["trace[2098393000] 'agreement among raft nodes before linearized reading' (duration: 331.668773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.728429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.396671Z","time spent":"331.754405ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:56:47.728585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.264677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.728612Z","caller":"traceutil/trace.go:171","msg":"trace[1221344332] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:623; }","duration":"471.307498ms","start":"2026-04-08T22:56:47.257296Z","end":"2026-04-08T22:56:47.728604Z","steps":["trace[1221344332] 'agreement among raft nodes before linearized reading' (duration: 471.261227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.728634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.257291Z","time spent":"471.336209ms","remote":"127.0.0.1:53286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-04-08T22:56:47.728728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.559824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:56:47.728745Z","caller":"traceutil/trace.go:171","msg":"trace[1665541128] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:623; }","duration":"471.598506ms","start":"2026-04-08T22:56:47.257141Z","end":"2026-04-08T22:56:47.728739Z","steps":["trace[1665541128] 'agreement among raft nodes before linearized reading' (duration: 471.572385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.728764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.25713Z","time spent":"471.629268ms","remote":"127.0.0.1:53286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:56:47.727523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.099065Z","time spent":"628.453768ms","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T22:56:47.730463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.279989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:56:47.730497Z","caller":"traceutil/trace.go:171","msg":"trace[696889242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"361.357031ms","start":"2026-04-08T22:56:47.369132Z","end":"2026-04-08T22:56:47.730489Z","steps":["trace[696889242] 'agreement among raft nodes before linearized reading' (duration: 359.360767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:47.730519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:56:47.369102Z","time spent":"361.412632ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T22:56:48.282514Z","caller":"traceutil/trace.go:171","msg":"trace[1168094655] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"117.137588ms","start":"2026-04-08T22:56:48.165344Z","end":"2026-04-08T22:56:48.282481Z","steps":["trace[1168094655] 'read index received' (duration: 116.317066ms)","trace[1168094655] 'applied index is now lower than readState.Index' (duration: 819.602µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:56:48.282669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.318923ms","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":"info","ts":"2026-04-08T22:56:48.282703Z","caller":"traceutil/trace.go:171","msg":"trace[506722071] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:629; }","duration":"117.397955ms","start":"2026-04-08T22:56:48.165298Z","end":"2026-04-08T22:56:48.282696Z","steps":["trace[506722071] 'agreement among raft nodes before linearized reading' (duration: 117.294832ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:48.282548Z","caller":"traceutil/trace.go:171","msg":"trace[22069039] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"125.600173ms","start":"2026-04-08T22:56:48.156932Z","end":"2026-04-08T22:56:48.282532Z","steps":["trace[22069039] 'process raft request' (duration: 124.960026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:48.282846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.235201ms","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-04-08T22:56:48.282908Z","caller":"traceutil/trace.go:171","msg":"trace[226523976] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:629; }","duration":"117.334044ms","start":"2026-04-08T22:56:48.165552Z","end":"2026-04-08T22:56:48.282886Z","steps":["trace[226523976] 'agreement among raft nodes before linearized reading' (duration: 117.225581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:48.283308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.214831ms","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":"info","ts":"2026-04-08T22:56:48.283759Z","caller":"traceutil/trace.go:171","msg":"trace[869981747] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:629; }","duration":"117.650782ms","start":"2026-04-08T22:56:48.166037Z","end":"2026-04-08T22:56:48.283688Z","steps":["trace[869981747] 'agreement among raft nodes before linearized reading' (duration: 117.181949ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:49.897792Z","caller":"traceutil/trace.go:171","msg":"trace[382826666] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"207.337252ms","start":"2026-04-08T22:56:49.690431Z","end":"2026-04-08T22:56:49.897768Z","steps":["trace[382826666] 'process raft request' (duration: 207.190868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:49.898124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.771327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:56:49.898186Z","caller":"traceutil/trace.go:171","msg":"trace[1310197944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"157.902761ms","start":"2026-04-08T22:56:49.740266Z","end":"2026-04-08T22:56:49.898169Z","steps":["trace[1310197944] 'agreement among raft nodes before linearized reading' (duration: 157.649403ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:49.89833Z","caller":"traceutil/trace.go:171","msg":"trace[519666971] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"157.388078ms","start":"2026-04-08T22:56:49.740345Z","end":"2026-04-08T22:56:49.897733Z","steps":["trace[519666971] 'read index received' (duration: 157.255005ms)","trace[519666971] 'applied index is now lower than readState.Index' (duration: 132.153µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:56:49.900564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.631241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:56:49.900634Z","caller":"traceutil/trace.go:171","msg":"trace[860308042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:665; }","duration":"155.743754ms","start":"2026-04-08T22:56:49.744872Z","end":"2026-04-08T22:56:49.900616Z","steps":["trace[860308042] 'agreement among raft nodes before linearized reading' (duration: 155.525598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:49.900974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.021151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T22:56:49.901063Z","caller":"traceutil/trace.go:171","msg":"trace[1491483427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"156.145383ms","start":"2026-04-08T22:56:49.744886Z","end":"2026-04-08T22:56:49.901032Z","steps":["trace[1491483427] 'agreement among raft nodes before linearized reading' (duration: 155.919857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:49.901112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.170272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:56:49.901172Z","caller":"traceutil/trace.go:171","msg":"trace[709875006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"154.249714ms","start":"2026-04-08T22:56:49.746905Z","end":"2026-04-08T22:56:49.901155Z","steps":["trace[709875006] 'agreement among raft nodes before linearized reading' (duration: 154.083569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:04.430954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.809803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:57:04.431088Z","caller":"traceutil/trace.go:171","msg":"trace[302860346] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"873.728049ms","start":"2026-04-08T22:57:03.557337Z","end":"2026-04-08T22:57:04.431065Z","steps":["trace[302860346] 'read index received' (duration: 482.653309ms)","trace[302860346] 'applied index is now lower than readState.Index' (duration: 391.072749ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:57:04.431411Z","caller":"traceutil/trace.go:171","msg":"trace[1517630984] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"1.125075999s","start":"2026-04-08T22:57:03.306319Z","end":"2026-04-08T22:57:04.431395Z","steps":["trace[1517630984] 'process raft request' (duration: 733.671131ms)","trace[1517630984] 'compare' (duration: 390.313619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:57:04.431509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:03.306293Z","time spent":"1.125169452s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:57:04.43184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.509889ms","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-04-08T22:57:04.431888Z","caller":"traceutil/trace.go:171","msg":"trace[833704682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"874.615072ms","start":"2026-04-08T22:57:03.557258Z","end":"2026-04-08T22:57:04.431873Z","steps":["trace[833704682] 'agreement among raft nodes before linearized reading' (duration: 874.455368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:04.431934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:03.557225Z","time spent":"874.697984ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T22:57:04.617079Z","caller":"traceutil/trace.go:171","msg":"trace[641964645] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"185.924474ms","start":"2026-04-08T22:57:04.431133Z","end":"2026-04-08T22:57:04.617058Z","steps":["trace[641964645] 'read index received' (duration: 185.884573ms)","trace[641964645] 'applied index is now lower than readState.Index' (duration: 38.811µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:57:04.617264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021167312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:57:04.617292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.868484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:57:04.617369Z","caller":"traceutil/trace.go:171","msg":"trace[573365119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"621.965157ms","start":"2026-04-08T22:57:03.995384Z","end":"2026-04-08T22:57:04.617349Z","steps":["trace[573365119] 'agreement among raft nodes before linearized reading' (duration: 621.729191ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:04.617334Z","caller":"traceutil/trace.go:171","msg":"trace[2107665736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"1.021302785s","start":"2026-04-08T22:57:03.595992Z","end":"2026-04-08T22:57:04.617294Z","steps":["trace[2107665736] 'agreement among raft nodes before linearized reading' (duration: 1.021174501s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:04.617419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:03.995375Z","time spent":"622.035918ms","remote":"127.0.0.1:58160","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-04-08T22:57:04.61745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:03.595967Z","time spent":"1.02147148s","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T22:57:04.617536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.148418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:57:04.617637Z","caller":"traceutil/trace.go:171","msg":"trace[166133594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"626.286181ms","start":"2026-04-08T22:57:03.991333Z","end":"2026-04-08T22:57:04.61762Z","steps":["trace[166133594] 'agreement among raft nodes before linearized reading' (duration: 626.050715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:04.617676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:03.991313Z","time spent":"626.354953ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:57:04.617685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.5979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:57:04.617897Z","caller":"traceutil/trace.go:171","msg":"trace[1562940696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"623.890788ms","start":"2026-04-08T22:57:03.993977Z","end":"2026-04-08T22:57:04.617868Z","steps":["trace[1562940696] 'agreement among raft nodes before linearized reading' (duration: 623.304101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:04.617969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:03.993943Z","time spent":"624.011561ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T22:57:04.618041Z","caller":"traceutil/trace.go:171","msg":"trace[1761622927] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"918.574989ms","start":"2026-04-08T22:57:03.698678Z","end":"2026-04-08T22:57:04.617253Z","steps":["trace[1761622927] 'process raft request' (duration: 918.25263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:04.618312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.57356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T22:57:04.618353Z","caller":"traceutil/trace.go:171","msg":"trace[348009811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:790; }","duration":"623.661462ms","start":"2026-04-08T22:57:03.994681Z","end":"2026-04-08T22:57:04.618343Z","steps":["trace[348009811] 'agreement among raft nodes before linearized reading' (duration: 622.953794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:04.61838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:03.994668Z","time spent":"623.706582ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:57:04.618733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:03.698625Z","time spent":"919.521184ms","remote":"127.0.0.1:58124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:57:04.621177Z","caller":"traceutil/trace.go:171","msg":"trace[2118044589] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"184.799244ms","start":"2026-04-08T22:57:04.436362Z","end":"2026-04-08T22:57:04.621161Z","steps":["trace[2118044589] 'process raft request' (duration: 184.702062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:41.332681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198869984178992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:57:41.690151Z","caller":"traceutil/trace.go:171","msg":"trace[252954925] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"858.06806ms","start":"2026-04-08T22:57:40.832052Z","end":"2026-04-08T22:57:41.69012Z","steps":["trace[252954925] 'process raft request' (duration: 857.918167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:41.690315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:40.832034Z","time spent":"858.193304ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:57:41.734236Z","caller":"traceutil/trace.go:171","msg":"trace[1142172307] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1045; }","duration":"901.731088ms","start":"2026-04-08T22:57:40.832482Z","end":"2026-04-08T22:57:41.734213Z","steps":["trace[1142172307] 'read index received' (duration: 857.477766ms)","trace[1142172307] 'applied index is now lower than readState.Index' (duration: 44.252182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:57:41.734532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.305891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:57:41.734609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.823756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:57:41.734715Z","caller":"traceutil/trace.go:171","msg":"trace[332751652] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"767.897342ms","start":"2026-04-08T22:57:40.966807Z","end":"2026-04-08T22:57:41.734704Z","steps":["trace[332751652] 'process raft request' (duration: 767.284987ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:41.734616Z","caller":"traceutil/trace.go:171","msg":"trace[1325119197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"839.436103ms","start":"2026-04-08T22:57:40.895164Z","end":"2026-04-08T22:57:41.7346Z","steps":["trace[1325119197] 'agreement among raft nodes before linearized reading' (duration: 839.32869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:41.734764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:40.895142Z","time spent":"839.610298ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T22:57:41.734813Z","caller":"traceutil/trace.go:171","msg":"trace[604773944] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"899.710553ms","start":"2026-04-08T22:57:40.835095Z","end":"2026-04-08T22:57:41.734805Z","steps":["trace[604773944] 'process raft request' (duration: 898.85195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:41.734838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.51285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-08T22:57:41.734867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:40.835078Z","time spent":"899.764985ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:57:41.734856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.344933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:57:41.734913Z","caller":"traceutil/trace.go:171","msg":"trace[214823399] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:1010; }","duration":"889.640715ms","start":"2026-04-08T22:57:40.845253Z","end":"2026-04-08T22:57:41.734894Z","steps":["trace[214823399] 'agreement among raft nodes before linearized reading' (duration: 889.397748ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:41.73493Z","caller":"traceutil/trace.go:171","msg":"trace[1090346950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"902.474737ms","start":"2026-04-08T22:57:40.83244Z","end":"2026-04-08T22:57:41.734915Z","steps":["trace[1090346950] 'agreement among raft nodes before linearized reading' (duration: 902.216931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:41.734964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:40.832422Z","time spent":"902.53668ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:57:41.734961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:40.845244Z","time spent":"889.708877ms","remote":"127.0.0.1:57892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-04-08T22:57:41.73467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.187285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:57:41.734714Z","caller":"traceutil/trace.go:171","msg":"trace[967653295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"896.96921ms","start":"2026-04-08T22:57:40.837733Z","end":"2026-04-08T22:57:41.734702Z","steps":["trace[967653295] 'agreement among raft nodes before linearized reading' (duration: 896.700374ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:41.735035Z","caller":"traceutil/trace.go:171","msg":"trace[419622588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"140.606185ms","start":"2026-04-08T22:57:41.59442Z","end":"2026-04-08T22:57:41.735026Z","steps":["trace[419622588] 'agreement among raft nodes before linearized reading' (duration: 140.233135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:41.735078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:40.837725Z","time spent":"897.338541ms","remote":"127.0.0.1:58160","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-04-08T22:57:41.735128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.758208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T22:57:41.735218Z","caller":"traceutil/trace.go:171","msg":"trace[1574862921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"895.887422ms","start":"2026-04-08T22:57:40.839304Z","end":"2026-04-08T22:57:41.735192Z","steps":["trace[1574862921] 'agreement among raft nodes before linearized reading' (duration: 895.586844ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:41.735253Z","caller":"traceutil/trace.go:171","msg":"trace[558066135] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"722.006957ms","start":"2026-04-08T22:57:41.013236Z","end":"2026-04-08T22:57:41.735243Z","steps":["trace[558066135] 'process raft request' (duration: 720.935528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:41.735271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:40.839285Z","time spent":"895.976993ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:57:41.734777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:40.966778Z","time spent":"767.963654ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:57:41.735328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:41.013213Z","time spent":"722.061008ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:04.304652Z","caller":"traceutil/trace.go:171","msg":"trace[1616692666] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"500.587532ms","start":"2026-04-08T22:58:03.804021Z","end":"2026-04-08T22:58:04.304609Z","steps":["trace[1616692666] 'process raft request' (duration: 500.336786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:04.304933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:03.803991Z","time spent":"500.774937ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:04.87719Z","caller":"traceutil/trace.go:171","msg":"trace[1009057651] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1124; }","duration":"1.028385551s","start":"2026-04-08T22:58:03.84876Z","end":"2026-04-08T22:58:04.877145Z","steps":["trace[1009057651] 'read index received' (duration: 455.718184ms)","trace[1009057651] 'applied index is now lower than readState.Index' (duration: 572.665367ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:04.877217Z","caller":"traceutil/trace.go:171","msg":"trace[1259688921] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"1.067579318s","start":"2026-04-08T22:58:03.809595Z","end":"2026-04-08T22:58:04.877174Z","steps":["trace[1259688921] 'process raft request' (duration: 1.022441033s)","trace[1259688921] 'compare' (duration: 44.804606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:04.877492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:03.809573Z","time spent":"1.067787692s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:04.877506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028732381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:58:04.877568Z","caller":"traceutil/trace.go:171","msg":"trace[1543981698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"1.028823713s","start":"2026-04-08T22:58:03.848728Z","end":"2026-04-08T22:58:04.877552Z","steps":["trace[1543981698] 'agreement among raft nodes before linearized reading' (duration: 1.028511884s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:04.877617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:03.848713Z","time spent":"1.028896605s","remote":"127.0.0.1:58160","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-04-08T22:58:04.877663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027718973s","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-04-08T22:58:04.877789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.297001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:04.8778Z","caller":"traceutil/trace.go:171","msg":"trace[388905889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"1.027933409s","start":"2026-04-08T22:58:03.84984Z","end":"2026-04-08T22:58:04.877773Z","steps":["trace[388905889] 'agreement among raft nodes before linearized reading' (duration: 1.027576719s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:04.877827Z","caller":"traceutil/trace.go:171","msg":"trace[41016582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"282.379033ms","start":"2026-04-08T22:58:04.595437Z","end":"2026-04-08T22:58:04.877816Z","steps":["trace[41016582] 'agreement among raft nodes before linearized reading' (duration: 282.329041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:04.877857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:03.849804Z","time spent":"1.028043362s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T22:58:04.878102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.375036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:58:04.878141Z","caller":"traceutil/trace.go:171","msg":"trace[572683004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"950.43933ms","start":"2026-04-08T22:58:03.927692Z","end":"2026-04-08T22:58:04.878131Z","steps":["trace[572683004] 'agreement among raft nodes before linearized reading' (duration: 950.330457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:04.87817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:03.927675Z","time spent":"950.48845ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:58:04.877743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.45408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T22:58:04.878189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004062741s","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-04-08T22:58:04.87817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.639197ms","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-04-08T22:58:04.878223Z","caller":"traceutil/trace.go:171","msg":"trace[1324721625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1082; }","duration":"1.004092901s","start":"2026-04-08T22:58:03.874117Z","end":"2026-04-08T22:58:04.87821Z","steps":["trace[1324721625] 'agreement among raft nodes before linearized reading' (duration: 1.00400024s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:04.878231Z","caller":"traceutil/trace.go:171","msg":"trace[930424721] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1082; }","duration":"648.74899ms","start":"2026-04-08T22:58:04.229468Z","end":"2026-04-08T22:58:04.878217Z","steps":["trace[930424721] 'agreement among raft nodes before linearized reading' (duration: 648.659549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:04.878257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:03.87408Z","time spent":"1.004165324s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:58:04.87827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:04.229442Z","time spent":"648.816202ms","remote":"127.0.0.1:53472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-08T22:58:04.878243Z","caller":"traceutil/trace.go:171","msg":"trace[1168367763] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1082; }","duration":"372.993883ms","start":"2026-04-08T22:58:04.505215Z","end":"2026-04-08T22:58:04.878209Z","steps":["trace[1168367763] 'agreement among raft nodes before linearized reading' (duration: 372.49181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:04.87835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:04.50511Z","time spent":"373.203529ms","remote":"127.0.0.1:58050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-08T22:58:05.074361Z","caller":"traceutil/trace.go:171","msg":"trace[162322240] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"189.914682ms","start":"2026-04-08T22:58:04.884404Z","end":"2026-04-08T22:58:05.074319Z","steps":["trace[162322240] 'process raft request' (duration: 129.54424ms)","trace[162322240] 'compare' (duration: 60.119626ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:05.074833Z","caller":"traceutil/trace.go:171","msg":"trace[194629096] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"189.551573ms","start":"2026-04-08T22:58:04.885241Z","end":"2026-04-08T22:58:05.074792Z","steps":["trace[194629096] 'process raft request' (duration: 189.024129ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:05.074857Z","caller":"traceutil/trace.go:171","msg":"trace[621044794] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"188.65656ms","start":"2026-04-08T22:58:04.886156Z","end":"2026-04-08T22:58:05.074813Z","steps":["trace[621044794] 'process raft request' (duration: 188.430044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:06.483655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.1989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-08T22:58:06.483784Z","caller":"traceutil/trace.go:171","msg":"trace[1978354424] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"274.580265ms","start":"2026-04-08T22:58:06.209173Z","end":"2026-04-08T22:58:06.483753Z","steps":["trace[1978354424] 'read index received' (duration: 98.993µs)","trace[1978354424] 'applied index is now lower than readState.Index' (duration: 274.479462ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:06.48397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.756569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:06.4841Z","caller":"traceutil/trace.go:171","msg":"trace[1276299787] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1087; }","duration":"274.967996ms","start":"2026-04-08T22:58:06.209104Z","end":"2026-04-08T22:58:06.484072Z","steps":["trace[1276299787] 'agreement among raft nodes before linearized reading' (duration: 274.716448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:06.484183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.975051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:58:06.484309Z","caller":"traceutil/trace.go:171","msg":"trace[304855186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"173.175066ms","start":"2026-04-08T22:58:06.311105Z","end":"2026-04-08T22:58:06.48428Z","steps":["trace[304855186] 'agreement among raft nodes before linearized reading' (duration: 172.829776ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:06.925025Z","caller":"traceutil/trace.go:171","msg":"trace[1082089304] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"328.909936ms","start":"2026-04-08T22:58:06.596088Z","end":"2026-04-08T22:58:06.924998Z","steps":["trace[1082089304] 'read index received' (duration: 328.891765ms)","trace[1082089304] 'applied index is now lower than readState.Index' (duration: 16.701µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:06.925085Z","caller":"traceutil/trace.go:171","msg":"trace[1557400945] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"435.205745ms","start":"2026-04-08T22:58:06.489828Z","end":"2026-04-08T22:58:06.925033Z","steps":["trace[1557400945] 'process raft request' (duration: 435.01116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:06.925167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.054279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:06.925258Z","caller":"traceutil/trace.go:171","msg":"trace[1815853800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"329.187114ms","start":"2026-04-08T22:58:06.596046Z","end":"2026-04-08T22:58:06.925233Z","steps":["trace[1815853800] 'agreement among raft nodes before linearized reading' (duration: 329.05135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:06.925323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:06.59602Z","time spent":"329.293216ms","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T22:58:06.925411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:06.489795Z","time spent":"435.38737ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:07.113279Z","caller":"traceutil/trace.go:171","msg":"trace[1577524794] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"188.156436ms","start":"2026-04-08T22:58:06.925101Z","end":"2026-04-08T22:58:07.113257Z","steps":["trace[1577524794] 'read index received' (duration: 187.983901ms)","trace[1577524794] 'applied index is now lower than readState.Index' (duration: 171.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:07.113407Z","caller":"traceutil/trace.go:171","msg":"trace[1566966277] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"225.555834ms","start":"2026-04-08T22:58:06.887842Z","end":"2026-04-08T22:58:07.113398Z","steps":["trace[1566966277] 'process raft request' (duration: 225.252726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:07.113859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.765007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:58:07.113994Z","caller":"traceutil/trace.go:171","msg":"trace[1224289459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"156.968462ms","start":"2026-04-08T22:58:06.956996Z","end":"2026-04-08T22:58:07.113965Z","steps":["trace[1224289459] 'agreement among raft nodes before linearized reading' (duration: 156.50461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:07.113981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.094786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:07.114089Z","caller":"traceutil/trace.go:171","msg":"trace[269381237] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1089; }","duration":"455.396964ms","start":"2026-04-08T22:58:06.658667Z","end":"2026-04-08T22:58:07.114064Z","steps":["trace[269381237] 'agreement among raft nodes before linearized reading' (duration: 455.094776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:07.114158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:06.658623Z","time spent":"455.522798ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-04-08T22:58:09.228556Z","caller":"traceutil/trace.go:171","msg":"trace[1537563638] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"292.726869ms","start":"2026-04-08T22:58:08.935788Z","end":"2026-04-08T22:58:09.228515Z","steps":["trace[1537563638] 'process raft request' (duration: 292.459883ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:09.466673Z","caller":"traceutil/trace.go:171","msg":"trace[1755913063] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1138; }","duration":"306.077256ms","start":"2026-04-08T22:58:09.160558Z","end":"2026-04-08T22:58:09.466636Z","steps":["trace[1755913063] 'read index received' (duration: 67.715739ms)","trace[1755913063] 'applied index is now lower than readState.Index' (duration: 238.360157ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:09.466751Z","caller":"traceutil/trace.go:171","msg":"trace[738160736] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"347.028311ms","start":"2026-04-08T22:58:09.119672Z","end":"2026-04-08T22:58:09.4667Z","steps":["trace[738160736] 'process raft request' (duration: 346.732223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:09.467115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.525708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T22:58:09.46718Z","caller":"traceutil/trace.go:171","msg":"trace[1864130928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"306.678873ms","start":"2026-04-08T22:58:09.160487Z","end":"2026-04-08T22:58:09.467166Z","steps":["trace[1864130928] 'agreement among raft nodes before linearized reading' (duration: 306.364995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:09.467237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:09.160457Z","time spent":"306.767495ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T22:58:09.467222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:09.119639Z","time spent":"347.345449ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:09.467553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.540161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:58:09.467636Z","caller":"traceutil/trace.go:171","msg":"trace[1637563154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"299.662174ms","start":"2026-04-08T22:58:09.16796Z","end":"2026-04-08T22:58:09.467622Z","steps":["trace[1637563154] 'agreement among raft nodes before linearized reading' (duration: 299.458628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:09.467854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.053495ms","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-04-08T22:58:09.467972Z","caller":"traceutil/trace.go:171","msg":"trace[1687027409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"300.207539ms","start":"2026-04-08T22:58:09.167751Z","end":"2026-04-08T22:58:09.467958Z","steps":["trace[1687027409] 'agreement among raft nodes before linearized reading' (duration: 299.388248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:09.46807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:09.167732Z","time spent":"300.324972ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:58:09.467966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.069335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T22:58:09.468287Z","caller":"traceutil/trace.go:171","msg":"trace[1591048498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1095; }","duration":"300.397904ms","start":"2026-04-08T22:58:09.167867Z","end":"2026-04-08T22:58:09.468265Z","steps":["trace[1591048498] 'agreement among raft nodes before linearized reading' (duration: 299.892391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:09.468357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:09.167857Z","time spent":"300.488037ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T22:58:21.216755Z","caller":"traceutil/trace.go:171","msg":"trace[1031777954] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"273.229239ms","start":"2026-04-08T22:58:20.943502Z","end":"2026-04-08T22:58:21.216731Z","steps":["trace[1031777954] 'process raft request' (duration: 273.050894ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:23.771979Z","caller":"traceutil/trace.go:171","msg":"trace[1971822551] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"176.167897ms","start":"2026-04-08T22:58:23.595779Z","end":"2026-04-08T22:58:23.771946Z","steps":["trace[1971822551] 'read index received' (duration: 175.260663ms)","trace[1971822551] 'applied index is now lower than readState.Index' (duration: 905.574µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:23.772043Z","caller":"traceutil/trace.go:171","msg":"trace[1310184703] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"208.474079ms","start":"2026-04-08T22:58:23.563521Z","end":"2026-04-08T22:58:23.771995Z","steps":["trace[1310184703] 'process raft request' (duration: 207.650247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:23.772187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.342791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:23.772269Z","caller":"traceutil/trace.go:171","msg":"trace[1118578838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"176.534376ms","start":"2026-04-08T22:58:23.595715Z","end":"2026-04-08T22:58:23.772249Z","steps":["trace[1118578838] 'agreement among raft nodes before linearized reading' (duration: 176.356651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:23.772282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.981754ms","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-04-08T22:58:23.772276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.051479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:58:23.772321Z","caller":"traceutil/trace.go:171","msg":"trace[247678004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"154.088687ms","start":"2026-04-08T22:58:23.618223Z","end":"2026-04-08T22:58:23.772311Z","steps":["trace[247678004] 'agreement among raft nodes before linearized reading' (duration: 153.882651ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:23.772363Z","caller":"traceutil/trace.go:171","msg":"trace[239908007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1192; }","duration":"451.206083ms","start":"2026-04-08T22:58:23.321136Z","end":"2026-04-08T22:58:23.772342Z","steps":["trace[239908007] 'range keys from in-memory index tree' (duration: 450.248618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:23.772411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:23.321109Z","time spent":"451.293094ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:58:23.772599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.916581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:58:23.772624Z","caller":"traceutil/trace.go:171","msg":"trace[1756028118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"138.992682ms","start":"2026-04-08T22:58:23.633624Z","end":"2026-04-08T22:58:23.772617Z","steps":["trace[1756028118] 'agreement among raft nodes before linearized reading' (duration: 138.907381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:23.772647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.777415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T22:58:23.772693Z","caller":"traceutil/trace.go:171","msg":"trace[1773566562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1193; }","duration":"114.868679ms","start":"2026-04-08T22:58:23.657811Z","end":"2026-04-08T22:58:23.77268Z","steps":["trace[1773566562] 'agreement among raft nodes before linearized reading' (duration: 114.648954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:23.77275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.53514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:58:23.772799Z","caller":"traceutil/trace.go:171","msg":"trace[635095671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"144.609533ms","start":"2026-04-08T22:58:23.628179Z","end":"2026-04-08T22:58:23.772788Z","steps":["trace[635095671] 'agreement among raft nodes before linearized reading' (duration: 144.424278ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:36.013878Z","caller":"traceutil/trace.go:171","msg":"trace[2049411778] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"184.398166ms","start":"2026-04-08T22:58:35.829451Z","end":"2026-04-08T22:58:36.01385Z","steps":["trace[2049411778] 'read index received' (duration: 184.206211ms)","trace[2049411778] 'applied index is now lower than readState.Index' (duration: 190.695µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:36.013982Z","caller":"traceutil/trace.go:171","msg":"trace[1993846988] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"194.439274ms","start":"2026-04-08T22:58:35.819504Z","end":"2026-04-08T22:58:36.013943Z","steps":["trace[1993846988] 'process raft request' (duration: 194.144357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:36.014187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.704404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T22:58:36.01426Z","caller":"traceutil/trace.go:171","msg":"trace[137883642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1232; }","duration":"184.835117ms","start":"2026-04-08T22:58:35.829402Z","end":"2026-04-08T22:58:36.014237Z","steps":["trace[137883642] 'agreement among raft nodes before linearized reading' (duration: 184.633262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:36.014367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.760531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:58:36.014416Z","caller":"traceutil/trace.go:171","msg":"trace[718117637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"149.859444ms","start":"2026-04-08T22:58:35.864536Z","end":"2026-04-08T22:58:36.014395Z","steps":["trace[718117637] 'agreement among raft nodes before linearized reading' (duration: 149.670829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:36.014403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.01986ms","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-04-08T22:58:36.014475Z","caller":"traceutil/trace.go:171","msg":"trace[1891835350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"149.105662ms","start":"2026-04-08T22:58:35.865351Z","end":"2026-04-08T22:58:36.014456Z","steps":["trace[1891835350] 'agreement among raft nodes before linearized reading' (duration: 148.928337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:36.014474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.873827ms","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-04-08T22:58:36.014592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.862997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:58:36.014613Z","caller":"traceutil/trace.go:171","msg":"trace[309475711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"149.034271ms","start":"2026-04-08T22:58:35.865552Z","end":"2026-04-08T22:58:36.014586Z","steps":["trace[309475711] 'agreement among raft nodes before linearized reading' (duration: 148.696103ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:36.014644Z","caller":"traceutil/trace.go:171","msg":"trace[960480382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"148.97517ms","start":"2026-04-08T22:58:35.865655Z","end":"2026-04-08T22:58:36.01463Z","steps":["trace[960480382] 'agreement among raft nodes before linearized reading' (duration: 148.795465ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:36.457014Z","caller":"traceutil/trace.go:171","msg":"trace[2023236261] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"168.493351ms","start":"2026-04-08T22:58:36.288494Z","end":"2026-04-08T22:58:36.456988Z","steps":["trace[2023236261] 'process raft request' (duration: 167.630968ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:39.261508Z","caller":"traceutil/trace.go:171","msg":"trace[1403706746] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"105.280361ms","start":"2026-04-08T22:58:39.156193Z","end":"2026-04-08T22:58:39.261473Z","steps":["trace[1403706746] 'process raft request' (duration: 41.597041ms)","trace[1403706746] 'compare' (duration: 63.546067ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:39.303706Z","caller":"traceutil/trace.go:171","msg":"trace[170098603] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"144.275955ms","start":"2026-04-08T22:58:39.159386Z","end":"2026-04-08T22:58:39.303662Z","steps":["trace[170098603] 'process raft request' (duration: 144.196172ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:39.30407Z","caller":"traceutil/trace.go:171","msg":"trace[1830402045] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"147.405277ms","start":"2026-04-08T22:58:39.156616Z","end":"2026-04-08T22:58:39.304021Z","steps":["trace[1830402045] 'process raft request' (duration: 146.822102ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:40.412494Z","caller":"traceutil/trace.go:171","msg":"trace[1842990303] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"287.752246ms","start":"2026-04-08T22:58:40.124715Z","end":"2026-04-08T22:58:40.412467Z","steps":["trace[1842990303] 'read index received' (duration: 287.491039ms)","trace[1842990303] 'applied index is now lower than readState.Index' (duration: 260.237µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:40.412901Z","caller":"traceutil/trace.go:171","msg":"trace[1386953846] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"383.512824ms","start":"2026-04-08T22:58:40.02937Z","end":"2026-04-08T22:58:40.412883Z","steps":["trace[1386953846] 'process raft request' (duration: 382.928017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:40.413043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.029332Z","time spent":"383.617587ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:40.413383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.67156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T22:58:40.413426Z","caller":"traceutil/trace.go:171","msg":"trace[643858720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1265; }","duration":"288.749594ms","start":"2026-04-08T22:58:40.124665Z","end":"2026-04-08T22:58:40.413414Z","steps":["trace[643858720] 'agreement among raft nodes before linearized reading' (duration: 288.61433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:40.413679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.932181ms","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-04-08T22:58:40.413718Z","caller":"traceutil/trace.go:171","msg":"trace[1527352335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"284.987962ms","start":"2026-04-08T22:58:40.128721Z","end":"2026-04-08T22:58:40.413709Z","steps":["trace[1527352335] 'agreement among raft nodes before linearized reading' (duration: 284.868179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:40.413941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.27565ms","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-04-08T22:58:40.413981Z","caller":"traceutil/trace.go:171","msg":"trace[1249046391] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1265; }","duration":"124.323982ms","start":"2026-04-08T22:58:40.289648Z","end":"2026-04-08T22:58:40.413972Z","steps":["trace[1249046391] 'agreement among raft nodes before linearized reading' (duration: 124.259479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:40.414194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.318464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T22:58:40.414228Z","caller":"traceutil/trace.go:171","msg":"trace[1643823152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"284.402587ms","start":"2026-04-08T22:58:40.129817Z","end":"2026-04-08T22:58:40.41422Z","steps":["trace[1643823152] 'agreement among raft nodes before linearized reading' (duration: 284.315434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:40.414616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.746666ms","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-04-08T22:58:40.414652Z","caller":"traceutil/trace.go:171","msg":"trace[262081213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"285.002642ms","start":"2026-04-08T22:58:40.12964Z","end":"2026-04-08T22:58:40.414643Z","steps":["trace[262081213] 'agreement among raft nodes before linearized reading' (duration: 284.707694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:40.414843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.080182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T22:58:40.414876Z","caller":"traceutil/trace.go:171","msg":"trace[498591578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"286.120162ms","start":"2026-04-08T22:58:40.128748Z","end":"2026-04-08T22:58:40.414868Z","steps":["trace[498591578] 'agreement among raft nodes before linearized reading' (duration: 286.03207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:41.095768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198869984179590,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:58:41.20597Z","caller":"traceutil/trace.go:171","msg":"trace[1927104467] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"780.381726ms","start":"2026-04-08T22:58:40.425542Z","end":"2026-04-08T22:58:41.205924Z","steps":["trace[1927104467] 'process raft request' (duration: 752.070671ms)","trace[1927104467] 'compare' (duration: 27.521384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:41.206173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.425519Z","time spent":"780.54261ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:41.444137Z","caller":"traceutil/trace.go:171","msg":"trace[1518171080] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"1.018468376s","start":"2026-04-08T22:58:40.42562Z","end":"2026-04-08T22:58:41.444088Z","steps":["trace[1518171080] 'process raft request' (duration: 951.084865ms)","trace[1518171080] 'compare' (duration: 67.090843ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:41.444233Z","caller":"traceutil/trace.go:171","msg":"trace[630093381] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"1.018445776s","start":"2026-04-08T22:58:40.425752Z","end":"2026-04-08T22:58:41.444198Z","steps":["trace[630093381] 'process raft request' (duration: 1.018284551s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:41.444211Z","caller":"traceutil/trace.go:171","msg":"trace[502305711] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1318; }","duration":"849.058001ms","start":"2026-04-08T22:58:40.595096Z","end":"2026-04-08T22:58:41.444154Z","steps":["trace[502305711] 'read index received' (duration: 582.580512ms)","trace[502305711] 'applied index is now lower than readState.Index' (duration: 266.475829ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:41.444306Z","caller":"traceutil/trace.go:171","msg":"trace[821379673] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"1.018576179s","start":"2026-04-08T22:58:40.425699Z","end":"2026-04-08T22:58:41.444275Z","steps":["trace[821379673] 'process raft request' (duration: 1.018279591s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:41.444335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.425603Z","time spent":"1.018631399s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:41.444348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.425743Z","time spent":"1.018554509s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:41.444406Z","caller":"traceutil/trace.go:171","msg":"trace[1124108820] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"1.018549737s","start":"2026-04-08T22:58:40.425799Z","end":"2026-04-08T22:58:41.444349Z","steps":["trace[1124108820] 'process raft request' (duration: 1.018295591s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:41.444464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.346988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:58:41.444498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.425691Z","time spent":"1.018679582s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:41.444566Z","caller":"traceutil/trace.go:171","msg":"trace[731590253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"849.488363ms","start":"2026-04-08T22:58:40.595043Z","end":"2026-04-08T22:58:41.444532Z","steps":["trace[731590253] 'agreement among raft nodes before linearized reading' (duration: 849.357449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:41.444621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.595021Z","time spent":"849.587275ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T22:58:41.444637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.869639ms","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":"warn","ts":"2026-04-08T22:58:41.444656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.425793Z","time spent":"1.018723772s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:41.444688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.17282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-08T22:58:41.444704Z","caller":"traceutil/trace.go:171","msg":"trace[648380972] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1270; }","duration":"325.000761ms","start":"2026-04-08T22:58:41.119682Z","end":"2026-04-08T22:58:41.444683Z","steps":["trace[648380972] 'agreement among raft nodes before linearized reading' (duration: 324.867208ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:41.444738Z","caller":"traceutil/trace.go:171","msg":"trace[1438557682] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1270; }","duration":"640.262453ms","start":"2026-04-08T22:58:40.804461Z","end":"2026-04-08T22:58:41.444724Z","steps":["trace[1438557682] 'agreement among raft nodes before linearized reading' (duration: 640.066628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:41.444748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:41.11965Z","time spent":"325.088514ms","remote":"127.0.0.1:58386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:58:41.444771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.804441Z","time spent":"640.321444ms","remote":"127.0.0.1:58378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":134,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:58:41.444888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.036329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T22:58:41.444955Z","caller":"traceutil/trace.go:171","msg":"trace[223450801] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:1270; }","duration":"597.128142ms","start":"2026-04-08T22:58:40.84781Z","end":"2026-04-08T22:58:41.444939Z","steps":["trace[223450801] 'agreement among raft nodes before linearized reading' (duration: 596.915396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:41.445005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:40.847797Z","time spent":"597.195803ms","remote":"127.0.0.1:57892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-04-08T22:58:41.680111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.192931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T22:58:41.680248Z","caller":"traceutil/trace.go:171","msg":"trace[1828645831] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1271; }","duration":"135.410968ms","start":"2026-04-08T22:58:41.544812Z","end":"2026-04-08T22:58:41.680223Z","steps":["trace[1828645831] 'range keys from in-memory index tree' (duration: 135.057818ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:42.809655Z","caller":"traceutil/trace.go:171","msg":"trace[1968755209] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"380.617599ms","start":"2026-04-08T22:58:42.429003Z","end":"2026-04-08T22:58:42.80962Z","steps":["trace[1968755209] 'process raft request' (duration: 380.408043ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:42.809723Z","caller":"traceutil/trace.go:171","msg":"trace[540007254] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1326; }","duration":"213.644596ms","start":"2026-04-08T22:58:42.59604Z","end":"2026-04-08T22:58:42.809685Z","steps":["trace[540007254] 'read index received' (duration: 213.625085ms)","trace[540007254] 'applied index is now lower than readState.Index' (duration: 16.271µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:42.809899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:42.42899Z","time spent":"380.762472ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:42.809939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.861031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:42.810028Z","caller":"traceutil/trace.go:171","msg":"trace[1690091099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"214.026125ms","start":"2026-04-08T22:58:42.595982Z","end":"2026-04-08T22:58:42.810008Z","steps":["trace[1690091099] 'agreement among raft nodes before linearized reading' (duration: 213.8257ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:43.038472Z","caller":"traceutil/trace.go:171","msg":"trace[101215645] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"226.439098ms","start":"2026-04-08T22:58:42.812008Z","end":"2026-04-08T22:58:43.038448Z","steps":["trace[101215645] 'read index received' (duration: 140.291316ms)","trace[101215645] 'applied index is now lower than readState.Index' (duration: 86.145432ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:43.038611Z","caller":"traceutil/trace.go:171","msg":"trace[1732502763] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"267.84959ms","start":"2026-04-08T22:58:42.770686Z","end":"2026-04-08T22:58:43.038535Z","steps":["trace[1732502763] 'process raft request' (duration: 181.709697ms)","trace[1732502763] 'compare' (duration: 85.748943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:43.038725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.554087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:43.038827Z","caller":"traceutil/trace.go:171","msg":"trace[1925417313] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1273; }","duration":"180.683101ms","start":"2026-04-08T22:58:42.85812Z","end":"2026-04-08T22:58:43.038803Z","steps":["trace[1925417313] 'agreement among raft nodes before linearized reading' (duration: 180.511286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:43.038666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.646524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:43.03893Z","caller":"traceutil/trace.go:171","msg":"trace[1246681756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"226.967254ms","start":"2026-04-08T22:58:42.811949Z","end":"2026-04-08T22:58:43.038917Z","steps":["trace[1246681756] 'agreement among raft nodes before linearized reading' (duration: 226.631685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:43.951484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198869984179614,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:58:44.033431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.311565ms","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-04-08T22:58:44.033529Z","caller":"traceutil/trace.go:171","msg":"trace[1744008083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"822.477459ms","start":"2026-04-08T22:58:43.211027Z","end":"2026-04-08T22:58:44.033505Z","steps":["trace[1744008083] 'range keys from in-memory index tree' (duration: 822.049078ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:44.033542Z","caller":"traceutil/trace.go:171","msg":"trace[234533128] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"583.052262ms","start":"2026-04-08T22:58:43.450459Z","end":"2026-04-08T22:58:44.033512Z","steps":["trace[234533128] 'read index received' (duration: 521.077779ms)","trace[234533128] 'applied index is now lower than readState.Index' (duration: 61.973223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:44.033579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:43.211Z","time spent":"822.569442ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T22:58:44.033733Z","caller":"traceutil/trace.go:171","msg":"trace[1755470737] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"670.365576ms","start":"2026-04-08T22:58:43.363352Z","end":"2026-04-08T22:58:44.033718Z","steps":["trace[1755470737] 'process raft request' (duration: 608.28636ms)","trace[1755470737] 'compare' (duration: 61.742117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:44.033959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:43.363312Z","time spent":"670.453658ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:44.034164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.675379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T22:58:44.03425Z","caller":"traceutil/trace.go:171","msg":"trace[1913365365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1274; }","duration":"583.817123ms","start":"2026-04-08T22:58:43.450411Z","end":"2026-04-08T22:58:44.034228Z","steps":["trace[1913365365] 'agreement among raft nodes before linearized reading' (duration: 583.589827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:44.034298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:43.450382Z","time spent":"583.902785ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:58:44.035185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.915759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:44.035233Z","caller":"traceutil/trace.go:171","msg":"trace[1367494282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1274; }","duration":"408.022873ms","start":"2026-04-08T22:58:43.627197Z","end":"2026-04-08T22:58:44.03522Z","steps":["trace[1367494282] 'agreement among raft nodes before linearized reading' (duration: 407.934491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:44.035275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:43.627158Z","time spent":"408.104305ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:58:44.035331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.558932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T22:58:44.035377Z","caller":"traceutil/trace.go:171","msg":"trace[291741647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"584.685666ms","start":"2026-04-08T22:58:43.45068Z","end":"2026-04-08T22:58:44.035365Z","steps":["trace[291741647] 'agreement among raft nodes before linearized reading' (duration: 584.4447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:44.035427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:43.450646Z","time spent":"584.772209ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T22:58:44.035657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.173825ms","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-04-08T22:58:44.0357Z","caller":"traceutil/trace.go:171","msg":"trace[1040591992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"583.229576ms","start":"2026-04-08T22:58:43.452452Z","end":"2026-04-08T22:58:44.035682Z","steps":["trace[1040591992] 'agreement among raft nodes before linearized reading' (duration: 583.122573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:44.035731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:43.452442Z","time spent":"583.282368ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T22:58:44.035728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.438699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T22:58:44.035765Z","caller":"traceutil/trace.go:171","msg":"trace[1830112718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"584.497311ms","start":"2026-04-08T22:58:43.451259Z","end":"2026-04-08T22:58:44.035756Z","steps":["trace[1830112718] 'agreement among raft nodes before linearized reading' (duration: 584.396718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:44.035791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:43.451246Z","time spent":"584.535362ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T22:58:44.253584Z","caller":"traceutil/trace.go:171","msg":"trace[280228889] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"215.558516ms","start":"2026-04-08T22:58:44.037996Z","end":"2026-04-08T22:58:44.253555Z","steps":["trace[280228889] 'read index received' (duration: 178.06182ms)","trace[280228889] 'applied index is now lower than readState.Index' (duration: 37.495766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:44.253985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.961467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T22:58:44.254037Z","caller":"traceutil/trace.go:171","msg":"trace[1001794521] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1275; }","duration":"216.032149ms","start":"2026-04-08T22:58:44.03799Z","end":"2026-04-08T22:58:44.254022Z","steps":["trace[1001794521] 'agreement among raft nodes before linearized reading' (duration: 215.673419ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:44.254277Z","caller":"traceutil/trace.go:171","msg":"trace[1464543419] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"216.219535ms","start":"2026-04-08T22:58:44.037707Z","end":"2026-04-08T22:58:44.253927Z","steps":["trace[1464543419] 'process raft request' (duration: 178.42907ms)","trace[1464543419] 'compare' (duration: 36.997493ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:44.292041Z","caller":"traceutil/trace.go:171","msg":"trace[960314056] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"250.586236ms","start":"2026-04-08T22:58:44.041439Z","end":"2026-04-08T22:58:44.292025Z","steps":["trace[960314056] 'process raft request' (duration: 250.534615ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:44.292427Z","caller":"traceutil/trace.go:171","msg":"trace[2069121300] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"251.661416ms","start":"2026-04-08T22:58:44.040717Z","end":"2026-04-08T22:58:44.292378Z","steps":["trace[2069121300] 'process raft request' (duration: 251.156382ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:44.292464Z","caller":"traceutil/trace.go:171","msg":"trace[1416384400] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"251.612334ms","start":"2026-04-08T22:58:44.040834Z","end":"2026-04-08T22:58:44.292447Z","steps":["trace[1416384400] 'process raft request' (duration: 251.0799ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:44.29246Z","caller":"traceutil/trace.go:171","msg":"trace[1488531789] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"251.79935ms","start":"2026-04-08T22:58:44.040624Z","end":"2026-04-08T22:58:44.292423Z","steps":["trace[1488531789] 'process raft request' (duration: 251.114532ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:45.081137Z","caller":"traceutil/trace.go:171","msg":"trace[325183092] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"264.633955ms","start":"2026-04-08T22:58:44.816472Z","end":"2026-04-08T22:58:45.081106Z","steps":["trace[325183092] 'process raft request' (duration: 264.454959ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:45.763242Z","caller":"traceutil/trace.go:171","msg":"trace[1534803417] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"252.615531ms","start":"2026-04-08T22:58:45.510603Z","end":"2026-04-08T22:58:45.763218Z","steps":["trace[1534803417] 'process raft request' (duration: 252.353254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:45.763457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.039604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:45.763558Z","caller":"traceutil/trace.go:171","msg":"trace[1286619755] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1281; }","duration":"151.221519ms","start":"2026-04-08T22:58:45.612315Z","end":"2026-04-08T22:58:45.763536Z","steps":["trace[1286619755] 'agreement among raft nodes before linearized reading' (duration: 150.994343ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:45.763243Z","caller":"traceutil/trace.go:171","msg":"trace[1005844480] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"150.827678ms","start":"2026-04-08T22:58:45.612381Z","end":"2026-04-08T22:58:45.763209Z","steps":["trace[1005844480] 'read index received' (duration: 150.488419ms)","trace[1005844480] 'applied index is now lower than readState.Index' (duration: 337.999µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:46.252201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.654994ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:46.252417Z","caller":"traceutil/trace.go:171","msg":"trace[2061336642] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1282; }","duration":"264.89794ms","start":"2026-04-08T22:58:45.987484Z","end":"2026-04-08T22:58:46.252382Z","steps":["trace[2061336642] 'range keys from in-memory index tree' (duration: 264.627544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:46.252724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.518726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:58:46.252903Z","caller":"traceutil/trace.go:171","msg":"trace[164202749] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"311.78834ms","start":"2026-04-08T22:58:45.941095Z","end":"2026-04-08T22:58:46.252883Z","steps":["trace[164202749] 'process raft request' (duration: 311.733099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:46.252965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:45.941072Z","time spent":"311.864842ms","remote":"127.0.0.1:57950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:58:46.252948Z","caller":"traceutil/trace.go:171","msg":"trace[310616913] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"462.935548ms","start":"2026-04-08T22:58:45.789982Z","end":"2026-04-08T22:58:46.252917Z","steps":["trace[310616913] 'read index received' (duration: 60.098214ms)","trace[310616913] 'applied index is now lower than readState.Index' (duration: 402.834244ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:46.252937Z","caller":"traceutil/trace.go:171","msg":"trace[1883348348] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"464.694205ms","start":"2026-04-08T22:58:45.788205Z","end":"2026-04-08T22:58:46.2529Z","steps":["trace[1883348348] 'process raft request' (duration: 61.939553ms)","trace[1883348348] 'compare' (duration: 401.995832ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:46.253179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.174585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-04-08T22:58:46.253169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:45.788189Z","time spent":"464.882871ms","remote":"127.0.0.1:57950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:58:46.253204Z","caller":"traceutil/trace.go:171","msg":"trace[1080203950] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1284; }","duration":"463.250987ms","start":"2026-04-08T22:58:45.789946Z","end":"2026-04-08T22:58:46.253197Z","steps":["trace[1080203950] 'agreement among raft nodes before linearized reading' (duration: 463.062511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:46.253238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:45.789934Z","time spent":"463.299097ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-08T22:58:46.458667Z","caller":"traceutil/trace.go:171","msg":"trace[1288424569] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"485.342199ms","start":"2026-04-08T22:58:45.97325Z","end":"2026-04-08T22:58:46.458593Z","steps":["trace[1288424569] 'process raft request' (duration: 470.188502ms)","trace[1288424569] 'compare' (duration: 14.960051ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:46.458851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:45.973216Z","time spent":"485.539405ms","remote":"127.0.0.1:58050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5964,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:46.495073Z","caller":"traceutil/trace.go:171","msg":"trace[905423718] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"237.176927ms","start":"2026-04-08T22:58:46.257872Z","end":"2026-04-08T22:58:46.495049Z","steps":["trace[905423718] 'process raft request' (duration: 237.044733ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:46.495447Z","caller":"traceutil/trace.go:171","msg":"trace[1676239838] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1338; }","duration":"237.263859ms","start":"2026-04-08T22:58:46.258156Z","end":"2026-04-08T22:58:46.49542Z","steps":["trace[1676239838] 'read index received' (duration: 185.411068ms)","trace[1676239838] 'applied index is now lower than readState.Index' (duration: 51.851281ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:46.495604Z","caller":"traceutil/trace.go:171","msg":"trace[65740943] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"237.100655ms","start":"2026-04-08T22:58:46.258491Z","end":"2026-04-08T22:58:46.495592Z","steps":["trace[65740943] 'process raft request' (duration: 236.523439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:46.49596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.101505ms","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-04-08T22:58:46.49601Z","caller":"traceutil/trace.go:171","msg":"trace[1468467161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1287; }","duration":"199.197126ms","start":"2026-04-08T22:58:46.296802Z","end":"2026-04-08T22:58:46.495999Z","steps":["trace[1468467161] 'agreement among raft nodes before linearized reading' (duration: 199.002842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:46.496204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.04673ms","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-04-08T22:58:46.49624Z","caller":"traceutil/trace.go:171","msg":"trace[1682854860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"238.114602ms","start":"2026-04-08T22:58:46.258116Z","end":"2026-04-08T22:58:46.496231Z","steps":["trace[1682854860] 'agreement among raft nodes before linearized reading' (duration: 238.0252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:46.496427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.357911ms","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-04-08T22:58:46.496716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.510075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:58:46.496731Z","caller":"traceutil/trace.go:171","msg":"trace[465593875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1287; }","duration":"199.442874ms","start":"2026-04-08T22:58:46.297033Z","end":"2026-04-08T22:58:46.496475Z","steps":["trace[465593875] 'agreement among raft nodes before linearized reading' (duration: 199.267229ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:46.496759Z","caller":"traceutil/trace.go:171","msg":"trace[458391854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1287; }","duration":"199.602857ms","start":"2026-04-08T22:58:46.297146Z","end":"2026-04-08T22:58:46.496749Z","steps":["trace[458391854] 'agreement among raft nodes before linearized reading' (duration: 199.495315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:46.496445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.460785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:58:46.496874Z","caller":"traceutil/trace.go:171","msg":"trace[1819662084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"199.918837ms","start":"2026-04-08T22:58:46.296944Z","end":"2026-04-08T22:58:46.496862Z","steps":["trace[1819662084] 'agreement among raft nodes before linearized reading' (duration: 199.438714ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:46.692885Z","caller":"traceutil/trace.go:171","msg":"trace[920592317] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"191.389318ms","start":"2026-04-08T22:58:46.501461Z","end":"2026-04-08T22:58:46.692851Z","steps":["trace[920592317] 'process raft request' (duration: 136.033842ms)","trace[920592317] 'compare' (duration: 55.211552ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:46.701345Z","caller":"traceutil/trace.go:171","msg":"trace[1442637359] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"199.742192ms","start":"2026-04-08T22:58:46.50156Z","end":"2026-04-08T22:58:46.701303Z","steps":["trace[1442637359] 'process raft request' (duration: 199.487915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:46.701488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.57368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:46.701547Z","caller":"traceutil/trace.go:171","msg":"trace[25000915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"106.691244ms","start":"2026-04-08T22:58:46.594845Z","end":"2026-04-08T22:58:46.701537Z","steps":["trace[25000915] 'agreement among raft nodes before linearized reading' (duration: 106.569291ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:46.701361Z","caller":"traceutil/trace.go:171","msg":"trace[27189110] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1342; }","duration":"106.421497ms","start":"2026-04-08T22:58:46.594903Z","end":"2026-04-08T22:58:46.701325Z","steps":["trace[27189110] 'read index received' (duration: 42.669165ms)","trace[27189110] 'applied index is now lower than readState.Index' (duration: 63.751062ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:46.70183Z","caller":"traceutil/trace.go:171","msg":"trace[876424352] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"115.152722ms","start":"2026-04-08T22:58:46.586665Z","end":"2026-04-08T22:58:46.701817Z","steps":["trace[876424352] 'process raft request' (duration: 114.620368ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:46.701849Z","caller":"traceutil/trace.go:171","msg":"trace[1895402932] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"198.92232ms","start":"2026-04-08T22:58:46.502867Z","end":"2026-04-08T22:58:46.701789Z","steps":["trace[1895402932] 'process raft request' (duration: 198.382875ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:46.702046Z","caller":"traceutil/trace.go:171","msg":"trace[2103340571] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"200.42332ms","start":"2026-04-08T22:58:46.501612Z","end":"2026-04-08T22:58:46.702035Z","steps":["trace[2103340571] 'process raft request' (duration: 199.559537ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:46.702074Z","caller":"traceutil/trace.go:171","msg":"trace[942308598] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"200.42093ms","start":"2026-04-08T22:58:46.501631Z","end":"2026-04-08T22:58:46.702052Z","steps":["trace[942308598] 'process raft request' (duration: 199.580238ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:47.189135Z","caller":"traceutil/trace.go:171","msg":"trace[1436169120] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"102.227404ms","start":"2026-04-08T22:58:47.086872Z","end":"2026-04-08T22:58:47.189099Z","steps":["trace[1436169120] 'process raft request' (duration: 101.981288ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:55.134999Z","caller":"traceutil/trace.go:171","msg":"trace[1000144140] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"309.274462ms","start":"2026-04-08T22:58:54.825704Z","end":"2026-04-08T22:58:55.134979Z","steps":["trace[1000144140] 'read index received' (duration: 309.118648ms)","trace[1000144140] 'applied index is now lower than readState.Index' (duration: 155.074µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:55.135084Z","caller":"traceutil/trace.go:171","msg":"trace[2111448359] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"310.579698ms","start":"2026-04-08T22:58:54.824497Z","end":"2026-04-08T22:58:55.135077Z","steps":["trace[2111448359] 'process raft request' (duration: 310.355752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:55.135166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:54.824481Z","time spent":"310.621938ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:55.135501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.744444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:58:55.136206Z","caller":"traceutil/trace.go:171","msg":"trace[232915941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1352; }","duration":"309.909209ms","start":"2026-04-08T22:58:54.825683Z","end":"2026-04-08T22:58:55.135592Z","steps":["trace[232915941] 'agreement among raft nodes before linearized reading' (duration: 309.419686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:55.136349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:54.825674Z","time spent":"310.649279ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T22:58:55.352944Z","caller":"traceutil/trace.go:171","msg":"trace[1069998232] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"217.865258ms","start":"2026-04-08T22:58:55.135049Z","end":"2026-04-08T22:58:55.352914Z","steps":["trace[1069998232] 'read index received' (duration: 212.945327ms)","trace[1069998232] 'applied index is now lower than readState.Index' (duration: 4.918561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:55.353186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.981547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:58:55.353151Z","caller":"traceutil/trace.go:171","msg":"trace[1717686901] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"526.131034ms","start":"2026-04-08T22:58:54.826981Z","end":"2026-04-08T22:58:55.353112Z","steps":["trace[1717686901] 'process raft request' (duration: 520.996306ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:55.353212Z","caller":"traceutil/trace.go:171","msg":"trace[738970175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"527.046498ms","start":"2026-04-08T22:58:54.826157Z","end":"2026-04-08T22:58:55.353203Z","steps":["trace[738970175] 'agreement among raft nodes before linearized reading' (duration: 526.871643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:55.353203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.843686ms","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-04-08T22:58:55.353189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.078571ms","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-04-08T22:58:55.353241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:54.826149Z","time spent":"527.084859ms","remote":"127.0.0.1:58160","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-04-08T22:58:55.353337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:54.826971Z","time spent":"526.261177ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:55.353269Z","caller":"traceutil/trace.go:171","msg":"trace[1507317045] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1353; }","duration":"457.222804ms","start":"2026-04-08T22:58:54.896031Z","end":"2026-04-08T22:58:55.353254Z","steps":["trace[1507317045] 'agreement among raft nodes before linearized reading' (duration: 456.993297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:55.353422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:54.89601Z","time spent":"457.397439ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-08T22:58:55.353266Z","caller":"traceutil/trace.go:171","msg":"trace[1098494105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1353; }","duration":"525.913158ms","start":"2026-04-08T22:58:54.827334Z","end":"2026-04-08T22:58:55.353247Z","steps":["trace[1098494105] 'agreement among raft nodes before linearized reading' (duration: 525.770995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:55.35356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:54.827325Z","time spent":"526.209886ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T22:58:55.397839Z","caller":"traceutil/trace.go:171","msg":"trace[1715851435] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"163.117039ms","start":"2026-04-08T22:58:55.234699Z","end":"2026-04-08T22:58:55.397816Z","steps":["trace[1715851435] 'process raft request' (duration: 163.058988ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:55.397855Z","caller":"traceutil/trace.go:171","msg":"trace[189465545] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"256.120915ms","start":"2026-04-08T22:58:55.14171Z","end":"2026-04-08T22:58:55.397831Z","steps":["trace[189465545] 'process raft request' (duration: 255.893179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:56.570695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.325351ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:56.570921Z","caller":"traceutil/trace.go:171","msg":"trace[2008374220] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1355; }","duration":"583.571357ms","start":"2026-04-08T22:58:55.987324Z","end":"2026-04-08T22:58:56.570896Z","steps":["trace[2008374220] 'range keys from in-memory index tree' (duration: 583.30415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:56.571361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.901956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:58:56.571591Z","caller":"traceutil/trace.go:171","msg":"trace[894917720] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.213462746s","start":"2026-04-08T22:58:55.358104Z","end":"2026-04-08T22:58:56.571566Z","steps":["trace[894917720] 'process raft request' (duration: 439.240161ms)","trace[894917720] 'compare' (duration: 773.718442ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:58:56.571715Z","caller":"traceutil/trace.go:171","msg":"trace[64932276] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"1.213097486s","start":"2026-04-08T22:58:55.358593Z","end":"2026-04-08T22:58:56.571691Z","steps":["trace[64932276] 'process raft request' (duration: 1.21289368s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:56.571734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:55.358084Z","time spent":"1.213562099s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:56.571912Z","caller":"traceutil/trace.go:171","msg":"trace[1451807257] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"1.212938921s","start":"2026-04-08T22:58:55.358848Z","end":"2026-04-08T22:58:56.571787Z","steps":["trace[1451807257] 'process raft request' (duration: 1.212695915s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:56.572194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:55.35884Z","time spent":"1.213313703s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:58:56.57225Z","caller":"traceutil/trace.go:171","msg":"trace[1214631216] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1409; }","duration":"1.175287931s","start":"2026-04-08T22:58:55.39695Z","end":"2026-04-08T22:58:56.572238Z","steps":["trace[1214631216] 'read index received' (duration: 701.57µs)","trace[1214631216] 'applied index is now lower than readState.Index' (duration: 1.174584621s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:58:56.572388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:55.358585Z","time spent":"1.213696932s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:58:56.574139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101787258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:56.574191Z","caller":"traceutil/trace.go:171","msg":"trace[1774682228] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1358; }","duration":"1.10189088s","start":"2026-04-08T22:58:55.472287Z","end":"2026-04-08T22:58:56.574178Z","steps":["trace[1774682228] 'agreement among raft nodes before linearized reading' (duration: 1.101807379s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:56.574239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:55.47226Z","time spent":"1.101970732s","remote":"127.0.0.1:58116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:58:56.574537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177584263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:56.57461Z","caller":"traceutil/trace.go:171","msg":"trace[1031310279] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1358; }","duration":"1.177694355s","start":"2026-04-08T22:58:55.396904Z","end":"2026-04-08T22:58:56.574598Z","steps":["trace[1031310279] 'agreement among raft nodes before linearized reading' (duration: 1.175436974s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:56.574641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:55.396854Z","time spent":"1.177743467s","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:58:56.575082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.66698ms","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:526"} {"level":"warn","ts":"2026-04-08T22:58:56.575114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.143856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:56.575136Z","caller":"traceutil/trace.go:171","msg":"trace[2038059368] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"792.771132ms","start":"2026-04-08T22:58:55.78235Z","end":"2026-04-08T22:58:56.575121Z","steps":["trace[2038059368] 'agreement among raft nodes before linearized reading' (duration: 792.602978ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:56.575149Z","caller":"traceutil/trace.go:171","msg":"trace[885887187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"979.231497ms","start":"2026-04-08T22:58:55.595905Z","end":"2026-04-08T22:58:56.575136Z","steps":["trace[885887187] 'agreement among raft nodes before linearized reading' (duration: 979.184566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:56.575172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:55.782321Z","time spent":"792.843795ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-08T22:58:56.575175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:58:55.595878Z","time spent":"979.291541ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T22:59:28.36812Z","caller":"traceutil/trace.go:171","msg":"trace[1669298333] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"164.63182ms","start":"2026-04-08T22:59:28.203454Z","end":"2026-04-08T22:59:28.368086Z","steps":["trace[1669298333] 'process raft request' (duration: 164.405704ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:28.985308Z","caller":"traceutil/trace.go:171","msg":"trace[310506735] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"111.465012ms","start":"2026-04-08T22:59:28.873804Z","end":"2026-04-08T22:59:28.985269Z","steps":["trace[310506735] 'process raft request' (duration: 111.40343ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:28.985691Z","caller":"traceutil/trace.go:171","msg":"trace[829312524] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"112.107789ms","start":"2026-04-08T22:59:28.873536Z","end":"2026-04-08T22:59:28.985644Z","steps":["trace[829312524] 'process raft request' (duration: 54.0067ms)","trace[829312524] 'compare' (duration: 57.449362ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:35.132645Z","caller":"traceutil/trace.go:171","msg":"trace[1653745888] linearizableReadLoop","detail":"{readStateIndex:1597; appliedIndex:1596; }","duration":"228.295209ms","start":"2026-04-08T22:59:34.904302Z","end":"2026-04-08T22:59:35.132597Z","steps":["trace[1653745888] 'read index received' (duration: 228.044252ms)","trace[1653745888] 'applied index is now lower than readState.Index' (duration: 249.427µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:35.132671Z","caller":"traceutil/trace.go:171","msg":"trace[669050578] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"236.592392ms","start":"2026-04-08T22:59:34.896045Z","end":"2026-04-08T22:59:35.132637Z","steps":["trace[669050578] 'process raft request' (duration: 236.348285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:35.132906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.573136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T22:59:35.13298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.044835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-08T22:59:35.133001Z","caller":"traceutil/trace.go:171","msg":"trace[853137896] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1533; }","duration":"228.709559ms","start":"2026-04-08T22:59:34.904269Z","end":"2026-04-08T22:59:35.132979Z","steps":["trace[853137896] 'agreement among raft nodes before linearized reading' (duration: 228.494573ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:35.133037Z","caller":"traceutil/trace.go:171","msg":"trace[1295503493] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1533; }","duration":"119.159429ms","start":"2026-04-08T22:59:35.013863Z","end":"2026-04-08T22:59:35.133022Z","steps":["trace[1295503493] 'agreement among raft nodes before linearized reading' (duration: 118.970954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:35.133045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.107397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-08T22:59:35.133092Z","caller":"traceutil/trace.go:171","msg":"trace[1836543527] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1533; }","duration":"151.233249ms","start":"2026-04-08T22:59:34.981847Z","end":"2026-04-08T22:59:35.133081Z","steps":["trace[1836543527] 'agreement among raft nodes before linearized reading' (duration: 150.979493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:35.133159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.964061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T22:59:35.133197Z","caller":"traceutil/trace.go:171","msg":"trace[5181600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1533; }","duration":"123.049582ms","start":"2026-04-08T22:59:35.010137Z","end":"2026-04-08T22:59:35.133186Z","steps":["trace[5181600] 'agreement among raft nodes before linearized reading' (duration: 122.936239ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:35.469613Z","caller":"traceutil/trace.go:171","msg":"trace[2033899274] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"331.490878ms","start":"2026-04-08T22:59:35.138096Z","end":"2026-04-08T22:59:35.469586Z","steps":["trace[2033899274] 'process raft request' (duration: 331.340554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:35.469775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:35.138074Z","time spent":"331.616202ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:59:35.471219Z","caller":"traceutil/trace.go:171","msg":"trace[1927918890] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"212.220158ms","start":"2026-04-08T22:59:35.258965Z","end":"2026-04-08T22:59:35.471185Z","steps":["trace[1927918890] 'process raft request' (duration: 211.871737ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:35.471201Z","caller":"traceutil/trace.go:171","msg":"trace[57819220] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"332.577419ms","start":"2026-04-08T22:59:35.138586Z","end":"2026-04-08T22:59:35.471163Z","steps":["trace[57819220] 'process raft request' (duration: 332.131226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:35.471417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:59:35.138523Z","time spent":"332.805034ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:59:53.767454Z","caller":"traceutil/trace.go:171","msg":"trace[2126377684] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"108.565173ms","start":"2026-04-08T22:59:53.658836Z","end":"2026-04-08T22:59:53.767401Z","steps":["trace[2126377684] 'process raft request' (duration: 108.048079ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:53.767614Z","caller":"traceutil/trace.go:171","msg":"trace[1297909204] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"106.481726ms","start":"2026-04-08T22:59:53.661073Z","end":"2026-04-08T22:59:53.767554Z","steps":["trace[1297909204] 'process raft request' (duration: 105.993673ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:53.766659Z","caller":"traceutil/trace.go:171","msg":"trace[829097922] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"152.650692ms","start":"2026-04-08T22:59:53.61388Z","end":"2026-04-08T22:59:53.76653Z","steps":["trace[829097922] 'process raft request' (duration: 126.27292ms)","trace[829097922] 'compare' (duration: 26.148296ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:57.990441Z","caller":"traceutil/trace.go:171","msg":"trace[1805054194] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"132.798527ms","start":"2026-04-08T22:59:57.857611Z","end":"2026-04-08T22:59:57.99041Z","steps":["trace[1805054194] 'process raft request' (duration: 111.896812ms)","trace[1805054194] 'compare' (duration: 20.765411ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:00:13.920979Z","caller":"traceutil/trace.go:171","msg":"trace[1118115924] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"113.213277ms","start":"2026-04-08T23:00:13.807733Z","end":"2026-04-08T23:00:13.920946Z","steps":["trace[1118115924] 'process raft request' (duration: 113.001792ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:16.040851Z","caller":"traceutil/trace.go:171","msg":"trace[1570115815] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"111.381948ms","start":"2026-04-08T23:00:15.929442Z","end":"2026-04-08T23:00:16.040824Z","steps":["trace[1570115815] 'process raft request' (duration: 111.199303ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:16.049413Z","caller":"traceutil/trace.go:171","msg":"trace[1169368467] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"118.638514ms","start":"2026-04-08T23:00:15.930724Z","end":"2026-04-08T23:00:16.049362Z","steps":["trace[1169368467] 'process raft request' (duration: 118.397568ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:16.177979Z","caller":"traceutil/trace.go:171","msg":"trace[91948744] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"123.004442ms","start":"2026-04-08T23:00:16.054935Z","end":"2026-04-08T23:00:16.177939Z","steps":["trace[91948744] 'process raft request' (duration: 113.320271ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:16.178306Z","caller":"traceutil/trace.go:171","msg":"trace[1182231918] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"123.315221ms","start":"2026-04-08T23:00:16.054975Z","end":"2026-04-08T23:00:16.17829Z","steps":["trace[1182231918] 'process raft request' (duration: 122.896049ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:16.2319Z","caller":"traceutil/trace.go:171","msg":"trace[1600012473] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"176.803936ms","start":"2026-04-08T23:00:16.055051Z","end":"2026-04-08T23:00:16.231855Z","steps":["trace[1600012473] 'process raft request' (duration: 176.539409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:00:16.332211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.435603ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-08T23:00:16.33255Z","caller":"traceutil/trace.go:171","msg":"trace[1005540873] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"136.828536ms","start":"2026-04-08T23:00:16.195701Z","end":"2026-04-08T23:00:16.33253Z","steps":["trace[1005540873] 'process raft request' (duration: 136.64217ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:16.332632Z","caller":"traceutil/trace.go:171","msg":"trace[1850629849] linearizableReadLoop","detail":"{readStateIndex:1794; appliedIndex:1791; }","duration":"251.840372ms","start":"2026-04-08T23:00:16.080681Z","end":"2026-04-08T23:00:16.332521Z","steps":["trace[1850629849] 'read index received' (duration: 87.727459ms)","trace[1850629849] 'applied index is now lower than readState.Index' (duration: 164.109053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:00:16.332894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.198112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T23:00:16.3331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.598497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:00:16.334095Z","caller":"traceutil/trace.go:171","msg":"trace[185918742] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1723; }","duration":"253.444475ms","start":"2026-04-08T23:00:16.080605Z","end":"2026-04-08T23:00:16.33405Z","steps":["trace[185918742] 'agreement among raft nodes before linearized reading' (duration: 252.1831ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:16.334201Z","caller":"traceutil/trace.go:171","msg":"trace[571049477] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1723; }","duration":"141.672417ms","start":"2026-04-08T23:00:16.192478Z","end":"2026-04-08T23:00:16.33415Z","steps":["trace[571049477] 'agreement among raft nodes before linearized reading' (duration: 140.563207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:00:16.333098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.387004ms","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-04-08T23:00:16.334995Z","caller":"traceutil/trace.go:171","msg":"trace[531197328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1723; }","duration":"148.345626ms","start":"2026-04-08T23:00:16.186627Z","end":"2026-04-08T23:00:16.334973Z","steps":["trace[531197328] 'agreement among raft nodes before linearized reading' (duration: 146.292621ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:17.110324Z","caller":"traceutil/trace.go:171","msg":"trace[749193166] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"190.138776ms","start":"2026-04-08T23:00:16.920152Z","end":"2026-04-08T23:00:17.110291Z","steps":["trace[749193166] 'process raft request' (duration: 189.91036ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:19.919659Z","caller":"traceutil/trace.go:171","msg":"trace[1901512418] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"171.667086ms","start":"2026-04-08T23:00:19.747925Z","end":"2026-04-08T23:00:19.919592Z","steps":["trace[1901512418] 'process raft request' (duration: 145.251703ms)","trace[1901512418] 'compare' (duration: 26.252649ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:00:24.359512Z","caller":"traceutil/trace.go:171","msg":"trace[2117941294] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"151.203184ms","start":"2026-04-08T23:00:24.208257Z","end":"2026-04-08T23:00:24.359461Z","steps":["trace[2117941294] 'process raft request' (duration: 75.581892ms)","trace[2117941294] 'compare' (duration: 75.437327ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:00:42.497972Z","caller":"traceutil/trace.go:171","msg":"trace[445971438] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"253.693502ms","start":"2026-04-08T23:00:42.244227Z","end":"2026-04-08T23:00:42.497921Z","steps":["trace[445971438] 'process raft request' (duration: 253.427175ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:42.658575Z","caller":"traceutil/trace.go:171","msg":"trace[1410165539] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1918; }","duration":"107.604636ms","start":"2026-04-08T23:00:42.550905Z","end":"2026-04-08T23:00:42.658509Z","steps":["trace[1410165539] 'read index received' (duration: 93.225257ms)","trace[1410165539] 'applied index is now lower than readState.Index' (duration: 14.378159ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:00:42.658734Z","caller":"traceutil/trace.go:171","msg":"trace[1657197133] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"155.567832ms","start":"2026-04-08T23:00:42.503139Z","end":"2026-04-08T23:00:42.658707Z","steps":["trace[1657197133] 'process raft request' (duration: 155.282774ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:42.658744Z","caller":"traceutil/trace.go:171","msg":"trace[139277760] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"155.599282ms","start":"2026-04-08T23:00:42.503087Z","end":"2026-04-08T23:00:42.658687Z","steps":["trace[139277760] 'process raft request' (duration: 155.217362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:00:42.658939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.998568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T23:00:42.658986Z","caller":"traceutil/trace.go:171","msg":"trace[1605515491] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"155.895041ms","start":"2026-04-08T23:00:42.503074Z","end":"2026-04-08T23:00:42.658969Z","steps":["trace[1605515491] 'process raft request' (duration: 140.984669ms)","trace[1605515491] 'compare' (duration: 13.768971ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:00:42.659022Z","caller":"traceutil/trace.go:171","msg":"trace[1519777943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1844; }","duration":"108.146931ms","start":"2026-04-08T23:00:42.550856Z","end":"2026-04-08T23:00:42.659003Z","steps":["trace[1519777943] 'agreement among raft nodes before linearized reading' (duration: 107.900614ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:42.659024Z","caller":"traceutil/trace.go:171","msg":"trace[863161850] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"155.750336ms","start":"2026-04-08T23:00:42.503239Z","end":"2026-04-08T23:00:42.65899Z","steps":["trace[863161850] 'process raft request' (duration: 155.225492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.305324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.328133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T23:01:13.305464Z","caller":"traceutil/trace.go:171","msg":"trace[384962525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1979; }","duration":"455.605451ms","start":"2026-04-08T23:01:12.849829Z","end":"2026-04-08T23:01:13.305434Z","steps":["trace[384962525] 'range keys from in-memory index tree' (duration: 454.87254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.305525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:12.849796Z","time spent":"455.717253ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T23:01:13.305698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.029353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T23:01:13.305708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.811967ms","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-04-08T23:01:13.305756Z","caller":"traceutil/trace.go:171","msg":"trace[511623549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1979; }","duration":"434.116365ms","start":"2026-04-08T23:01:12.871626Z","end":"2026-04-08T23:01:13.305743Z","steps":["trace[511623549] 'range keys from in-memory index tree' (duration: 433.89436ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.305779Z","caller":"traceutil/trace.go:171","msg":"trace[1349014000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1979; }","duration":"451.873149ms","start":"2026-04-08T23:01:12.853878Z","end":"2026-04-08T23:01:13.305751Z","steps":["trace[1349014000] 'range keys from in-memory index tree' (duration: 451.650532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.305743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.135273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T23:01:13.305813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:12.871615Z","time spent":"434.189238ms","remote":"127.0.0.1:58160","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-04-08T23:01:13.305816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:12.853812Z","time spent":"451.995913ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T23:01:13.30585Z","caller":"traceutil/trace.go:171","msg":"trace[80970506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1979; }","duration":"428.277146ms","start":"2026-04-08T23:01:12.877543Z","end":"2026-04-08T23:01:13.30582Z","steps":["trace[80970506] 'range keys from in-memory index tree' (duration: 427.916278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.305899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:12.877531Z","time spent":"428.35809ms","remote":"127.0.0.1:58160","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-04-08T23:01:13.306034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.82473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-08T23:01:13.306133Z","caller":"traceutil/trace.go:171","msg":"trace[653651730] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1979; }","duration":"429.988974ms","start":"2026-04-08T23:01:12.876116Z","end":"2026-04-08T23:01:13.306105Z","steps":["trace[653651730] 'range keys from in-memory index tree' (duration: 429.579723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:13.306175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:12.876101Z","time spent":"430.065615ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-08T23:01:13.500715Z","caller":"traceutil/trace.go:171","msg":"trace[618821714] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"188.654065ms","start":"2026-04-08T23:01:13.312017Z","end":"2026-04-08T23:01:13.500671Z","steps":["trace[618821714] 'process raft request' (duration: 188.380137ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.500732Z","caller":"traceutil/trace.go:171","msg":"trace[1924917301] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"188.605953ms","start":"2026-04-08T23:01:13.312093Z","end":"2026-04-08T23:01:13.500699Z","steps":["trace[1924917301] 'process raft request' (duration: 188.520631ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.501133Z","caller":"traceutil/trace.go:171","msg":"trace[843172582] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"187.898975ms","start":"2026-04-08T23:01:13.313218Z","end":"2026-04-08T23:01:13.501117Z","steps":["trace[843172582] 'process raft request' (duration: 187.697269ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:13.501155Z","caller":"traceutil/trace.go:171","msg":"trace[1303486339] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"188.593674ms","start":"2026-04-08T23:01:13.312521Z","end":"2026-04-08T23:01:13.501115Z","steps":["trace[1303486339] 'process raft request' (duration: 188.140001ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:32.283228Z","caller":"traceutil/trace.go:171","msg":"trace[1152161623] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"151.269876ms","start":"2026-04-08T23:01:32.131906Z","end":"2026-04-08T23:01:32.283176Z","steps":["trace[1152161623] 'process raft request' (duration: 151.01914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:52.51724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.217765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T23:01:52.517348Z","caller":"traceutil/trace.go:171","msg":"trace[1663728137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2160; }","duration":"108.381671ms","start":"2026-04-08T23:01:52.408943Z","end":"2026-04-08T23:01:52.517325Z","steps":["trace[1663728137] 'range keys from in-memory index tree' (duration: 107.99551ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:52.715099Z","caller":"traceutil/trace.go:171","msg":"trace[851017785] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"191.908333ms","start":"2026-04-08T23:01:52.523162Z","end":"2026-04-08T23:01:52.71507Z","steps":["trace[851017785] 'process raft request' (duration: 191.729558ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:52.716695Z","caller":"traceutil/trace.go:171","msg":"trace[1883192306] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2252; }","duration":"121.857088ms","start":"2026-04-08T23:01:52.594819Z","end":"2026-04-08T23:01:52.716676Z","steps":["trace[1883192306] 'read index received' (duration: 120.130261ms)","trace[1883192306] 'applied index is now lower than readState.Index' (duration: 1.725707ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:01:52.716739Z","caller":"traceutil/trace.go:171","msg":"trace[890041213] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"193.27998ms","start":"2026-04-08T23:01:52.523441Z","end":"2026-04-08T23:01:52.716721Z","steps":["trace[890041213] 'process raft request' (duration: 193.144417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:52.71693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.096794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:01:52.716996Z","caller":"traceutil/trace.go:171","msg":"trace[443140190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2162; }","duration":"122.215237ms","start":"2026-04-08T23:01:52.594765Z","end":"2026-04-08T23:01:52.71698Z","steps":["trace[443140190] 'agreement among raft nodes before linearized reading' (duration: 121.97656ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:57.845474Z","caller":"traceutil/trace.go:171","msg":"trace[1834563947] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"121.247775ms","start":"2026-04-08T23:01:57.724186Z","end":"2026-04-08T23:01:57.845434Z","steps":["trace[1834563947] 'process raft request' (duration: 120.993077ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:08.156013Z","caller":"traceutil/trace.go:171","msg":"trace[230039121] linearizableReadLoop","detail":"{readStateIndex:2342; appliedIndex:2341; }","duration":"197.353028ms","start":"2026-04-08T23:02:07.958624Z","end":"2026-04-08T23:02:08.155977Z","steps":["trace[230039121] 'read index received' (duration: 197.175404ms)","trace[230039121] 'applied index is now lower than readState.Index' (duration: 175.904µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:08.156103Z","caller":"traceutil/trace.go:171","msg":"trace[1688901642] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"290.770094ms","start":"2026-04-08T23:02:07.865295Z","end":"2026-04-08T23:02:08.156065Z","steps":["trace[1688901642] 'process raft request' (duration: 290.493657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.156398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.806332ms","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-04-08T23:02:08.156494Z","caller":"traceutil/trace.go:171","msg":"trace[327600030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2246; }","duration":"197.974506ms","start":"2026-04-08T23:02:07.958496Z","end":"2026-04-08T23:02:08.156471Z","steps":["trace[327600030] 'agreement among raft nodes before linearized reading' (duration: 197.658017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.67665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198869984181950,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T23:02:08.841096Z","caller":"traceutil/trace.go:171","msg":"trace[940717068] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"679.705368ms","start":"2026-04-08T23:02:08.161358Z","end":"2026-04-08T23:02:08.841064Z","steps":["trace[940717068] 'process raft request' (duration: 672.579624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.84142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:08.16133Z","time spent":"679.867922ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:02:08.920852Z","caller":"traceutil/trace.go:171","msg":"trace[721458482] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"759.384539ms","start":"2026-04-08T23:02:08.161432Z","end":"2026-04-08T23:02:08.920816Z","steps":["trace[721458482] 'process raft request' (duration: 759.130312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.92105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:08.161406Z","time spent":"759.543524ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:02:08.921151Z","caller":"traceutil/trace.go:171","msg":"trace[1533343318] linearizableReadLoop","detail":"{readStateIndex:2346; appliedIndex:2342; }","duration":"744.77585ms","start":"2026-04-08T23:02:08.176358Z","end":"2026-04-08T23:02:08.921134Z","steps":["trace[1533343318] 'read index received' (duration: 657.594955ms)","trace[1533343318] 'applied index is now lower than readState.Index' (duration: 87.179505ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:08.921294Z","caller":"traceutil/trace.go:171","msg":"trace[837965505] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"759.252576ms","start":"2026-04-08T23:02:08.161977Z","end":"2026-04-08T23:02:08.92123Z","steps":["trace[837965505] 'process raft request' (duration: 758.786384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.921365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.006996ms","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-04-08T23:02:08.921335Z","caller":"traceutil/trace.go:171","msg":"trace[870404080] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"677.625071ms","start":"2026-04-08T23:02:08.243662Z","end":"2026-04-08T23:02:08.921287Z","steps":["trace[870404080] 'process raft request' (duration: 677.387334ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:08.921415Z","caller":"traceutil/trace.go:171","msg":"trace[1554399276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2250; }","duration":"745.1057ms","start":"2026-04-08T23:02:08.176294Z","end":"2026-04-08T23:02:08.9214Z","steps":["trace[1554399276] 'agreement among raft nodes before linearized reading' (duration: 744.927885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.921456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:08.176263Z","time spent":"745.185912ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T23:02:08.921456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.575419ms","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:508"} {"level":"info","ts":"2026-04-08T23:02:08.921499Z","caller":"traceutil/trace.go:171","msg":"trace[1016452683] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2250; }","duration":"152.666801ms","start":"2026-04-08T23:02:08.768819Z","end":"2026-04-08T23:02:08.921486Z","steps":["trace[1016452683] 'agreement among raft nodes before linearized reading' (duration: 152.574319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.921489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.564145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:02:08.921578Z","caller":"traceutil/trace.go:171","msg":"trace[1833200351] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2250; }","duration":"704.7412ms","start":"2026-04-08T23:02:08.216812Z","end":"2026-04-08T23:02:08.921553Z","steps":["trace[1833200351] 'agreement among raft nodes before linearized reading' (duration: 704.585546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.921653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:08.216782Z","time spent":"704.858373ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-08T23:02:08.921562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:08.161966Z","time spent":"759.453242ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:02:08.921937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:08.243647Z","time spent":"677.792956ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:02:08.921369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.820682ms","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-04-08T23:02:08.922121Z","caller":"traceutil/trace.go:171","msg":"trace[1749702235] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2250; }","duration":"745.583333ms","start":"2026-04-08T23:02:08.176511Z","end":"2026-04-08T23:02:08.922094Z","steps":["trace[1749702235] 'agreement among raft nodes before linearized reading' (duration: 744.720229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:08.922185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:08.176494Z","time spent":"745.673246ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-08T23:02:13.056484Z","caller":"traceutil/trace.go:171","msg":"trace[1391821261] linearizableReadLoop","detail":"{readStateIndex:2368; appliedIndex:2367; }","duration":"117.414111ms","start":"2026-04-08T23:02:12.939016Z","end":"2026-04-08T23:02:13.05643Z","steps":["trace[1391821261] 'read index received' (duration: 117.093373ms)","trace[1391821261] 'applied index is now lower than readState.Index' (duration: 318.467µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:13.056515Z","caller":"traceutil/trace.go:171","msg":"trace[517106878] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"194.582684ms","start":"2026-04-08T23:02:12.861884Z","end":"2026-04-08T23:02:13.056466Z","steps":["trace[517106878] 'process raft request' (duration: 194.266785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:13.056942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.884054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T23:02:13.057011Z","caller":"traceutil/trace.go:171","msg":"trace[1806970925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2271; }","duration":"118.034227ms","start":"2026-04-08T23:02:12.938959Z","end":"2026-04-08T23:02:13.056994Z","steps":["trace[1806970925] 'agreement among raft nodes before linearized reading' (duration: 117.722749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:13.057105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.29781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T23:02:13.057329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.083545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-08T23:02:13.057381Z","caller":"traceutil/trace.go:171","msg":"trace[2136385003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2271; }","duration":"112.62843ms","start":"2026-04-08T23:02:12.944728Z","end":"2026-04-08T23:02:13.057356Z","steps":["trace[2136385003] 'agreement among raft nodes before linearized reading' (duration: 112.163178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:13.057423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.584863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T23:02:13.057433Z","caller":"traceutil/trace.go:171","msg":"trace[1523046341] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2271; }","duration":"112.214348ms","start":"2026-04-08T23:02:12.945194Z","end":"2026-04-08T23:02:13.057408Z","steps":["trace[1523046341] 'agreement among raft nodes before linearized reading' (duration: 111.97264ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:13.057468Z","caller":"traceutil/trace.go:171","msg":"trace[278545485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2271; }","duration":"114.672956ms","start":"2026-04-08T23:02:12.942784Z","end":"2026-04-08T23:02:13.057457Z","steps":["trace[278545485] 'agreement among raft nodes before linearized reading' (duration: 114.48337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:13.057758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.595835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T23:02:13.057926Z","caller":"traceutil/trace.go:171","msg":"trace[1122944498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2271; }","duration":"117.835582ms","start":"2026-04-08T23:02:12.940075Z","end":"2026-04-08T23:02:13.057911Z","steps":["trace[1122944498] 'agreement among raft nodes before linearized reading' (duration: 116.740552ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:13.298103Z","caller":"traceutil/trace.go:171","msg":"trace[40960676] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"235.880969ms","start":"2026-04-08T23:02:13.062198Z","end":"2026-04-08T23:02:13.298079Z","steps":["trace[40960676] 'process raft request' (duration: 229.022683ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:13.300575Z","caller":"traceutil/trace.go:171","msg":"trace[1016380831] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"237.509953ms","start":"2026-04-08T23:02:13.063021Z","end":"2026-04-08T23:02:13.300531Z","steps":["trace[1016380831] 'process raft request' (duration: 237.133853ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:13.30071Z","caller":"traceutil/trace.go:171","msg":"trace[1428879343] linearizableReadLoop","detail":"{readStateIndex:2374; appliedIndex:2368; }","duration":"137.938429ms","start":"2026-04-08T23:02:13.162747Z","end":"2026-04-08T23:02:13.300685Z","steps":["trace[1428879343] 'read index received' (duration: 128.43953ms)","trace[1428879343] 'applied index is now lower than readState.Index' (duration: 9.497529ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:13.300819Z","caller":"traceutil/trace.go:171","msg":"trace[840920573] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"236.778724ms","start":"2026-04-08T23:02:13.064027Z","end":"2026-04-08T23:02:13.300806Z","steps":["trace[840920573] 'process raft request' (duration: 236.515616ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:13.300873Z","caller":"traceutil/trace.go:171","msg":"trace[1089921489] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"201.714318ms","start":"2026-04-08T23:02:13.099142Z","end":"2026-04-08T23:02:13.300857Z","steps":["trace[1089921489] 'process raft request' (duration: 201.468541ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:13.301004Z","caller":"traceutil/trace.go:171","msg":"trace[1438671343] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"237.888544ms","start":"2026-04-08T23:02:13.063071Z","end":"2026-04-08T23:02:13.300959Z","steps":["trace[1438671343] 'process raft request' (duration: 237.374259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:13.30105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.287558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-08T23:02:13.301153Z","caller":"traceutil/trace.go:171","msg":"trace[413088359] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2277; }","duration":"138.454223ms","start":"2026-04-08T23:02:13.162675Z","end":"2026-04-08T23:02:13.301129Z","steps":["trace[413088359] 'agreement among raft nodes before linearized reading' (duration: 138.173135ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:13.301415Z","caller":"traceutil/trace.go:171","msg":"trace[306817857] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"237.437801ms","start":"2026-04-08T23:02:13.063959Z","end":"2026-04-08T23:02:13.301397Z","steps":["trace[306817857] 'process raft request' (duration: 236.535547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:21.393459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198869984182117,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T23:02:21.397693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:20.862156Z","time spent":"535.532494ms","remote":"127.0.0.1:57892","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-08T23:02:21.414896Z","caller":"traceutil/trace.go:171","msg":"trace[1134615039] linearizableReadLoop","detail":"{readStateIndex:2412; appliedIndex:2410; }","duration":"521.513373ms","start":"2026-04-08T23:02:20.893359Z","end":"2026-04-08T23:02:21.414872Z","steps":["trace[1134615039] 'read index received' (duration: 504.138289ms)","trace[1134615039] 'applied index is now lower than readState.Index' (duration: 17.374244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:02:21.415219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.80768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:02:21.415268Z","caller":"traceutil/trace.go:171","msg":"trace[174083523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2313; }","duration":"521.954174ms","start":"2026-04-08T23:02:20.893299Z","end":"2026-04-08T23:02:21.415253Z","steps":["trace[174083523] 'agreement among raft nodes before linearized reading' (duration: 521.832861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:21.415302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:20.893265Z","time spent":"522.030828ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T23:02:21.424198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.790477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T23:02:21.424281Z","caller":"traceutil/trace.go:171","msg":"trace[734015123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2314; }","duration":"329.942502ms","start":"2026-04-08T23:02:21.09432Z","end":"2026-04-08T23:02:21.424262Z","steps":["trace[734015123] 'agreement among raft nodes before linearized reading' (duration: 329.685965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:21.424334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:02:21.094297Z","time spent":"330.029504ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T23:02:43.816435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.303228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:02:43.816604Z","caller":"traceutil/trace.go:171","msg":"trace[1346410934] linearizableReadLoop","detail":"{readStateIndex:2527; appliedIndex:2526; }","duration":"235.544929ms","start":"2026-04-08T23:02:43.581035Z","end":"2026-04-08T23:02:43.816579Z","steps":["trace[1346410934] 'read index received' (duration: 48.82803ms)","trace[1346410934] 'applied index is now lower than readState.Index' (duration: 186.715369ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:43.816763Z","caller":"traceutil/trace.go:171","msg":"trace[2134184849] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"241.714507ms","start":"2026-04-08T23:02:43.574995Z","end":"2026-04-08T23:02:43.816709Z","steps":["trace[2134184849] 'process raft request' (duration: 54.936837ms)","trace[2134184849] 'compare' (duration: 186.141393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:02:43.816986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.354355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/kube-public/\" range_end:\"/registry/networkpolicies/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T23:02:43.817098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.801704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T23:02:43.817112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.679571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-08T23:02:43.817149Z","caller":"traceutil/trace.go:171","msg":"trace[1293814166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2424; }","duration":"225.861476ms","start":"2026-04-08T23:02:43.59127Z","end":"2026-04-08T23:02:43.817131Z","steps":["trace[1293814166] 'agreement among raft nodes before linearized reading' (duration: 225.784744ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:43.817158Z","caller":"traceutil/trace.go:171","msg":"trace[211673891] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2424; }","duration":"225.736172ms","start":"2026-04-08T23:02:43.591408Z","end":"2026-04-08T23:02:43.817144Z","steps":["trace[211673891] 'agreement among raft nodes before linearized reading' (duration: 225.534416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:43.817157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.740459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/envoy-gateway-system/\" range_end:\"/registry/events/envoy-gateway-system0\" limit:500 ","response":"range_response_count:15 size:12285"} {"level":"info","ts":"2026-04-08T23:02:43.817253Z","caller":"traceutil/trace.go:171","msg":"trace[1738019685] range","detail":"{range_begin:/registry/events/envoy-gateway-system/; range_end:/registry/events/envoy-gateway-system0; response_count:15; response_revision:2424; }","duration":"197.913255ms","start":"2026-04-08T23:02:43.619316Z","end":"2026-04-08T23:02:43.81723Z","steps":["trace[1738019685] 'agreement among raft nodes before linearized reading' (duration: 197.494663ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:43.817091Z","caller":"traceutil/trace.go:171","msg":"trace[1983231354] range","detail":"{range_begin:/registry/networkpolicies/kube-public/; range_end:/registry/networkpolicies/kube-public0; response_count:0; response_revision:2424; }","duration":"151.51459ms","start":"2026-04-08T23:02:43.665552Z","end":"2026-04-08T23:02:43.817067Z","steps":["trace[1983231354] 'agreement among raft nodes before linearized reading' (duration: 151.284054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:43.817309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.246018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-08T23:02:43.816993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.477929ms","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-04-08T23:02:43.817421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.850416ms","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-04-08T23:02:43.817478Z","caller":"traceutil/trace.go:171","msg":"trace[730178218] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2424; }","duration":"232.030124ms","start":"2026-04-08T23:02:43.585435Z","end":"2026-04-08T23:02:43.817465Z","steps":["trace[730178218] 'agreement among raft nodes before linearized reading' (duration: 231.352766ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:43.817483Z","caller":"traceutil/trace.go:171","msg":"trace[582049694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2424; }","duration":"225.950288ms","start":"2026-04-08T23:02:43.591519Z","end":"2026-04-08T23:02:43.81747Z","steps":["trace[582049694] 'agreement among raft nodes before linearized reading' (duration: 225.725152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:43.817547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.652089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:4637"} {"level":"info","ts":"2026-04-08T23:02:43.817581Z","caller":"traceutil/trace.go:171","msg":"trace[1104266188] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2424; }","duration":"178.731692ms","start":"2026-04-08T23:02:43.638839Z","end":"2026-04-08T23:02:43.817571Z","steps":["trace[1104266188] 'agreement among raft nodes before linearized reading' (duration: 178.626369ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:43.81742Z","caller":"traceutil/trace.go:171","msg":"trace[220079139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2424; }","duration":"236.404023ms","start":"2026-04-08T23:02:43.580991Z","end":"2026-04-08T23:02:43.817395Z","steps":["trace[220079139] 'agreement among raft nodes before linearized reading' (duration: 235.744125ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:02:45.477245Z","caller":"traceutil/trace.go:171","msg":"trace[1631062907] linearizableReadLoop","detail":"{readStateIndex:2534; appliedIndex:2533; }","duration":"112.891727ms","start":"2026-04-08T23:02:45.364325Z","end":"2026-04-08T23:02:45.477217Z","steps":["trace[1631062907] 'read index received' (duration: 112.832765ms)","trace[1631062907] 'applied index is now lower than readState.Index' (duration: 57.632µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:02:45.477339Z","caller":"traceutil/trace.go:171","msg":"trace[641831453] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"124.056061ms","start":"2026-04-08T23:02:45.353252Z","end":"2026-04-08T23:02:45.477308Z","steps":["trace[641831453] 'process raft request' (duration: 123.781963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:45.477451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.103744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-public/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-08T23:02:45.477495Z","caller":"traceutil/trace.go:171","msg":"trace[236108898] range","detail":"{range_begin:/registry/serviceaccounts/kube-public/default; range_end:; response_count:1; response_revision:2431; }","duration":"113.192245ms","start":"2026-04-08T23:02:45.364292Z","end":"2026-04-08T23:02:45.477484Z","steps":["trace[236108898] 'agreement among raft nodes before linearized reading' (duration: 113.082712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:02:45.477721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.364733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-config\" ","response":"range_response_count:1 size:899"} {"level":"info","ts":"2026-04-08T23:02:45.477811Z","caller":"traceutil/trace.go:171","msg":"trace[1827300530] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-config; range_end:; response_count:1; response_revision:2431; }","duration":"101.482346ms","start":"2026-04-08T23:02:45.376307Z","end":"2026-04-08T23:02:45.477789Z","steps":["trace[1827300530] 'agreement among raft nodes before linearized reading' (duration: 101.211498ms)"],"step_count":1}