{"level":"warn","ts":"2026-04-07T17:50:12.976462Z","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-07T17:50:12.976574Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-07T17:50:12.976665Z","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-07T17:50:12.976677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-07T17:50:12.976705Z","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-07T17:50:12.977492Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-04-07T17:50:12.977584Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-07T17:50:12.982618Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.75812ms"} {"level":"info","ts":"2026-04-07T17:50:12.988473Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-04-07T17:50:12.98854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T17:50:12.988568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-04-07T17:50:12.988582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T17:50:12.988599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-04-07T17:50:12.988734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-04-07T17:50:12.996629Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T17:50:13.003447Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T17:50:13.007387Z","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-07T17:50:13.009327Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T17:50:13.009717Z","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-07T17:50:13.009849Z","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-07T17:50:13.009717Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T17:50:13.009872Z","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-07T17:50:13.011527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-04-07T17:50:13.011843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-07T17:50:13.013953Z","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-07T17:50:13.014163Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T17:50:13.014204Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T17:50:13.01486Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-07T17:50:13.014888Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-07T17:50:13.889328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T17:50:13.889407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T17:50:13.889449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-04-07T17:50:13.88947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-04-07T17:50:13.889488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-07T17:50:13.889511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-04-07T17:50:13.889531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-07T17:50:13.890346Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T17:50:13.890436Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:50:13.890523Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:50:13.89085Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T17:50:13.890898Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T17:50:13.890345Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:50:13.89352Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:50:13.893672Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:50:13.893715Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:50:13.894624Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T17:50:13.894829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-04-07T17:50:18.455086Z","caller":"traceutil/trace.go:171","msg":"trace[517933269] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"156.366374ms","start":"2026-04-07T17:50:18.298698Z","end":"2026-04-07T17:50:18.455064Z","steps":["trace[517933269] 'process raft request' (duration: 156.20924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:22.74864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.430172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-04-07T17:50:22.748827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.867074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-04-07T17:50:22.748878Z","caller":"traceutil/trace.go:171","msg":"trace[607191004] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:345; }","duration":"100.708059ms","start":"2026-04-07T17:50:22.648151Z","end":"2026-04-07T17:50:22.748859Z","steps":["trace[607191004] 'range keys from in-memory index tree' (duration: 100.168847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:22.748824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.378568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-07T17:50:22.748969Z","caller":"traceutil/trace.go:171","msg":"trace[1074674826] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:345; }","duration":"150.606973ms","start":"2026-04-07T17:50:22.598334Z","end":"2026-04-07T17:50:22.748941Z","steps":["trace[1074674826] 'range keys from in-memory index tree' (duration: 150.164202ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:22.748899Z","caller":"traceutil/trace.go:171","msg":"trace[2060360190] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:345; }","duration":"111.980597ms","start":"2026-04-07T17:50:22.636902Z","end":"2026-04-07T17:50:22.748882Z","steps":["trace[2060360190] 'range keys from in-memory index tree' (duration: 111.582658ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:22.901326Z","caller":"traceutil/trace.go:171","msg":"trace[17539813] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"144.122295ms","start":"2026-04-07T17:50:22.757159Z","end":"2026-04-07T17:50:22.901281Z","steps":["trace[17539813] 'process raft request' (duration: 104.457685ms)","trace[17539813] 'compare' (duration: 39.427204ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:50:22.901399Z","caller":"traceutil/trace.go:171","msg":"trace[2129926407] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:357; }","duration":"103.299458ms","start":"2026-04-07T17:50:22.798071Z","end":"2026-04-07T17:50:22.90137Z","steps":["trace[2129926407] 'read index received' (duration: 20.449438ms)","trace[2129926407] 'applied index is now lower than readState.Index' (duration: 82.84893ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:50:22.901503Z","caller":"traceutil/trace.go:171","msg":"trace[1412796802] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"144.292409ms","start":"2026-04-07T17:50:22.757198Z","end":"2026-04-07T17:50:22.90149Z","steps":["trace[1412796802] 'process raft request' (duration: 144.054723ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:22.901748Z","caller":"traceutil/trace.go:171","msg":"trace[821769504] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"144.062683ms","start":"2026-04-07T17:50:22.757673Z","end":"2026-04-07T17:50:22.901736Z","steps":["trace[821769504] 'process raft request' (duration: 143.638164ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:22.901788Z","caller":"traceutil/trace.go:171","msg":"trace[1110181523] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"144.609185ms","start":"2026-04-07T17:50:22.757168Z","end":"2026-04-07T17:50:22.901777Z","steps":["trace[1110181523] 'process raft request' (duration: 144.008652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:22.901982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.899952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-07T17:50:22.902031Z","caller":"traceutil/trace.go:171","msg":"trace[1579309657] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:350; }","duration":"103.998085ms","start":"2026-04-07T17:50:22.798019Z","end":"2026-04-07T17:50:22.902017Z","steps":["trace[1579309657] 'agreement among raft nodes before linearized reading' (duration: 103.904703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:29.674873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878253341933727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:50:30.176084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878253341933727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:50:30.208765Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.30863679s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T17:50:30.209263Z","caller":"traceutil/trace.go:171","msg":"trace[1940490498] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"1.112176096s","start":"2026-04-07T17:50:29.097061Z","end":"2026-04-07T17:50:30.209237Z","steps":["trace[1940490498] 'read index received' (duration: 1.111983142s)","trace[1940490498] 'applied index is now lower than readState.Index' (duration: 191.574µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:50:30.209368Z","caller":"traceutil/trace.go:171","msg":"trace[384647539] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.565328456s","start":"2026-04-07T17:50:28.643991Z","end":"2026-04-07T17:50:30.20932Z","steps":["trace[384647539] 'process raft request' (duration: 1.565075581s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:30.209522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112447283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-04-07T17:50:30.209568Z","caller":"traceutil/trace.go:171","msg":"trace[676893003] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:415; }","duration":"1.112545235s","start":"2026-04-07T17:50:29.097003Z","end":"2026-04-07T17:50:30.209548Z","steps":["trace[676893003] 'agreement among raft nodes before linearized reading' (duration: 1.112442293s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:30.210043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033860222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-07T17:50:30.210118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:28.643954Z","time spent":"1.56547057s","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:50:30.210157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.539248ms","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-07T17:50:30.210282Z","caller":"traceutil/trace.go:171","msg":"trace[1342579360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"405.744822ms","start":"2026-04-07T17:50:29.804506Z","end":"2026-04-07T17:50:30.210251Z","steps":["trace[1342579360] 'agreement among raft nodes before linearized reading' (duration: 405.455626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:30.209612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:29.096979Z","time spent":"1.112624157s","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T17:50:30.210349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:29.804476Z","time spent":"405.861544ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T17:50:30.210402Z","caller":"traceutil/trace.go:171","msg":"trace[1157848064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"1.034086998s","start":"2026-04-07T17:50:29.176058Z","end":"2026-04-07T17:50:30.210145Z","steps":["trace[1157848064] 'agreement among raft nodes before linearized reading' (duration: 1.033714268s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:30.210521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:29.176009Z","time spent":"1.034494267s","remote":"127.0.0.1:45616","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-07T17:50:30.210542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.041743ms","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-07T17:50:30.210607Z","caller":"traceutil/trace.go:171","msg":"trace[890609302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"924.132336ms","start":"2026-04-07T17:50:29.286454Z","end":"2026-04-07T17:50:30.210586Z","steps":["trace[890609302] 'agreement among raft nodes before linearized reading' (duration: 923.984222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:30.210661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:29.28643Z","time spent":"924.218328ms","remote":"127.0.0.1:45616","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-07T17:50:30.210568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.829865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:50:30.210603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.083291ms","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-07T17:50:30.210775Z","caller":"traceutil/trace.go:171","msg":"trace[592696707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"499.083421ms","start":"2026-04-07T17:50:29.711659Z","end":"2026-04-07T17:50:30.210742Z","steps":["trace[592696707] 'agreement among raft nodes before linearized reading' (duration: 498.783004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:30.210841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:29.711639Z","time spent":"499.191003ms","remote":"127.0.0.1:45344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:50:30.210851Z","caller":"traceutil/trace.go:171","msg":"trace[940667704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"345.421309ms","start":"2026-04-07T17:50:29.865397Z","end":"2026-04-07T17:50:30.210818Z","steps":["trace[940667704] 'agreement among raft nodes before linearized reading' (duration: 345.00792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:30.210923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:29.865358Z","time spent":"345.547452ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T17:50:47.892975Z","caller":"traceutil/trace.go:171","msg":"trace[205358849] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"174.754147ms","start":"2026-04-07T17:50:47.718191Z","end":"2026-04-07T17:50:47.892945Z","steps":["trace[205358849] 'read index received' (duration: 172.529246ms)","trace[205358849] 'applied index is now lower than readState.Index' (duration: 2.223561ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:50:47.893071Z","caller":"traceutil/trace.go:171","msg":"trace[436745565] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"240.658647ms","start":"2026-04-07T17:50:47.652369Z","end":"2026-04-07T17:50:47.893028Z","steps":["trace[436745565] 'process raft request' (duration: 238.61764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:47.893247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.996732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:50:47.893339Z","caller":"traceutil/trace.go:171","msg":"trace[1031851538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"175.174236ms","start":"2026-04-07T17:50:47.718147Z","end":"2026-04-07T17:50:47.893321Z","steps":["trace[1031851538] 'agreement among raft nodes before linearized reading' (duration: 174.90765ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:47.903196Z","caller":"traceutil/trace.go:171","msg":"trace[1458206196] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"106.856489ms","start":"2026-04-07T17:50:47.796325Z","end":"2026-04-07T17:50:47.903181Z","steps":["trace[1458206196] 'process raft request' (duration: 106.585453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:47.905081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.298476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-n85x7\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-04-07T17:50:47.905175Z","caller":"traceutil/trace.go:171","msg":"trace[800618937] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-n85x7; range_end:; response_count:1; response_revision:605; }","duration":"174.445299ms","start":"2026-04-07T17:50:47.730705Z","end":"2026-04-07T17:50:47.90515Z","steps":["trace[800618937] 'agreement among raft nodes before linearized reading' (duration: 174.154153ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:48.842787Z","caller":"traceutil/trace.go:171","msg":"trace[268843409] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"699.65481ms","start":"2026-04-07T17:50:48.143111Z","end":"2026-04-07T17:50:48.842765Z","steps":["trace[268843409] 'process raft request' (duration: 699.238361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:48.842934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:48.143046Z","time spent":"699.808494ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:50:48.849591Z","caller":"traceutil/trace.go:171","msg":"trace[1315246055] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"330.508747ms","start":"2026-04-07T17:50:48.519069Z","end":"2026-04-07T17:50:48.849578Z","steps":["trace[1315246055] 'read index received' (duration: 323.22385ms)","trace[1315246055] 'applied index is now lower than readState.Index' (duration: 7.284097ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:50:48.849629Z","caller":"traceutil/trace.go:171","msg":"trace[518878251] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"687.821448ms","start":"2026-04-07T17:50:48.161774Z","end":"2026-04-07T17:50:48.849595Z","steps":["trace[518878251] 'process raft request' (duration: 687.640464ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:48.849695Z","caller":"traceutil/trace.go:171","msg":"trace[86555940] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"450.389085ms","start":"2026-04-07T17:50:48.3993Z","end":"2026-04-07T17:50:48.849689Z","steps":["trace[86555940] 'process raft request' (duration: 450.257102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:48.849744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:48.399265Z","time spent":"450.451736ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T17:50:48.849779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:48.16174Z","time spent":"687.959322ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T17:50:48.849907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.847285ms","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-07T17:50:48.849937Z","caller":"traceutil/trace.go:171","msg":"trace[1668247260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"330.941547ms","start":"2026-04-07T17:50:48.518988Z","end":"2026-04-07T17:50:48.84993Z","steps":["trace[1668247260] 'agreement among raft nodes before linearized reading' (duration: 330.855185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:48.849957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:48.518966Z","time spent":"330.986598ms","remote":"127.0.0.1:45616","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-07T17:50:48.849966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.367238ms","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-07T17:50:48.850001Z","caller":"traceutil/trace.go:171","msg":"trace[1172720204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"328.425199ms","start":"2026-04-07T17:50:48.521565Z","end":"2026-04-07T17:50:48.849991Z","steps":["trace[1172720204] 'agreement among raft nodes before linearized reading' (duration: 328.349727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:48.850022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:48.521556Z","time spent":"328.46068ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T17:50:48.850032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.425856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:50:48.850103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.067003ms","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-07T17:50:48.850118Z","caller":"traceutil/trace.go:171","msg":"trace[823984815] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:618; }","duration":"255.556269ms","start":"2026-04-07T17:50:48.594545Z","end":"2026-04-07T17:50:48.850101Z","steps":["trace[823984815] 'agreement among raft nodes before linearized reading' (duration: 255.388205ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:48.850127Z","caller":"traceutil/trace.go:171","msg":"trace[2044081891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"315.135534ms","start":"2026-04-07T17:50:48.534982Z","end":"2026-04-07T17:50:48.850118Z","steps":["trace[2044081891] 'agreement among raft nodes before linearized reading' (duration: 315.095053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:48.850147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:48.534954Z","time spent":"315.189386ms","remote":"127.0.0.1:45616","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-07T17:50:48.850214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.503681ms","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-07T17:50:48.850229Z","caller":"traceutil/trace.go:171","msg":"trace[1880151720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"328.543232ms","start":"2026-04-07T17:50:48.521681Z","end":"2026-04-07T17:50:48.850224Z","steps":["trace[1880151720] 'agreement among raft nodes before linearized reading' (duration: 328.513942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:48.850247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:48.521668Z","time spent":"328.573873ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T17:50:48.849669Z","caller":"traceutil/trace.go:171","msg":"trace[321306496] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"645.851636ms","start":"2026-04-07T17:50:48.203811Z","end":"2026-04-07T17:50:48.849662Z","steps":["trace[321306496] 'process raft request' (duration: 645.719303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:48.850298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:48.20378Z","time spent":"646.499732ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T17:50:50.24912Z","caller":"traceutil/trace.go:171","msg":"trace[343031374] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"499.142853ms","start":"2026-04-07T17:50:49.749958Z","end":"2026-04-07T17:50:50.249101Z","steps":["trace[343031374] 'process raft request' (duration: 499.00418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:50.249261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:49.749934Z","time spent":"499.252265ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4559,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:50:50.249691Z","caller":"traceutil/trace.go:171","msg":"trace[1707695293] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:659; }","duration":"451.184644ms","start":"2026-04-07T17:50:49.798499Z","end":"2026-04-07T17:50:50.249683Z","steps":["trace[1707695293] 'read index received' (duration: 451.181734ms)","trace[1707695293] 'applied index is now lower than readState.Index' (duration: 2.56µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:50:50.249757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.257006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-07T17:50:50.249776Z","caller":"traceutil/trace.go:171","msg":"trace[167320728] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:636; }","duration":"451.308616ms","start":"2026-04-07T17:50:49.79846Z","end":"2026-04-07T17:50:50.249768Z","steps":["trace[167320728] 'agreement among raft nodes before linearized reading' (duration: 451.265685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:50.249791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:49.798441Z","time spent":"451.347038ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-04-07T17:50:50.260176Z","caller":"traceutil/trace.go:171","msg":"trace[1886649944] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"264.020253ms","start":"2026-04-07T17:50:49.996139Z","end":"2026-04-07T17:50:50.260159Z","steps":["trace[1886649944] 'process raft request' (duration: 263.623284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:55.637647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.173266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T17:50:55.637807Z","caller":"traceutil/trace.go:171","msg":"trace[170099308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"394.405311ms","start":"2026-04-07T17:50:55.243375Z","end":"2026-04-07T17:50:55.63778Z","steps":["trace[170099308] 'range keys from in-memory index tree' (duration: 393.852129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:55.637908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:55.243311Z","time spent":"394.582355ms","remote":"127.0.0.1:45616","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-07T17:50:55.944451Z","caller":"traceutil/trace.go:171","msg":"trace[561339511] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"160.388557ms","start":"2026-04-07T17:50:55.784036Z","end":"2026-04-07T17:50:55.944424Z","steps":["trace[561339511] 'process raft request' (duration: 160.127221ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:56.355544Z","caller":"traceutil/trace.go:171","msg":"trace[895483729] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"117.291419ms","start":"2026-04-07T17:50:56.238222Z","end":"2026-04-07T17:50:56.355513Z","steps":["trace[895483729] 'process raft request' (duration: 117.068714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:57.398215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878253341934309,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T17:50:57.790866Z","caller":"traceutil/trace.go:171","msg":"trace[991617944] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"893.152826ms","start":"2026-04-07T17:50:56.897684Z","end":"2026-04-07T17:50:57.790837Z","steps":["trace[991617944] 'read index received' (duration: 893.005422ms)","trace[991617944] 'applied index is now lower than readState.Index' (duration: 145.614µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:50:57.791088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:56.890987Z","time spent":"899.916511ms","remote":"127.0.0.1:45384","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T17:50:57.791227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.500504ms","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-07T17:50:57.79136Z","caller":"traceutil/trace.go:171","msg":"trace[818446725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"893.646417ms","start":"2026-04-07T17:50:56.897655Z","end":"2026-04-07T17:50:57.791302Z","steps":["trace[818446725] 'agreement among raft nodes before linearized reading' (duration: 893.32777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:57.791384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.964362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T17:50:57.791405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:56.897644Z","time spent":"893.752779ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T17:50:57.791433Z","caller":"traceutil/trace.go:171","msg":"trace[1589308720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"873.054425ms","start":"2026-04-07T17:50:56.918365Z","end":"2026-04-07T17:50:57.79142Z","steps":["trace[1589308720] 'agreement among raft nodes before linearized reading' (duration: 872.792039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:57.791468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:56.918354Z","time spent":"873.105386ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T17:50:57.791484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.095729ms","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-07T17:50:57.791535Z","caller":"traceutil/trace.go:171","msg":"trace[667807585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"881.187671ms","start":"2026-04-07T17:50:56.910325Z","end":"2026-04-07T17:50:57.791512Z","steps":["trace[667807585] 'agreement among raft nodes before linearized reading' (duration: 880.918215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:57.791573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:56.910312Z","time spent":"881.255242ms","remote":"127.0.0.1:45616","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-07T17:50:57.791619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.646481ms","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-07T17:50:57.791654Z","caller":"traceutil/trace.go:171","msg":"trace[136659031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"884.715293ms","start":"2026-04-07T17:50:56.906929Z","end":"2026-04-07T17:50:57.791644Z","steps":["trace[136659031] 'agreement among raft nodes before linearized reading' (duration: 884.076829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:57.79168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:56.906909Z","time spent":"884.766044ms","remote":"127.0.0.1:45616","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-07T17:50:58.342885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878253341934320,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T17:50:58.667801Z","caller":"traceutil/trace.go:171","msg":"trace[738829738] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"845.139265ms","start":"2026-04-07T17:50:57.82264Z","end":"2026-04-07T17:50:58.66778Z","steps":["trace[738829738] 'process raft request' (duration: 845.071464ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:50:58.66787Z","caller":"traceutil/trace.go:171","msg":"trace[606898109] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"845.247798ms","start":"2026-04-07T17:50:57.822563Z","end":"2026-04-07T17:50:58.667811Z","steps":["trace[606898109] 'process raft request' (duration: 845.001043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:58.667944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:57.822635Z","time spent":"845.225207ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:50:58.66808Z","caller":"traceutil/trace.go:171","msg":"trace[200867292] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"845.382081ms","start":"2026-04-07T17:50:57.822675Z","end":"2026-04-07T17:50:58.668058Z","steps":["trace[200867292] 'process raft request' (duration: 845.070074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:58.668072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:57.822544Z","time spent":"845.403421ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:50:58.668211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:57.822669Z","time spent":"845.461913ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:50:58.668771Z","caller":"traceutil/trace.go:171","msg":"trace[615123533] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"845.931283ms","start":"2026-04-07T17:50:57.82283Z","end":"2026-04-07T17:50:58.668761Z","steps":["trace[615123533] 'process raft request' (duration: 845.111385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:58.668825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:57.822822Z","time spent":"845.976984ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:50:58.669033Z","caller":"traceutil/trace.go:171","msg":"trace[453035428] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:716; }","duration":"826.489427ms","start":"2026-04-07T17:50:57.842535Z","end":"2026-04-07T17:50:58.669025Z","steps":["trace[453035428] 'read index received' (duration: 824.967493ms)","trace[453035428] 'applied index is now lower than readState.Index' (duration: 1.521134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:50:58.669106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.578281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-07T17:50:58.669134Z","caller":"traceutil/trace.go:171","msg":"trace[917538004] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:696; }","duration":"826.605942ms","start":"2026-04-07T17:50:57.842521Z","end":"2026-04-07T17:50:58.669127Z","steps":["trace[917538004] 'agreement among raft nodes before linearized reading' (duration: 826.530079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:58.669153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:57.842148Z","time spent":"826.99983ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-04-07T17:50:58.669189Z","caller":"traceutil/trace.go:171","msg":"trace[49141999] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"845.971094ms","start":"2026-04-07T17:50:57.823207Z","end":"2026-04-07T17:50:58.669178Z","steps":["trace[49141999] 'process raft request' (duration: 845.521833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:50:58.669251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:50:57.823198Z","time spent":"846.022666ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:15.790249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034629989s","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-07T17:51:15.790341Z","caller":"traceutil/trace.go:171","msg":"trace[2068820694] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.034785963s","start":"2026-04-07T17:51:14.755536Z","end":"2026-04-07T17:51:15.790322Z","steps":["trace[2068820694] 'range keys from in-memory index tree' (duration: 1.034439554s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:15.790384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:14.755513Z","time spent":"1.034861334s","remote":"127.0.0.1:45616","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-07T17:51:15.79067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037506575s","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-07T17:51:15.790718Z","caller":"traceutil/trace.go:171","msg":"trace[422677345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.037568456s","start":"2026-04-07T17:51:14.753139Z","end":"2026-04-07T17:51:15.790708Z","steps":["trace[422677345] 'range keys from in-memory index tree' (duration: 1.037408843s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:15.790748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:14.753134Z","time spent":"1.037606377s","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T17:51:15.790744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038229941s","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-07T17:51:15.790849Z","caller":"traceutil/trace.go:171","msg":"trace[1825777630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.038375345s","start":"2026-04-07T17:51:14.752444Z","end":"2026-04-07T17:51:15.79082Z","steps":["trace[1825777630] 'range keys from in-memory index tree' (duration: 1.037464093s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:15.790906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:14.752424Z","time spent":"1.038471587s","remote":"127.0.0.1:45616","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-07T17:51:15.790957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035673653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T17:51:15.790959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079432426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:51:15.79099Z","caller":"traceutil/trace.go:171","msg":"trace[2126329819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"1.035716313s","start":"2026-04-07T17:51:14.755263Z","end":"2026-04-07T17:51:15.79098Z","steps":["trace[2126329819] 'range keys from in-memory index tree' (duration: 1.035495069s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:15.79104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:14.755169Z","time spent":"1.035860557s","remote":"127.0.0.1:45616","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-07T17:51:15.79105Z","caller":"traceutil/trace.go:171","msg":"trace[697491029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"1.079579629s","start":"2026-04-07T17:51:14.711448Z","end":"2026-04-07T17:51:15.791027Z","steps":["trace[697491029] 'range keys from in-memory index tree' (duration: 1.079335274s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:15.791093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:14.711426Z","time spent":"1.079658632s","remote":"127.0.0.1:45344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T17:51:15.79121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044126247s","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-07T17:51:15.791252Z","caller":"traceutil/trace.go:171","msg":"trace[2142894708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"1.044208789s","start":"2026-04-07T17:51:14.747033Z","end":"2026-04-07T17:51:15.791242Z","steps":["trace[2142894708] 'range keys from in-memory index tree' (duration: 1.043955233s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:15.791286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:14.747012Z","time spent":"1.0442662s","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T17:51:15.794481Z","caller":"traceutil/trace.go:171","msg":"trace[426422495] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"883.057715ms","start":"2026-04-07T17:51:14.911409Z","end":"2026-04-07T17:51:15.794466Z","steps":["trace[426422495] 'process raft request' (duration: 882.713708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:15.794578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:14.911386Z","time spent":"883.130978ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:51:16.020406Z","caller":"traceutil/trace.go:171","msg":"trace[256765704] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"223.921994ms","start":"2026-04-07T17:51:15.79645Z","end":"2026-04-07T17:51:16.020372Z","steps":["trace[256765704] 'process raft request' (duration: 222.675296ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:16.0226Z","caller":"traceutil/trace.go:171","msg":"trace[1188495599] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"226.331989ms","start":"2026-04-07T17:51:15.79624Z","end":"2026-04-07T17:51:16.022572Z","steps":["trace[1188495599] 'process raft request' (duration: 226.082014ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:16.022832Z","caller":"traceutil/trace.go:171","msg":"trace[586786422] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"223.549316ms","start":"2026-04-07T17:51:15.799255Z","end":"2026-04-07T17:51:16.022804Z","steps":["trace[586786422] 'process raft request' (duration: 223.269709ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:16.023079Z","caller":"traceutil/trace.go:171","msg":"trace[517192888] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"223.306199ms","start":"2026-04-07T17:51:15.799765Z","end":"2026-04-07T17:51:16.023071Z","steps":["trace[517192888] 'process raft request' (duration: 222.974001ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:16.023102Z","caller":"traceutil/trace.go:171","msg":"trace[718165186] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:835; }","duration":"220.891325ms","start":"2026-04-07T17:51:15.802181Z","end":"2026-04-07T17:51:16.023073Z","steps":["trace[718165186] 'read index received' (duration: 216.982665ms)","trace[718165186] 'applied index is now lower than readState.Index' (duration: 3.90797ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:16.023148Z","caller":"traceutil/trace.go:171","msg":"trace[2093814973] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"223.311339ms","start":"2026-04-07T17:51:15.799817Z","end":"2026-04-07T17:51:16.023128Z","steps":["trace[2093814973] 'process raft request' (duration: 223.187266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:16.023283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.069138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:51:16.023303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.703834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:51:16.02335Z","caller":"traceutil/trace.go:171","msg":"trace[362481160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"221.202922ms","start":"2026-04-07T17:51:15.802135Z","end":"2026-04-07T17:51:16.023338Z","steps":["trace[362481160] 'agreement among raft nodes before linearized reading' (duration: 221.071629ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:16.023373Z","caller":"traceutil/trace.go:171","msg":"trace[1572460682] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:811; }","duration":"167.843547ms","start":"2026-04-07T17:51:15.855519Z","end":"2026-04-07T17:51:16.023363Z","steps":["trace[1572460682] 'agreement among raft nodes before linearized reading' (duration: 167.715364ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:49.751152Z","caller":"traceutil/trace.go:171","msg":"trace[364633174] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"203.511091ms","start":"2026-04-07T17:51:49.547617Z","end":"2026-04-07T17:51:49.751128Z","steps":["trace[364633174] 'process raft request' (duration: 203.121222ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:51.290252Z","caller":"traceutil/trace.go:171","msg":"trace[1867718579] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.000362862s","start":"2026-04-07T17:51:50.289865Z","end":"2026-04-07T17:51:51.290228Z","steps":["trace[1867718579] 'process raft request' (duration: 1.000207339s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:51.29039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:50.289849Z","time spent":"1.000470145s","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:51.756697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.950653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:51:51.756927Z","caller":"traceutil/trace.go:171","msg":"trace[726300927] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.464580006s","start":"2026-04-07T17:51:50.29233Z","end":"2026-04-07T17:51:51.75691Z","steps":["trace[726300927] 'process raft request' (duration: 1.464537565s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:51.757001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:50.292323Z","time spent":"1.464646308s","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:51:51.757139Z","caller":"traceutil/trace.go:171","msg":"trace[1957475715] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.466260823s","start":"2026-04-07T17:51:50.290861Z","end":"2026-04-07T17:51:51.757122Z","steps":["trace[1957475715] 'process raft request' (duration: 1.465925686s)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:51.757122Z","caller":"traceutil/trace.go:171","msg":"trace[1173567475] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.467151324s","start":"2026-04-07T17:51:50.289937Z","end":"2026-04-07T17:51:51.757088Z","steps":["trace[1173567475] 'process raft request' (duration: 1.070586837s)","trace[1173567475] 'compare' (duration: 395.624606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:51.757234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:50.290852Z","time spent":"1.466323304s","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:51:51.757261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:50.28993Z","time spent":"1.467258546s","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:51:51.757281Z","caller":"traceutil/trace.go:171","msg":"trace[491647212] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"190.778394ms","start":"2026-04-07T17:51:51.566491Z","end":"2026-04-07T17:51:51.75727Z","steps":["trace[491647212] 'process raft request' (duration: 190.697512ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:14.34465Z","caller":"traceutil/trace.go:171","msg":"trace[329035519] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"415.088045ms","start":"2026-04-07T17:52:13.92953Z","end":"2026-04-07T17:52:14.344618Z","steps":["trace[329035519] 'process raft request' (duration: 414.926572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:14.34484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:13.929518Z","time spent":"415.227718ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:52:14.844936Z","caller":"traceutil/trace.go:171","msg":"trace[2047391374] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"915.372543ms","start":"2026-04-07T17:52:13.929527Z","end":"2026-04-07T17:52:14.8449Z","steps":["trace[2047391374] 'process raft request' (duration: 915.274291ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:14.844941Z","caller":"traceutil/trace.go:171","msg":"trace[1145664271] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"915.363164ms","start":"2026-04-07T17:52:13.92954Z","end":"2026-04-07T17:52:14.844903Z","steps":["trace[1145664271] 'process raft request' (duration: 915.077587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:14.845107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:13.929496Z","time spent":"915.528527ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:14.845162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:13.929522Z","time spent":"915.532897ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:14.845283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.455619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:14.845169Z","caller":"traceutil/trace.go:171","msg":"trace[2072972559] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1138; }","duration":"456.331626ms","start":"2026-04-07T17:52:14.388821Z","end":"2026-04-07T17:52:14.845152Z","steps":["trace[2072972559] 'read index received' (duration: 455.798734ms)","trace[2072972559] 'applied index is now lower than readState.Index' (duration: 531.882µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:14.845345Z","caller":"traceutil/trace.go:171","msg":"trace[365076874] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1098; }","duration":"456.574852ms","start":"2026-04-07T17:52:14.388756Z","end":"2026-04-07T17:52:14.845331Z","steps":["trace[365076874] 'agreement among raft nodes before linearized reading' (duration: 456.451859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:14.845398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:14.388723Z","time spent":"456.660593ms","remote":"127.0.0.1:45438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:52:14.845568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.360886ms","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-07T17:52:14.845625Z","caller":"traceutil/trace.go:171","msg":"trace[1792801868] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"916.008278ms","start":"2026-04-07T17:52:13.929601Z","end":"2026-04-07T17:52:14.845609Z","steps":["trace[1792801868] 'process raft request' (duration: 915.264102ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:14.845637Z","caller":"traceutil/trace.go:171","msg":"trace[1014337048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"362.473278ms","start":"2026-04-07T17:52:14.483149Z","end":"2026-04-07T17:52:14.845623Z","steps":["trace[1014337048] 'agreement among raft nodes before linearized reading' (duration: 362.263894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:14.845678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:14.483119Z","time spent":"362.546889ms","remote":"127.0.0.1:45616","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-07T17:52:14.845708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:13.929593Z","time spent":"916.067339ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:14.84618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.357454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:52:14.846193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.67844ms","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-07T17:52:14.846239Z","caller":"traceutil/trace.go:171","msg":"trace[385363432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"135.471307ms","start":"2026-04-07T17:52:14.710753Z","end":"2026-04-07T17:52:14.846225Z","steps":["trace[385363432] 'agreement among raft nodes before linearized reading' (duration: 135.384345ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:14.846259Z","caller":"traceutil/trace.go:171","msg":"trace[1008856527] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1098; }","duration":"314.769741ms","start":"2026-04-07T17:52:14.531469Z","end":"2026-04-07T17:52:14.846239Z","steps":["trace[1008856527] 'agreement among raft nodes before linearized reading' (duration: 314.672699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:14.84637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:14.531454Z","time spent":"314.897015ms","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"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-07T17:52:14.847209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.348372ms","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-07T17:52:14.847346Z","caller":"traceutil/trace.go:171","msg":"trace[635123827] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1098; }","duration":"302.480935ms","start":"2026-04-07T17:52:14.544816Z","end":"2026-04-07T17:52:14.847297Z","steps":["trace[635123827] 'agreement among raft nodes before linearized reading' (duration: 301.323768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:14.8474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:14.544805Z","time spent":"302.577938ms","remote":"127.0.0.1:45820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:52:16.049132Z","caller":"traceutil/trace.go:171","msg":"trace[1420795148] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"625.658317ms","start":"2026-04-07T17:52:15.422858Z","end":"2026-04-07T17:52:16.048517Z","steps":["trace[1420795148] 'process raft request' (duration: 625.476993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:16.049276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:15.422844Z","time spent":"626.362704ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:52:16.557675Z","caller":"traceutil/trace.go:171","msg":"trace[1472102409] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"130.485503ms","start":"2026-04-07T17:52:16.427161Z","end":"2026-04-07T17:52:16.557646Z","steps":["trace[1472102409] 'process raft request' (duration: 130.305439ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:16.99179Z","caller":"traceutil/trace.go:171","msg":"trace[747823309] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1144; }","duration":"128.279735ms","start":"2026-04-07T17:52:16.863485Z","end":"2026-04-07T17:52:16.991764Z","steps":["trace[747823309] 'read index received' (duration: 27.494161ms)","trace[747823309] 'applied index is now lower than readState.Index' (duration: 100.784474ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:16.991827Z","caller":"traceutil/trace.go:171","msg":"trace[2027368052] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"137.077133ms","start":"2026-04-07T17:52:16.854712Z","end":"2026-04-07T17:52:16.991789Z","steps":["trace[2027368052] 'process raft request' (duration: 122.066155ms)","trace[2027368052] 'compare' (duration: 14.676801ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:16.991914Z","caller":"traceutil/trace.go:171","msg":"trace[1417200499] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"136.432798ms","start":"2026-04-07T17:52:16.85547Z","end":"2026-04-07T17:52:16.991903Z","steps":["trace[1417200499] 'process raft request' (duration: 136.223433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:16.992236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.736105ms","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-07T17:52:16.992278Z","caller":"traceutil/trace.go:171","msg":"trace[854260881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"128.813326ms","start":"2026-04-07T17:52:16.863455Z","end":"2026-04-07T17:52:16.992268Z","steps":["trace[854260881] 'agreement among raft nodes before linearized reading' (duration: 128.676843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:16.992507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.704332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T17:52:16.992581Z","caller":"traceutil/trace.go:171","msg":"trace[1061171198] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:1104; }","duration":"100.824954ms","start":"2026-04-07T17:52:16.89174Z","end":"2026-04-07T17:52:16.992565Z","steps":["trace[1061171198] 'agreement among raft nodes before linearized reading' (duration: 100.62076ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:18.034515Z","caller":"traceutil/trace.go:171","msg":"trace[597008142] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"323.174332ms","start":"2026-04-07T17:52:17.711313Z","end":"2026-04-07T17:52:18.034487Z","steps":["trace[597008142] 'read index received' (duration: 323.039069ms)","trace[597008142] 'applied index is now lower than readState.Index' (duration: 133.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:18.034671Z","caller":"traceutil/trace.go:171","msg":"trace[1886029578] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"384.70867ms","start":"2026-04-07T17:52:17.649952Z","end":"2026-04-07T17:52:18.034661Z","steps":["trace[1886029578] 'process raft request' (duration: 384.359382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:18.034786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:17.649927Z","time spent":"384.778492ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:18.03489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.978362ms","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-07T17:52:18.035081Z","caller":"traceutil/trace.go:171","msg":"trace[2099020340] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1107; }","duration":"309.280509ms","start":"2026-04-07T17:52:17.725779Z","end":"2026-04-07T17:52:18.035059Z","steps":["trace[2099020340] 'agreement among raft nodes before linearized reading' (duration: 308.982811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:18.035206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:17.725757Z","time spent":"309.386302ms","remote":"127.0.0.1:47112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"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-07T17:52:18.03522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.863038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:18.035309Z","caller":"traceutil/trace.go:171","msg":"trace[1055661909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"324.014242ms","start":"2026-04-07T17:52:17.711276Z","end":"2026-04-07T17:52:18.03529Z","steps":["trace[1055661909] 'agreement among raft nodes before linearized reading' (duration: 323.857999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:18.035999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.431622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T17:52:18.035381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:17.711261Z","time spent":"324.111524ms","remote":"127.0.0.1:45344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:52:18.036106Z","caller":"traceutil/trace.go:171","msg":"trace[669449292] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1107; }","duration":"181.057106ms","start":"2026-04-07T17:52:17.855021Z","end":"2026-04-07T17:52:18.036078Z","steps":["trace[669449292] 'agreement among raft nodes before linearized reading' (duration: 180.090324ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:18.443227Z","caller":"traceutil/trace.go:171","msg":"trace[969501923] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"223.209626ms","start":"2026-04-07T17:52:18.219986Z","end":"2026-04-07T17:52:18.443196Z","steps":["trace[969501923] 'read index received' (duration: 222.999012ms)","trace[969501923] 'applied index is now lower than readState.Index' (duration: 208.894µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:18.443442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.444811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:52:18.443494Z","caller":"traceutil/trace.go:171","msg":"trace[2146906730] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1108; }","duration":"223.565594ms","start":"2026-04-07T17:52:18.21992Z","end":"2026-04-07T17:52:18.443485Z","steps":["trace[2146906730] 'agreement among raft nodes before linearized reading' (duration: 223.456911ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:18.44329Z","caller":"traceutil/trace.go:171","msg":"trace[1917500797] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"298.142857ms","start":"2026-04-07T17:52:18.145121Z","end":"2026-04-07T17:52:18.443264Z","steps":["trace[1917500797] 'process raft request' (duration: 297.890101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:19.259553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.612133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:19.259648Z","caller":"traceutil/trace.go:171","msg":"trace[1682012563] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1108; }","duration":"534.765186ms","start":"2026-04-07T17:52:18.724858Z","end":"2026-04-07T17:52:19.259623Z","steps":["trace[1682012563] 'count revisions from in-memory index tree' (duration: 534.48959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:19.259694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:18.724836Z","time spent":"534.844598ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:52:19.259794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.357044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:52:19.25989Z","caller":"traceutil/trace.go:171","msg":"trace[1456294237] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"292.683025ms","start":"2026-04-07T17:52:18.967158Z","end":"2026-04-07T17:52:19.259841Z","steps":["trace[1456294237] 'read index received' (duration: 165.119686ms)","trace[1456294237] 'applied index is now lower than readState.Index' (duration: 127.562449ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:19.259961Z","caller":"traceutil/trace.go:171","msg":"trace[633299502] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"363.87333ms","start":"2026-04-07T17:52:18.896071Z","end":"2026-04-07T17:52:19.259944Z","steps":["trace[633299502] 'process raft request' (duration: 236.26315ms)","trace[633299502] 'compare' (duration: 127.086219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:19.26Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:18.896045Z","time spent":"363.931742ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:19.260085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.741381ms","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-07T17:52:19.260115Z","caller":"traceutil/trace.go:171","msg":"trace[114901202] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"263.815863ms","start":"2026-04-07T17:52:18.996289Z","end":"2026-04-07T17:52:19.260105Z","steps":["trace[114901202] 'agreement among raft nodes before linearized reading' (duration: 263.71086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:19.260241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.453551ms","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-07T17:52:19.260262Z","caller":"traceutil/trace.go:171","msg":"trace[686652726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1109; }","duration":"242.578224ms","start":"2026-04-07T17:52:19.017677Z","end":"2026-04-07T17:52:19.260256Z","steps":["trace[686652726] 'agreement among raft nodes before linearized reading' (duration: 242.497042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:19.260318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.684019ms","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-07T17:52:19.260351Z","caller":"traceutil/trace.go:171","msg":"trace[1208975759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1109; }","duration":"263.738901ms","start":"2026-04-07T17:52:18.996603Z","end":"2026-04-07T17:52:19.260342Z","steps":["trace[1208975759] 'agreement among raft nodes before linearized reading' (duration: 263.656969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:19.26036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.213026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:52:19.260377Z","caller":"traceutil/trace.go:171","msg":"trace[132539475] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1109; }","duration":"293.270378ms","start":"2026-04-07T17:52:18.967102Z","end":"2026-04-07T17:52:19.260372Z","steps":["trace[132539475] 'agreement among raft nodes before linearized reading' (duration: 293.237797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:19.260612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.949196ms","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-07T17:52:19.260641Z","caller":"traceutil/trace.go:171","msg":"trace[244572443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1108; }","duration":"697.005197ms","start":"2026-04-07T17:52:18.563623Z","end":"2026-04-07T17:52:19.260629Z","steps":["trace[244572443] 'range keys from in-memory index tree' (duration: 695.667538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:19.260659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:18.563604Z","time spent":"697.050318ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T17:52:19.554318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.045171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:19.554432Z","caller":"traceutil/trace.go:171","msg":"trace[1288875758] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1113; }","duration":"144.222755ms","start":"2026-04-07T17:52:19.41018Z","end":"2026-04-07T17:52:19.554402Z","steps":["trace[1288875758] 'count revisions from in-memory index tree' (duration: 143.948908ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:33.22687Z","caller":"traceutil/trace.go:171","msg":"trace[2045332960] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"150.3165ms","start":"2026-04-07T17:52:33.076521Z","end":"2026-04-07T17:52:33.226838Z","steps":["trace[2045332960] 'process raft request' (duration: 150.137616ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:34.729469Z","caller":"traceutil/trace.go:171","msg":"trace[275273214] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"200.270143ms","start":"2026-04-07T17:52:34.529178Z","end":"2026-04-07T17:52:34.729448Z","steps":["trace[275273214] 'process raft request' (duration: 199.359833ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:49.53045Z","caller":"traceutil/trace.go:171","msg":"trace[470883711] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"102.108845ms","start":"2026-04-07T17:52:49.428292Z","end":"2026-04-07T17:52:49.530401Z","steps":["trace[470883711] 'read index received' (duration: 21.723644ms)","trace[470883711] 'applied index is now lower than readState.Index' (duration: 80.383291ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:49.53067Z","caller":"traceutil/trace.go:171","msg":"trace[545419604] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"103.155159ms","start":"2026-04-07T17:52:49.427491Z","end":"2026-04-07T17:52:49.530647Z","steps":["trace[545419604] 'process raft request' (duration: 22.585904ms)","trace[545419604] 'compare' (duration: 80.173865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:49.531032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.714339ms","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-07T17:52:49.53111Z","caller":"traceutil/trace.go:171","msg":"trace[1157994800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"102.818362ms","start":"2026-04-07T17:52:49.428271Z","end":"2026-04-07T17:52:49.531089Z","steps":["trace[1157994800] 'agreement among raft nodes before linearized reading' (duration: 102.619077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:54.044633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878253341935644,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T17:52:54.157732Z","caller":"traceutil/trace.go:171","msg":"trace[1327989590] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"779.57941ms","start":"2026-04-07T17:52:53.378124Z","end":"2026-04-07T17:52:54.157703Z","steps":["trace[1327989590] 'process raft request' (duration: 779.416016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:54.157902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:53.378098Z","time spent":"779.712352ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:54.447139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.115946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:52:54.447605Z","caller":"traceutil/trace.go:171","msg":"trace[191022655] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1354; }","duration":"903.491531ms","start":"2026-04-07T17:52:53.544093Z","end":"2026-04-07T17:52:54.447585Z","steps":["trace[191022655] 'read index received' (duration: 613.411038ms)","trace[191022655] 'applied index is now lower than readState.Index' (duration: 290.078783ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:54.447756Z","caller":"traceutil/trace.go:171","msg":"trace[1127612987] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"658.900481ms","start":"2026-04-07T17:52:53.788842Z","end":"2026-04-07T17:52:54.447742Z","steps":["trace[1127612987] 'process raft request' (duration: 446.088159ms)","trace[1127612987] 'compare' (duration: 211.87046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:54.448197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.966654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T17:52:54.448253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.944504ms","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-07T17:52:54.448315Z","caller":"traceutil/trace.go:171","msg":"trace[1624751605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"879.051547ms","start":"2026-04-07T17:52:53.569248Z","end":"2026-04-07T17:52:54.4483Z","steps":["trace[1624751605] 'agreement among raft nodes before linearized reading' (duration: 878.875503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:54.448347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:53.788821Z","time spent":"659.415962ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:52:54.44831Z","caller":"traceutil/trace.go:171","msg":"trace[951432118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"879.108467ms","start":"2026-04-07T17:52:53.569174Z","end":"2026-04-07T17:52:54.448282Z","steps":["trace[951432118] 'agreement among raft nodes before linearized reading' (duration: 878.936624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:54.44862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:53.569151Z","time spent":"879.413534ms","remote":"127.0.0.1:45616","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-07T17:52:54.448403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:53.569218Z","time spent":"879.170489ms","remote":"127.0.0.1:45616","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-07T17:52:54.448047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.078265ms","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-07T17:52:54.448871Z","caller":"traceutil/trace.go:171","msg":"trace[2040644245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"883.951844ms","start":"2026-04-07T17:52:53.564885Z","end":"2026-04-07T17:52:54.448837Z","steps":["trace[2040644245] 'agreement among raft nodes before linearized reading' (duration: 882.933962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:54.448941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:53.564866Z","time spent":"884.053166ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T17:52:54.448129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.034243ms","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-07T17:52:54.448997Z","caller":"traceutil/trace.go:171","msg":"trace[1681971187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"904.948653ms","start":"2026-04-07T17:52:53.544042Z","end":"2026-04-07T17:52:54.448991Z","steps":["trace[1681971187] 'agreement among raft nodes before linearized reading' (duration: 903.964931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:54.448188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.789711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:52:54.449016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:53.544017Z","time spent":"904.994644ms","remote":"127.0.0.1:45616","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-07T17:52:54.449076Z","caller":"traceutil/trace.go:171","msg":"trace[249542726] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1303; }","duration":"213.671901ms","start":"2026-04-07T17:52:54.235358Z","end":"2026-04-07T17:52:54.44903Z","steps":["trace[249542726] 'agreement among raft nodes before linearized reading' (duration: 212.800312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:54.447983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.711219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:54.44915Z","caller":"traceutil/trace.go:171","msg":"trace[1336148555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"739.915295ms","start":"2026-04-07T17:52:53.709226Z","end":"2026-04-07T17:52:54.449142Z","steps":["trace[1336148555] 'agreement among raft nodes before linearized reading' (duration: 738.715399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:54.449171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:53.70921Z","time spent":"739.953006ms","remote":"127.0.0.1:45344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:52:54.629742Z","caller":"traceutil/trace.go:171","msg":"trace[2049889591] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"174.304165ms","start":"2026-04-07T17:52:54.455398Z","end":"2026-04-07T17:52:54.629702Z","steps":["trace[2049889591] 'process raft request' (duration: 174.225363ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:54.630022Z","caller":"traceutil/trace.go:171","msg":"trace[273826196] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"175.315877ms","start":"2026-04-07T17:52:54.454676Z","end":"2026-04-07T17:52:54.629992Z","steps":["trace[273826196] 'process raft request' (duration: 107.373003ms)","trace[273826196] 'compare' (duration: 67.362791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:55.969682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.1002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:55.969778Z","caller":"traceutil/trace.go:171","msg":"trace[789347283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"258.272564ms","start":"2026-04-07T17:52:55.711485Z","end":"2026-04-07T17:52:55.969757Z","steps":["trace[789347283] 'range keys from in-memory index tree' (duration: 257.925406ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:56.481255Z","caller":"traceutil/trace.go:171","msg":"trace[1313257438] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"310.849667ms","start":"2026-04-07T17:52:56.17038Z","end":"2026-04-07T17:52:56.481229Z","steps":["trace[1313257438] 'process raft request' (duration: 310.55909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:56.481401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:56.170353Z","time spent":"310.981459ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:56.836426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.673813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:52:56.836743Z","caller":"traceutil/trace.go:171","msg":"trace[251562284] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"350.605042ms","start":"2026-04-07T17:52:56.485936Z","end":"2026-04-07T17:52:56.836541Z","steps":["trace[251562284] 'process raft request' (duration: 227.715274ms)","trace[251562284] 'compare' (duration: 122.458238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:56.83682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:56.485918Z","time spent":"350.864169ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:52:57.015118Z","caller":"traceutil/trace.go:171","msg":"trace[1522008852] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1363; }","duration":"450.466617ms","start":"2026-04-07T17:52:56.56462Z","end":"2026-04-07T17:52:57.015087Z","steps":["trace[1522008852] 'read index received' (duration: 149.046932ms)","trace[1522008852] 'applied index is now lower than readState.Index' (duration: 301.418455ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:52:57.015215Z","caller":"traceutil/trace.go:171","msg":"trace[375667761] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"528.335112ms","start":"2026-04-07T17:52:56.486821Z","end":"2026-04-07T17:52:57.015156Z","steps":["trace[375667761] 'process raft request' (duration: 505.783779ms)","trace[375667761] 'compare' (duration: 22.318957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:57.015453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:56.486803Z","time spent":"528.494525ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:52:57.015481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.829986ms","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-07T17:52:57.015529Z","caller":"traceutil/trace.go:171","msg":"trace[1339631845] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1312; }","duration":"450.952307ms","start":"2026-04-07T17:52:56.564563Z","end":"2026-04-07T17:52:57.015515Z","steps":["trace[1339631845] 'agreement among raft nodes before linearized reading' (duration: 450.700042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:57.015571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:56.56454Z","time spent":"451.021839ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-07T17:52:57.015601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.156243ms","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-07T17:52:57.015669Z","caller":"traceutil/trace.go:171","msg":"trace[2019660662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1312; }","duration":"381.272655ms","start":"2026-04-07T17:52:56.634381Z","end":"2026-04-07T17:52:57.015654Z","steps":["trace[2019660662] 'agreement among raft nodes before linearized reading' (duration: 381.090761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:57.015696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:56.634354Z","time spent":"381.337306ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T17:52:57.015782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.111355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:52:57.015777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.988823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:57.015798Z","caller":"traceutil/trace.go:171","msg":"trace[36903739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"304.161946ms","start":"2026-04-07T17:52:56.71163Z","end":"2026-04-07T17:52:57.015792Z","steps":["trace[36903739] 'agreement among raft nodes before linearized reading' (duration: 304.137926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:57.015811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:56.711611Z","time spent":"304.197758ms","remote":"127.0.0.1:45344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:52:57.015823Z","caller":"traceutil/trace.go:171","msg":"trace[1106954467] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1312; }","duration":"120.052934ms","start":"2026-04-07T17:52:56.895755Z","end":"2026-04-07T17:52:57.015808Z","steps":["trace[1106954467] 'agreement among raft nodes before linearized reading' (duration: 119.966182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:57.016058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.574772ms","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-07T17:52:57.016084Z","caller":"traceutil/trace.go:171","msg":"trace[1998406629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1312; }","duration":"381.648565ms","start":"2026-04-07T17:52:56.634428Z","end":"2026-04-07T17:52:57.016077Z","steps":["trace[1998406629] 'agreement among raft nodes before linearized reading' (duration: 381.582123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:57.0161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:56.634392Z","time spent":"381.703725ms","remote":"127.0.0.1:45616","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-07T17:52:57.016271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.090601ms","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-07T17:52:57.016306Z","caller":"traceutil/trace.go:171","msg":"trace[392098419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"122.143683ms","start":"2026-04-07T17:52:56.894151Z","end":"2026-04-07T17:52:57.016295Z","steps":["trace[392098419] 'agreement among raft nodes before linearized reading' (duration: 121.976409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:57.029665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.794966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T17:52:57.029749Z","caller":"traceutil/trace.go:171","msg":"trace[1423254215] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:1312; }","duration":"135.949019ms","start":"2026-04-07T17:52:56.89378Z","end":"2026-04-07T17:52:57.029729Z","steps":["trace[1423254215] 'agreement among raft nodes before linearized reading' (duration: 121.837745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:57.530645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.551305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:52:57.530783Z","caller":"traceutil/trace.go:171","msg":"trace[1155416836] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"343.965184ms","start":"2026-04-07T17:52:57.186797Z","end":"2026-04-07T17:52:57.530762Z","steps":["trace[1155416836] 'read index received' (duration: 70.144273ms)","trace[1155416836] 'applied index is now lower than readState.Index' (duration: 273.819271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:57.530921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.095976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:57.53096Z","caller":"traceutil/trace.go:171","msg":"trace[1992287916] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1321; }","duration":"344.174808ms","start":"2026-04-07T17:52:57.18677Z","end":"2026-04-07T17:52:57.530945Z","steps":["trace[1992287916] 'agreement among raft nodes before linearized reading' (duration: 344.047355ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:57.530936Z","caller":"traceutil/trace.go:171","msg":"trace[463322324] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"358.041506ms","start":"2026-04-07T17:52:57.17285Z","end":"2026-04-07T17:52:57.530892Z","steps":["trace[463322324] 'process raft request' (duration: 84.134074ms)","trace[463322324] 'compare' (duration: 273.173086ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:57.531003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:57.186757Z","time spent":"344.235609ms","remote":"127.0.0.1:47136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:52:57.531121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:57.172832Z","time spent":"358.198269ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T17:52:58.128816Z","caller":"traceutil/trace.go:171","msg":"trace[449577356] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"590.505606ms","start":"2026-04-07T17:52:57.538281Z","end":"2026-04-07T17:52:58.128786Z","steps":["trace[449577356] 'process raft request' (duration: 590.300952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:58.128963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:57.538263Z","time spent":"590.63416ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T17:52:58.282556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.014831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:52:58.282718Z","caller":"traceutil/trace.go:171","msg":"trace[1248150136] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"738.812821ms","start":"2026-04-07T17:52:57.543873Z","end":"2026-04-07T17:52:58.282685Z","steps":["trace[1248150136] 'process raft request' (duration: 602.563585ms)","trace[1248150136] 'compare' (duration: 135.849618ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:58.282808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:57.543842Z","time spent":"738.914113ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:52:59.042947Z","caller":"traceutil/trace.go:171","msg":"trace[980077879] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"197.945141ms","start":"2026-04-07T17:52:58.844942Z","end":"2026-04-07T17:52:59.042887Z","steps":["trace[980077879] 'process raft request' (duration: 197.733476ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:05.008035Z","caller":"traceutil/trace.go:171","msg":"trace[638667145] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"414.29328ms","start":"2026-04-07T17:53:04.593698Z","end":"2026-04-07T17:53:05.007991Z","steps":["trace[638667145] 'process raft request' (duration: 413.998624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:05.00828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:04.593669Z","time spent":"414.475244ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:06.037214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.582917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:06.037317Z","caller":"traceutil/trace.go:171","msg":"trace[1460288460] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1424; }","duration":"1.191653372s","start":"2026-04-07T17:53:04.845646Z","end":"2026-04-07T17:53:06.0373Z","steps":["trace[1460288460] 'read index received' (duration: 162.055981ms)","trace[1460288460] 'applied index is now lower than readState.Index' (duration: 1.029596181s)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:06.037424Z","caller":"traceutil/trace.go:171","msg":"trace[716353029] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"1.335922257s","start":"2026-04-07T17:53:04.701488Z","end":"2026-04-07T17:53:06.03741Z","steps":["trace[716353029] 'process raft request' (duration: 430.055563ms)","trace[716353029] 'compare' (duration: 905.289661ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:06.037485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:04.701462Z","time spent":"1.335994048s","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:53:06.037662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111229329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:06.03776Z","caller":"traceutil/trace.go:171","msg":"trace[2067140838] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1371; }","duration":"1.111399384s","start":"2026-04-07T17:53:04.926332Z","end":"2026-04-07T17:53:06.037731Z","steps":["trace[2067140838] 'agreement among raft nodes before linearized reading' (duration: 1.111247211s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:06.03782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:04.926302Z","time spent":"1.111502045s","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:06.037889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.855163ms","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-07T17:53:06.037925Z","caller":"traceutil/trace.go:171","msg":"trace[1097553745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"905.920215ms","start":"2026-04-07T17:53:05.131993Z","end":"2026-04-07T17:53:06.037913Z","steps":["trace[1097553745] 'agreement among raft nodes before linearized reading' (duration: 905.793422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:06.037969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:05.131978Z","time spent":"905.979836ms","remote":"127.0.0.1:45616","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-07T17:53:06.038105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.234484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:06.038136Z","caller":"traceutil/trace.go:171","msg":"trace[1671432312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"325.017882ms","start":"2026-04-07T17:53:05.71311Z","end":"2026-04-07T17:53:06.038128Z","steps":["trace[1671432312] 'agreement among raft nodes before linearized reading' (duration: 324.971361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:06.038161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:05.71309Z","time spent":"325.065722ms","remote":"127.0.0.1:45344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T17:53:06.038148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19247497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T17:53:06.038225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.106545ms","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-07T17:53:06.038275Z","caller":"traceutil/trace.go:171","msg":"trace[1089335865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"909.180277ms","start":"2026-04-07T17:53:05.12908Z","end":"2026-04-07T17:53:06.03826Z","steps":["trace[1089335865] 'agreement among raft nodes before linearized reading' (duration: 908.991123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:06.038332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:05.129066Z","time spent":"909.253669ms","remote":"127.0.0.1:45616","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-07T17:53:06.038286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.934966ms","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-07T17:53:06.038384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.03914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-07T17:53:06.038421Z","caller":"traceutil/trace.go:171","msg":"trace[1321176672] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1371; }","duration":"824.112532ms","start":"2026-04-07T17:53:05.214297Z","end":"2026-04-07T17:53:06.03841Z","steps":["trace[1321176672] 'agreement among raft nodes before linearized reading' (duration: 824.02308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:06.038458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:05.214276Z","time spent":"824.172984ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-07T17:53:06.038489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.937882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T17:53:06.038469Z","caller":"traceutil/trace.go:171","msg":"trace[1892948609] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1371; }","duration":"271.206552ms","start":"2026-04-07T17:53:05.767233Z","end":"2026-04-07T17:53:06.038439Z","steps":["trace[1892948609] 'agreement among raft nodes before linearized reading' (duration: 270.669771ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:06.038281Z","caller":"traceutil/trace.go:171","msg":"trace[910654353] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1371; }","duration":"1.192658674s","start":"2026-04-07T17:53:04.845585Z","end":"2026-04-07T17:53:06.038244Z","steps":["trace[910654353] 'agreement among raft nodes before linearized reading' (duration: 1.192382298s)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:06.038541Z","caller":"traceutil/trace.go:171","msg":"trace[1448474244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1371; }","duration":"962.028144ms","start":"2026-04-07T17:53:05.076503Z","end":"2026-04-07T17:53:06.038531Z","steps":["trace[1448474244] 'agreement among raft nodes before linearized reading' (duration: 961.81103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:06.038615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:05.076484Z","time spent":"962.122487ms","remote":"127.0.0.1:45616","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-07T17:53:06.038569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.015714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T17:53:06.038696Z","caller":"traceutil/trace.go:171","msg":"trace[1706319828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1371; }","duration":"944.183717ms","start":"2026-04-07T17:53:05.094484Z","end":"2026-04-07T17:53:06.038668Z","steps":["trace[1706319828] 'agreement among raft nodes before linearized reading' (duration: 943.751018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:06.038791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:05.094474Z","time spent":"944.305951ms","remote":"127.0.0.1:45616","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":"warn","ts":"2026-04-07T17:53:06.038878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.768777ms","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-07T17:53:06.038981Z","caller":"traceutil/trace.go:171","msg":"trace[1432734246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"925.89561ms","start":"2026-04-07T17:53:05.113067Z","end":"2026-04-07T17:53:06.038963Z","steps":["trace[1432734246] 'agreement among raft nodes before linearized reading' (duration: 925.549932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:06.038628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:04.845566Z","time spent":"1.193041393s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:53:06.039067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:05.113055Z","time spent":"925.998572ms","remote":"127.0.0.1:45616","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-07T17:53:06.28598Z","caller":"traceutil/trace.go:171","msg":"trace[1239595019] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"239.236671ms","start":"2026-04-07T17:53:06.046724Z","end":"2026-04-07T17:53:06.285961Z","steps":["trace[1239595019] 'process raft request' (duration: 239.177689ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:06.28606Z","caller":"traceutil/trace.go:171","msg":"trace[1078374821] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"242.076634ms","start":"2026-04-07T17:53:06.043948Z","end":"2026-04-07T17:53:06.286024Z","steps":["trace[1078374821] 'process raft request' (duration: 239.594139ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:06.286067Z","caller":"traceutil/trace.go:171","msg":"trace[917485443] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"241.527672ms","start":"2026-04-07T17:53:06.044506Z","end":"2026-04-07T17:53:06.286034Z","steps":["trace[917485443] 'process raft request' (duration: 241.331848ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:06.286413Z","caller":"traceutil/trace.go:171","msg":"trace[881611383] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"239.520196ms","start":"2026-04-07T17:53:06.04684Z","end":"2026-04-07T17:53:06.28636Z","steps":["trace[881611383] 'process raft request' (duration: 239.093137ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:06.28649Z","caller":"traceutil/trace.go:171","msg":"trace[1481171601] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"239.598789ms","start":"2026-04-07T17:53:06.046877Z","end":"2026-04-07T17:53:06.286476Z","steps":["trace[1481171601] 'process raft request' (duration: 239.20095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:06.286501Z","caller":"traceutil/trace.go:171","msg":"trace[217273394] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"239.438635ms","start":"2026-04-07T17:53:06.047041Z","end":"2026-04-07T17:53:06.28648Z","steps":["trace[217273394] 'process raft request' (duration: 239.384214ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:56:19.158499Z","caller":"traceutil/trace.go:171","msg":"trace[244323138] linearizableReadLoop","detail":"{readStateIndex:2383; appliedIndex:2382; }","duration":"158.00192ms","start":"2026-04-07T17:56:19.000467Z","end":"2026-04-07T17:56:19.158468Z","steps":["trace[244323138] 'read index received' (duration: 157.751665ms)","trace[244323138] 'applied index is now lower than readState.Index' (duration: 248.585µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:56:19.158814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.311507ms","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-07T17:56:19.158863Z","caller":"traceutil/trace.go:171","msg":"trace[1542273375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2288; }","duration":"158.44032ms","start":"2026-04-07T17:56:19.000406Z","end":"2026-04-07T17:56:19.158847Z","steps":["trace[1542273375] 'agreement among raft nodes before linearized reading' (duration: 158.202204ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:56:19.158992Z","caller":"traceutil/trace.go:171","msg":"trace[653640795] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"411.560152ms","start":"2026-04-07T17:56:18.747356Z","end":"2026-04-07T17:56:19.158916Z","steps":["trace[653640795] 'process raft request' (duration: 410.896398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:56:19.159239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:56:18.747289Z","time spent":"411.850428ms","remote":"127.0.0.1:45616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}