{"level":"warn","ts":"2026-04-23T16:56:56.211939Z","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-23T16:56:56.212037Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.11:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.11:2380","--initial-cluster=instance=https://199.19.213.11:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.11:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.11: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-23T16:56:56.212132Z","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-23T16:56:56.21216Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-04-23T16:56:56.212182Z","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-23T16:56:56.212944Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"]} {"level":"info","ts":"2026-04-23T16:56:56.21304Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.11: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-23T16:56:56.217249Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.96823ms"} {"level":"info","ts":"2026-04-23T16:56:56.226332Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ce8ad90e35537ee3","cluster-id":"3c17b62c6529d987"} {"level":"info","ts":"2026-04-23T16:56:56.226383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T16:56:56.2264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 0"} {"level":"info","ts":"2026-04-23T16:56:56.22641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ce8ad90e35537ee3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T16:56:56.226417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 1"} {"level":"info","ts":"2026-04-23T16:56:56.226446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"warn","ts":"2026-04-23T16:56:56.23441Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T16:56:56.235579Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T16:56:56.237227Z","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-23T16:56:56.239602Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ce8ad90e35537ee3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T16:56:56.239729Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ce8ad90e35537ee3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T16:56:56.239949Z","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-23T16:56:56.239983Z","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-23T16:56:56.239992Z","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-23T16:56:56.240609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"info","ts":"2026-04-23T16:56:56.240718Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","added-peer-id":"ce8ad90e35537ee3","added-peer-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-04-23T16:56:56.243314Z","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-23T16:56:56.243435Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-04-23T16:56:56.243476Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-04-23T16:56:56.243593Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ce8ad90e35537ee3","initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T16:56:56.243657Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T16:56:57.131386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T16:56:57.13146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T16:56:57.131498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgPreVoteResp from ce8ad90e35537ee3 at term 1"} {"level":"info","ts":"2026-04-23T16:56:57.131547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became candidate at term 2"} {"level":"info","ts":"2026-04-23T16:56:57.13156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgVoteResp from ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-04-23T16:56:57.131582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became leader at term 2"} {"level":"info","ts":"2026-04-23T16:56:57.131595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ce8ad90e35537ee3 elected leader ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-04-23T16:56:57.22333Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:56:57.225947Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:56:57.225952Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ce8ad90e35537ee3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.11:2379]}","request-path":"/0/members/ce8ad90e35537ee3/attributes","cluster-id":"3c17b62c6529d987","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T16:56:57.226004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:56:57.226292Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T16:56:57.226329Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T16:56:57.228076Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:56:57.228156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:56:57.228187Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:56:57.228217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T16:56:57.2303Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.11:2379"} {"level":"info","ts":"2026-04-23T16:56:58.744825Z","caller":"traceutil/trace.go:171","msg":"trace[895535830] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"254.970239ms","start":"2026-04-23T16:56:58.489839Z","end":"2026-04-23T16:56:58.744809Z","steps":["trace[895535830] 'process raft request' (duration: 254.736225ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:56:58.745348Z","caller":"traceutil/trace.go:171","msg":"trace[365780682] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"254.842366ms","start":"2026-04-23T16:56:58.489924Z","end":"2026-04-23T16:56:58.744767Z","steps":["trace[365780682] 'process raft request' (duration: 254.784986ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:56:58.855539Z","caller":"traceutil/trace.go:171","msg":"trace[1276765203] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"105.557881ms","start":"2026-04-23T16:56:58.749936Z","end":"2026-04-23T16:56:58.855494Z","steps":["trace[1276765203] 'process raft request' (duration: 105.52868ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:56:58.855883Z","caller":"traceutil/trace.go:171","msg":"trace[942402546] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"365.242916ms","start":"2026-04-23T16:56:58.49062Z","end":"2026-04-23T16:56:58.855863Z","steps":["trace[942402546] 'process raft request' (duration: 364.757435ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:56:58.855931Z","caller":"traceutil/trace.go:171","msg":"trace[1888825704] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:7; }","duration":"365.355409ms","start":"2026-04-23T16:56:58.49055Z","end":"2026-04-23T16:56:58.855905Z","steps":["trace[1888825704] 'read index received' (duration: 254.07298ms)","trace[1888825704] 'applied index is now lower than readState.Index' (duration: 111.280229ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:56:58.856088Z","caller":"traceutil/trace.go:171","msg":"trace[734961090] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"363.18983ms","start":"2026-04-23T16:56:58.492749Z","end":"2026-04-23T16:56:58.855939Z","steps":["trace[734961090] 'process raft request' (duration: 362.662828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:56:58.856113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.573724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:56:58.85612Z","caller":"traceutil/trace.go:171","msg":"trace[1449255202] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"106.594443ms","start":"2026-04-23T16:56:58.749458Z","end":"2026-04-23T16:56:58.856052Z","steps":["trace[1449255202] 'process raft request' (duration: 105.98113ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:56:58.856171Z","caller":"traceutil/trace.go:171","msg":"trace[379995524] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:0; response_revision:12; }","duration":"366.681879ms","start":"2026-04-23T16:56:58.489478Z","end":"2026-04-23T16:56:58.85616Z","steps":["trace[379995524] 'agreement among raft nodes before linearized reading' (duration: 366.540216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:56:58.856208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:56:58.489458Z","time spent":"366.742251ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.11\" "} {"level":"info","ts":"2026-04-23T16:56:58.856241Z","caller":"traceutil/trace.go:171","msg":"trace[1427990545] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"365.743308ms","start":"2026-04-23T16:56:58.490432Z","end":"2026-04-23T16:56:58.856175Z","steps":["trace[1427990545] 'process raft request' (duration: 364.903269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:56:58.856329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.589072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:56:58.856383Z","caller":"traceutil/trace.go:171","msg":"trace[1741102076] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:12; }","duration":"364.669724ms","start":"2026-04-23T16:56:58.491693Z","end":"2026-04-23T16:56:58.856363Z","steps":["trace[1741102076] 'agreement among raft nodes before linearized reading' (duration: 364.583512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:56:58.85642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:56:58.491685Z","time spent":"364.726495ms","remote":"127.0.0.1:50684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-23T16:56:58.856378Z","caller":"traceutil/trace.go:171","msg":"trace[1330453022] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"366.22795ms","start":"2026-04-23T16:56:58.490137Z","end":"2026-04-23T16:56:58.856365Z","steps":["trace[1330453022] 'process raft request' (duration: 358.372213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:56:58.85652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.291824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-23T16:56:58.856604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.594927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:56:58.85664Z","caller":"traceutil/trace.go:171","msg":"trace[736545803] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"315.654188ms","start":"2026-04-23T16:56:58.540976Z","end":"2026-04-23T16:56:58.856631Z","steps":["trace[736545803] 'agreement among raft nodes before linearized reading' (duration: 315.601957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:56:58.856674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:56:58.540967Z","time spent":"315.70109ms","remote":"127.0.0.1:50726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-23T16:56:58.856733Z","caller":"traceutil/trace.go:171","msg":"trace[943835224] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:12; }","duration":"191.530838ms","start":"2026-04-23T16:56:58.665077Z","end":"2026-04-23T16:56:58.856608Z","steps":["trace[943835224] 'agreement among raft nodes before linearized reading' (duration: 191.298434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:56:58.857056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:56:58.490598Z","time spent":"365.40817ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:56:58.858159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:56:58.492742Z","time spent":"363.421065ms","remote":"127.0.0.1:51120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:56:58.85824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:56:58.490378Z","time spent":"365.944563ms","remote":"127.0.0.1:51120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:56:58.858294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:56:58.49012Z","time spent":"366.465354ms","remote":"127.0.0.1:51120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:56:59.698697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.550828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-23T16:56:59.698951Z","caller":"traceutil/trace.go:171","msg":"trace[466082877] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"244.499764ms","start":"2026-04-23T16:56:59.454439Z","end":"2026-04-23T16:56:59.698939Z","steps":["trace[466082877] 'process raft request' (duration: 80.576127ms)","trace[466082877] 'compare' (duration: 163.461436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:05.045996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.029283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-23T16:57:05.046099Z","caller":"traceutil/trace.go:171","msg":"trace[812650927] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:335; }","duration":"287.184227ms","start":"2026-04-23T16:57:04.758891Z","end":"2026-04-23T16:57:05.046075Z","steps":["trace[812650927] 'range keys from in-memory index tree' (duration: 286.945941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:05.046304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.520437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-23T16:57:05.046379Z","caller":"traceutil/trace.go:171","msg":"trace[210107331] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:335; }","duration":"237.587919ms","start":"2026-04-23T16:57:04.808743Z","end":"2026-04-23T16:57:05.046331Z","steps":["trace[210107331] 'range keys from in-memory index tree' (duration: 237.454645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:05.046592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.636034ms","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-23T16:57:05.046637Z","caller":"traceutil/trace.go:171","msg":"trace[1713100830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:335; }","duration":"208.703025ms","start":"2026-04-23T16:57:04.837919Z","end":"2026-04-23T16:57:05.046622Z","steps":["trace[1713100830] 'range keys from in-memory index tree' (duration: 208.574904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:05.046857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.168216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-23T16:57:05.046909Z","caller":"traceutil/trace.go:171","msg":"trace[1436882067] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:335; }","duration":"189.241088ms","start":"2026-04-23T16:57:04.857654Z","end":"2026-04-23T16:57:05.046896Z","steps":["trace[1436882067] 'range keys from in-memory index tree' (duration: 189.106494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:05.047167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.012835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-23T16:57:05.047226Z","caller":"traceutil/trace.go:171","msg":"trace[101786865] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:335; }","duration":"139.103236ms","start":"2026-04-23T16:57:04.908108Z","end":"2026-04-23T16:57:05.047211Z","steps":["trace[101786865] 'range keys from in-memory index tree' (duration: 138.905642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:06.094848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.368002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:57:06.094986Z","caller":"traceutil/trace.go:171","msg":"trace[1323454928] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:372; }","duration":"435.922731ms","start":"2026-04-23T16:57:05.659048Z","end":"2026-04-23T16:57:06.09497Z","steps":["trace[1323454928] 'read index received' (duration: 1.778461ms)","trace[1323454928] 'applied index is now lower than readState.Index' (duration: 434.14365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:06.09509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.047074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-23T16:57:06.095075Z","caller":"traceutil/trace.go:171","msg":"trace[321007913] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"472.837503ms","start":"2026-04-23T16:57:05.622211Z","end":"2026-04-23T16:57:06.095048Z","steps":["trace[321007913] 'process raft request' (duration: 472.72018ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:06.095107Z","caller":"traceutil/trace.go:171","msg":"trace[1278865093] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:362; }","duration":"436.105374ms","start":"2026-04-23T16:57:05.658997Z","end":"2026-04-23T16:57:06.095102Z","steps":["trace[1278865093] 'agreement among raft nodes before linearized reading' (duration: 436.010733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:06.095145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:05.658971Z","time spent":"436.169095ms","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-23T16:57:06.09513Z","caller":"traceutil/trace.go:171","msg":"trace[729645130] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"475.846801ms","start":"2026-04-23T16:57:05.619248Z","end":"2026-04-23T16:57:06.095095Z","steps":["trace[729645130] 'process raft request' (duration: 313.172812ms)","trace[729645130] 'compare' (duration: 162.233419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:06.095303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:05.61923Z","time spent":"475.966653ms","remote":"127.0.0.1:50680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:06.095362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:05.622188Z","time spent":"472.939655ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4777,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:57:06.228215Z","caller":"traceutil/trace.go:171","msg":"trace[1439371968] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"129.806457ms","start":"2026-04-23T16:57:06.098388Z","end":"2026-04-23T16:57:06.228195Z","steps":["trace[1439371968] 'process raft request' (duration: 129.777426ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:06.228286Z","caller":"traceutil/trace.go:171","msg":"trace[1498840947] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"564.326114ms","start":"2026-04-23T16:57:05.663945Z","end":"2026-04-23T16:57:06.228271Z","steps":["trace[1498840947] 'process raft request' (duration: 541.521851ms)","trace[1498840947] 'compare' (duration: 22.484017ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:06.2283Z","caller":"traceutil/trace.go:171","msg":"trace[900142865] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"562.428942ms","start":"2026-04-23T16:57:05.665836Z","end":"2026-04-23T16:57:06.228265Z","steps":["trace[900142865] 'process raft request' (duration: 562.29316ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:06.228217Z","caller":"traceutil/trace.go:171","msg":"trace[548197699] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"563.064827ms","start":"2026-04-23T16:57:05.665126Z","end":"2026-04-23T16:57:06.22819Z","steps":["trace[548197699] 'process raft request' (duration: 562.956866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:06.22846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:05.665827Z","time spent":"562.553105ms","remote":"127.0.0.1:51060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:57:06.228559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.390961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-23T16:57:06.228463Z","caller":"traceutil/trace.go:171","msg":"trace[2009795007] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:374; }","duration":"130.280617ms","start":"2026-04-23T16:57:06.098161Z","end":"2026-04-23T16:57:06.228442Z","steps":["trace[2009795007] 'read index received' (duration: 107.281949ms)","trace[2009795007] 'applied index is now lower than readState.Index' (duration: 22.996608ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:06.228609Z","caller":"traceutil/trace.go:171","msg":"trace[2012966248] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:366; }","duration":"130.466582ms","start":"2026-04-23T16:57:06.098136Z","end":"2026-04-23T16:57:06.228603Z","steps":["trace[2012966248] 'agreement among raft nodes before linearized reading' (duration: 130.351489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:06.228521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:05.663928Z","time spent":"564.400147ms","remote":"127.0.0.1:50680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:06.228757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:05.665114Z","time spent":"563.322483ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:57:06.76707Z","caller":"traceutil/trace.go:171","msg":"trace[273047434] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"118.035402ms","start":"2026-04-23T16:57:06.649016Z","end":"2026-04-23T16:57:06.767052Z","steps":["trace[273047434] 'process raft request' (duration: 117.620292ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:07.381946Z","caller":"traceutil/trace.go:171","msg":"trace[1445945904] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"174.804621ms","start":"2026-04-23T16:57:07.207124Z","end":"2026-04-23T16:57:07.381929Z","steps":["trace[1445945904] 'process raft request' (duration: 174.694889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:07.616483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.678234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-23T16:57:07.616594Z","caller":"traceutil/trace.go:171","msg":"trace[1976646059] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:386; }","duration":"117.808067ms","start":"2026-04-23T16:57:07.498768Z","end":"2026-04-23T16:57:07.616576Z","steps":["trace[1976646059] 'range keys from in-memory index tree' (duration: 117.578291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:07.616668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.905517ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:07.616755Z","caller":"traceutil/trace.go:171","msg":"trace[832217758] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:386; }","duration":"124.994789ms","start":"2026-04-23T16:57:07.491748Z","end":"2026-04-23T16:57:07.616742Z","steps":["trace[832217758] 'range keys from in-memory index tree' (duration: 124.847176ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:08.138309Z","caller":"traceutil/trace.go:171","msg":"trace[2122778635] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"512.396926ms","start":"2026-04-23T16:57:07.625886Z","end":"2026-04-23T16:57:08.138283Z","steps":["trace[2122778635] 'process raft request' (duration: 511.860563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:08.138473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:07.625869Z","time spent":"512.509688ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:57:08.360702Z","caller":"traceutil/trace.go:171","msg":"trace[222534052] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"117.02255ms","start":"2026-04-23T16:57:08.243658Z","end":"2026-04-23T16:57:08.360681Z","steps":["trace[222534052] 'read index received' (duration: 78.394018ms)","trace[222534052] 'applied index is now lower than readState.Index' (duration: 38.627632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:08.360824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.155632ms","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-23T16:57:08.360861Z","caller":"traceutil/trace.go:171","msg":"trace[1910508551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:388; }","duration":"117.233824ms","start":"2026-04-23T16:57:08.243617Z","end":"2026-04-23T16:57:08.360851Z","steps":["trace[1910508551] 'agreement among raft nodes before linearized reading' (duration: 117.149222ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:08.361137Z","caller":"traceutil/trace.go:171","msg":"trace[1898260063] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"212.985454ms","start":"2026-04-23T16:57:08.148119Z","end":"2026-04-23T16:57:08.361104Z","steps":["trace[1898260063] 'process raft request' (duration: 174.141628ms)","trace[1898260063] 'compare' (duration: 38.316394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:09.334419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.402074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-04-23T16:57:09.334483Z","caller":"traceutil/trace.go:171","msg":"trace[1282922562] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:400; }","duration":"112.519417ms","start":"2026-04-23T16:57:09.22195Z","end":"2026-04-23T16:57:09.334469Z","steps":["trace[1282922562] 'range keys from in-memory index tree' (duration: 112.289233ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:10.346634Z","caller":"traceutil/trace.go:171","msg":"trace[2064801712] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"166.005434ms","start":"2026-04-23T16:57:10.180606Z","end":"2026-04-23T16:57:10.346612Z","steps":["trace[2064801712] 'process raft request' (duration: 165.84986ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:25.374695Z","caller":"traceutil/trace.go:171","msg":"trace[770065841] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"499.04923ms","start":"2026-04-23T16:57:24.875621Z","end":"2026-04-23T16:57:25.374671Z","steps":["trace[770065841] 'process raft request' (duration: 498.916086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.374867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:24.87561Z","time spent":"499.168863ms","remote":"127.0.0.1:50874","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-23T16:57:25.689948Z","caller":"traceutil/trace.go:171","msg":"trace[841168232] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"636.538942ms","start":"2026-04-23T16:57:25.053377Z","end":"2026-04-23T16:57:25.689916Z","steps":["trace[841168232] 'process raft request' (duration: 636.318525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.690082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.053369Z","time spent":"636.650494ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:25.690289Z","caller":"traceutil/trace.go:171","msg":"trace[1660230322] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"650.342283ms","start":"2026-04-23T16:57:25.039932Z","end":"2026-04-23T16:57:25.690275Z","steps":["trace[1660230322] 'process raft request' (duration: 649.5181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.690379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.039904Z","time spent":"650.416697ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:25.690381Z","caller":"traceutil/trace.go:171","msg":"trace[1841173369] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:520; }","duration":"549.362111ms","start":"2026-04-23T16:57:25.140995Z","end":"2026-04-23T16:57:25.690357Z","steps":["trace[1841173369] 'read index received' (duration: 233.653319ms)","trace[1841173369] 'applied index is now lower than readState.Index' (duration: 315.706052ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:25.690533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.521585ms","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-23T16:57:25.690569Z","caller":"traceutil/trace.go:171","msg":"trace[1781310026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"549.598778ms","start":"2026-04-23T16:57:25.140962Z","end":"2026-04-23T16:57:25.690561Z","steps":["trace[1781310026] 'agreement among raft nodes before linearized reading' (duration: 549.491314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.690617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.140948Z","time spent":"549.661599ms","remote":"127.0.0.1:50874","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-23T16:57:25.69076Z","caller":"traceutil/trace.go:171","msg":"trace[670498924] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"602.814622ms","start":"2026-04-23T16:57:25.087917Z","end":"2026-04-23T16:57:25.690732Z","steps":["trace[670498924] 'process raft request' (duration: 601.971857ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:25.690933Z","caller":"traceutil/trace.go:171","msg":"trace[88074658] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"645.339231ms","start":"2026-04-23T16:57:25.04558Z","end":"2026-04-23T16:57:25.69092Z","steps":["trace[88074658] 'process raft request' (duration: 644.069895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.691028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.087899Z","time spent":"602.978955ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:25.691095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.04556Z","time spent":"645.415234ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:25.691193Z","caller":"traceutil/trace.go:171","msg":"trace[729353131] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"551.10333ms","start":"2026-04-23T16:57:25.140081Z","end":"2026-04-23T16:57:25.691184Z","steps":["trace[729353131] 'process raft request' (duration: 550.0653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.691241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.140061Z","time spent":"551.159822ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:25.691284Z","caller":"traceutil/trace.go:171","msg":"trace[772331760] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"636.005466ms","start":"2026-04-23T16:57:25.055266Z","end":"2026-04-23T16:57:25.691271Z","steps":["trace[772331760] 'process raft request' (duration: 634.492533ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:25.691342Z","caller":"traceutil/trace.go:171","msg":"trace[1453754524] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"581.314169ms","start":"2026-04-23T16:57:25.11002Z","end":"2026-04-23T16:57:25.691335Z","steps":["trace[1453754524] 'process raft request' (duration: 580.018293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.691371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.055258Z","time spent":"636.084299ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:25.691486Z","caller":"traceutil/trace.go:171","msg":"trace[1075374308] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"608.00936ms","start":"2026-04-23T16:57:25.083469Z","end":"2026-04-23T16:57:25.691478Z","steps":["trace[1075374308] 'process raft request' (duration: 606.381303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.691565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.083451Z","time spent":"608.095022ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:25.69159Z","caller":"traceutil/trace.go:171","msg":"trace[1513924074] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"464.801276ms","start":"2026-04-23T16:57:25.226775Z","end":"2026-04-23T16:57:25.691576Z","steps":["trace[1513924074] 'process raft request' (duration: 463.524959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.691684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.226749Z","time spent":"464.907219ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:25.691402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.110011Z","time spent":"581.370951ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:25.691313Z","caller":"traceutil/trace.go:171","msg":"trace[505951035] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"587.265039ms","start":"2026-04-23T16:57:25.104039Z","end":"2026-04-23T16:57:25.691304Z","steps":["trace[505951035] 'process raft request' (duration: 585.927422ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:25.691698Z","caller":"traceutil/trace.go:171","msg":"trace[487226947] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"629.44674ms","start":"2026-04-23T16:57:25.062242Z","end":"2026-04-23T16:57:25.691689Z","steps":["trace[487226947] 'process raft request' (duration: 627.544596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.691829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.10402Z","time spent":"587.788254ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:57:25.69122Z","caller":"traceutil/trace.go:171","msg":"trace[737079942] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"636.999545ms","start":"2026-04-23T16:57:25.054209Z","end":"2026-04-23T16:57:25.691208Z","steps":["trace[737079942] 'process raft request' (duration: 635.520613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:25.69189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.062233Z","time spent":"629.599194ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:25.691928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.054203Z","time spent":"637.705945ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:26.563483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.518904ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:26.563591Z","caller":"traceutil/trace.go:171","msg":"trace[620335979] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:542; }","duration":"518.646747ms","start":"2026-04-23T16:57:26.044931Z","end":"2026-04-23T16:57:26.563578Z","steps":["trace[620335979] 'range keys from in-memory index tree' (duration: 518.501534ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:26.563694Z","caller":"traceutil/trace.go:171","msg":"trace[2090233815] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"712.988187ms","start":"2026-04-23T16:57:25.850667Z","end":"2026-04-23T16:57:26.563655Z","steps":["trace[2090233815] 'process raft request' (duration: 623.619993ms)","trace[2090233815] 'compare' (duration: 89.197259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:26.563853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.850638Z","time spent":"713.1306ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:57:26.567094Z","caller":"traceutil/trace.go:171","msg":"trace[1952376486] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"701.468998ms","start":"2026-04-23T16:57:25.86559Z","end":"2026-04-23T16:57:26.567059Z","steps":["trace[1952376486] 'process raft request' (duration: 701.128009ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:26.567175Z","caller":"traceutil/trace.go:171","msg":"trace[494304931] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"690.879368ms","start":"2026-04-23T16:57:25.876276Z","end":"2026-04-23T16:57:26.567156Z","steps":["trace[494304931] 'process raft request' (duration: 690.699483ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:26.567171Z","caller":"traceutil/trace.go:171","msg":"trace[1141875811] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:560; }","duration":"495.611922ms","start":"2026-04-23T16:57:26.071536Z","end":"2026-04-23T16:57:26.567148Z","steps":["trace[1141875811] 'read index received' (duration: 402.794021ms)","trace[1141875811] 'applied index is now lower than readState.Index' (duration: 92.816911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:26.567214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.865571Z","time spent":"701.580522ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:57:26.567244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:25.876266Z","time spent":"690.937869ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:26.567378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.030133ms","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-23T16:57:26.567393Z","caller":"traceutil/trace.go:171","msg":"trace[1797566018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"102.089325ms","start":"2026-04-23T16:57:26.465298Z","end":"2026-04-23T16:57:26.567387Z","steps":["trace[1797566018] 'agreement among raft nodes before linearized reading' (duration: 102.029903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:26.567387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.825038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:26.567489Z","caller":"traceutil/trace.go:171","msg":"trace[1652158429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"495.975661ms","start":"2026-04-23T16:57:26.071485Z","end":"2026-04-23T16:57:26.56746Z","steps":["trace[1652158429] 'agreement among raft nodes before linearized reading' (duration: 495.772286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:26.567591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:26.07147Z","time spent":"496.105416ms","remote":"127.0.0.1:50584","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-23T16:57:26.981227Z","caller":"traceutil/trace.go:171","msg":"trace[392815026] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"350.698219ms","start":"2026-04-23T16:57:26.630496Z","end":"2026-04-23T16:57:26.981194Z","steps":["trace[392815026] 'process raft request' (duration: 339.450408ms)","trace[392815026] 'compare' (duration: 10.933441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:26.981351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:26.630483Z","time spent":"350.806171ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:57:26.981368Z","caller":"traceutil/trace.go:171","msg":"trace[315993117] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"343.280598ms","start":"2026-04-23T16:57:26.63806Z","end":"2026-04-23T16:57:26.98134Z","steps":["trace[315993117] 'process raft request' (duration: 343.149244ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:26.981414Z","caller":"traceutil/trace.go:171","msg":"trace[1350263459] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"347.767036ms","start":"2026-04-23T16:57:26.63364Z","end":"2026-04-23T16:57:26.981407Z","steps":["trace[1350263459] 'process raft request' (duration: 347.443656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:26.981515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:26.633622Z","time spent":"347.876568ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:57:26.981522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:26.638044Z","time spent":"343.39665ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:57:27.200341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.276146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:57:27.200435Z","caller":"traceutil/trace.go:171","msg":"trace[521884116] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"194.849114ms","start":"2026-04-23T16:57:27.005571Z","end":"2026-04-23T16:57:27.20042Z","steps":["trace[521884116] 'process raft request' (duration: 77.424453ms)","trace[521884116] 'compare' (duration: 117.138403ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:27.288785Z","caller":"traceutil/trace.go:171","msg":"trace[2053714974] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"250.94279ms","start":"2026-04-23T16:57:27.037819Z","end":"2026-04-23T16:57:27.288762Z","steps":["trace[2053714974] 'process raft request' (duration: 250.902089ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:27.28892Z","caller":"traceutil/trace.go:171","msg":"trace[1736140645] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"272.979208ms","start":"2026-04-23T16:57:27.015885Z","end":"2026-04-23T16:57:27.288865Z","steps":["trace[1736140645] 'process raft request' (duration: 272.611507ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:27.3402Z","caller":"traceutil/trace.go:171","msg":"trace[326623567] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"110.425632ms","start":"2026-04-23T16:57:27.229748Z","end":"2026-04-23T16:57:27.340174Z","steps":["trace[326623567] 'process raft request' (duration: 109.27717ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:28.782214Z","caller":"traceutil/trace.go:171","msg":"trace[978064905] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"158.87141ms","start":"2026-04-23T16:57:28.623321Z","end":"2026-04-23T16:57:28.782193Z","steps":["trace[978064905] 'read index received' (duration: 158.665074ms)","trace[978064905] 'applied index is now lower than readState.Index' (duration: 205.586µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:28.782336Z","caller":"traceutil/trace.go:171","msg":"trace[1356781305] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"172.601912ms","start":"2026-04-23T16:57:28.609703Z","end":"2026-04-23T16:57:28.782305Z","steps":["trace[1356781305] 'process raft request' (duration: 172.343215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:28.782519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.181779ms","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-23T16:57:28.782563Z","caller":"traceutil/trace.go:171","msg":"trace[875344844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"159.260631ms","start":"2026-04-23T16:57:28.62329Z","end":"2026-04-23T16:57:28.782551Z","steps":["trace[875344844] 'agreement among raft nodes before linearized reading' (duration: 158.990164ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:28.911854Z","caller":"traceutil/trace.go:171","msg":"trace[584183642] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"125.051818ms","start":"2026-04-23T16:57:28.786777Z","end":"2026-04-23T16:57:28.911829Z","steps":["trace[584183642] 'process raft request' (duration: 68.798137ms)","trace[584183642] 'compare' (duration: 56.127507ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:30.15732Z","caller":"traceutil/trace.go:171","msg":"trace[183717838] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"206.137615ms","start":"2026-04-23T16:57:29.951165Z","end":"2026-04-23T16:57:30.157303Z","steps":["trace[183717838] 'process raft request' (duration: 197.169729ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:30.159283Z","caller":"traceutil/trace.go:171","msg":"trace[87332189] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"207.581807ms","start":"2026-04-23T16:57:29.951671Z","end":"2026-04-23T16:57:30.159252Z","steps":["trace[87332189] 'process raft request' (duration: 207.425522ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:30.45182Z","caller":"traceutil/trace.go:171","msg":"trace[349559354] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"110.729901ms","start":"2026-04-23T16:57:30.341064Z","end":"2026-04-23T16:57:30.451794Z","steps":["trace[349559354] 'process raft request' (duration: 109.948499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:30.583154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T16:57:30.583256Z","caller":"traceutil/trace.go:171","msg":"trace[931552763] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:593; }","duration":"109.435503ms","start":"2026-04-23T16:57:30.473799Z","end":"2026-04-23T16:57:30.583235Z","steps":["trace[931552763] 'range keys from in-memory index tree' (duration: 109.227128ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:31.040157Z","caller":"traceutil/trace.go:171","msg":"trace[2040861123] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"253.201174ms","start":"2026-04-23T16:57:30.786925Z","end":"2026-04-23T16:57:31.040126Z","steps":["trace[2040861123] 'read index received' (duration: 253.015528ms)","trace[2040861123] 'applied index is now lower than readState.Index' (duration: 184.416µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:31.040218Z","caller":"traceutil/trace.go:171","msg":"trace[247513025] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"428.624766ms","start":"2026-04-23T16:57:30.611542Z","end":"2026-04-23T16:57:31.040166Z","steps":["trace[247513025] 'process raft request' (duration: 428.344438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:31.040545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.595356ms","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-23T16:57:31.040639Z","caller":"traceutil/trace.go:171","msg":"trace[1970258584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"253.705338ms","start":"2026-04-23T16:57:30.78691Z","end":"2026-04-23T16:57:31.040615Z","steps":["trace[1970258584] 'agreement among raft nodes before linearized reading' (duration: 253.352728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:31.040649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:30.611499Z","time spent":"428.986655ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:57:31.224907Z","caller":"traceutil/trace.go:171","msg":"trace[141751000] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"184.481849ms","start":"2026-04-23T16:57:31.040395Z","end":"2026-04-23T16:57:31.224877Z","steps":["trace[141751000] 'read index received' (duration: 158.535851ms)","trace[141751000] 'applied index is now lower than readState.Index' (duration: 25.945008ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:31.225038Z","caller":"traceutil/trace.go:171","msg":"trace[2067662242] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"250.747574ms","start":"2026-04-23T16:57:30.974244Z","end":"2026-04-23T16:57:31.224992Z","steps":["trace[2067662242] 'process raft request' (duration: 224.778615ms)","trace[2067662242] 'compare' (duration: 25.475055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:31.225253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.969421ms","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-23T16:57:31.225351Z","caller":"traceutil/trace.go:171","msg":"trace[261322715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"309.120924ms","start":"2026-04-23T16:57:30.916205Z","end":"2026-04-23T16:57:31.225326Z","steps":["trace[261322715] 'agreement among raft nodes before linearized reading' (duration: 308.834996ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:31.225438Z","caller":"traceutil/trace.go:171","msg":"trace[1349583208] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"180.146825ms","start":"2026-04-23T16:57:31.045276Z","end":"2026-04-23T16:57:31.225423Z","steps":["trace[1349583208] 'process raft request' (duration: 179.930019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:31.225896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.952695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-23T16:57:31.225979Z","caller":"traceutil/trace.go:171","msg":"trace[1531546046] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:596; }","duration":"183.075939ms","start":"2026-04-23T16:57:31.042891Z","end":"2026-04-23T16:57:31.225967Z","steps":["trace[1531546046] 'agreement among raft nodes before linearized reading' (duration: 182.386669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:31.225408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:30.916181Z","time spent":"309.216699ms","remote":"127.0.0.1:50874","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-23T16:57:34.824361Z","caller":"traceutil/trace.go:171","msg":"trace[1644653747] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"236.86033ms","start":"2026-04-23T16:57:34.587478Z","end":"2026-04-23T16:57:34.824338Z","steps":["trace[1644653747] 'process raft request' (duration: 236.713905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.967971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.653276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.968043Z","caller":"traceutil/trace.go:171","msg":"trace[2048552121] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:633; }","duration":"195.780461ms","start":"2026-04-23T16:57:35.772248Z","end":"2026-04-23T16:57:35.968028Z","steps":["trace[2048552121] 'count revisions from in-memory index tree' (duration: 195.575974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.96824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.070498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:57:35.968281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.053868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.968303Z","caller":"traceutil/trace.go:171","msg":"trace[745430157] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:633; }","duration":"196.085699ms","start":"2026-04-23T16:57:35.772211Z","end":"2026-04-23T16:57:35.968297Z","steps":["trace[745430157] 'range keys from in-memory index tree' (duration: 196.035217ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:35.9683Z","caller":"traceutil/trace.go:171","msg":"trace[965783438] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:633; }","duration":"196.161012ms","start":"2026-04-23T16:57:35.772122Z","end":"2026-04-23T16:57:35.968283Z","steps":["trace[965783438] 'range keys from in-memory index tree' (duration: 196.016097ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:35.97216Z","caller":"traceutil/trace.go:171","msg":"trace[1390473473] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"186.702673ms","start":"2026-04-23T16:57:35.785439Z","end":"2026-04-23T16:57:35.972142Z","steps":["trace[1390473473] 'read index received' (duration: 181.370271ms)","trace[1390473473] 'applied index is now lower than readState.Index' (duration: 5.331752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:57:35.972356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.750793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.972418Z","caller":"traceutil/trace.go:171","msg":"trace[100791731] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:633; }","duration":"181.801523ms","start":"2026-04-23T16:57:35.790572Z","end":"2026-04-23T16:57:35.972374Z","steps":["trace[100791731] 'agreement among raft nodes before linearized reading' (duration: 181.762712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.972805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.165329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:57:35.972791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.120994ms","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-23T16:57:35.972825Z","caller":"traceutil/trace.go:171","msg":"trace[71803956] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:633; }","duration":"159.21309ms","start":"2026-04-23T16:57:35.813607Z","end":"2026-04-23T16:57:35.97282Z","steps":["trace[71803956] 'agreement among raft nodes before linearized reading' (duration: 159.182749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.972808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.478294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.972859Z","caller":"traceutil/trace.go:171","msg":"trace[1930615753] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:633; }","duration":"155.571926ms","start":"2026-04-23T16:57:35.817273Z","end":"2026-04-23T16:57:35.972845Z","steps":["trace[1930615753] 'agreement among raft nodes before linearized reading' (duration: 155.478954ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:35.972853Z","caller":"traceutil/trace.go:171","msg":"trace[1212181254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"187.457024ms","start":"2026-04-23T16:57:35.785379Z","end":"2026-04-23T16:57:35.972836Z","steps":["trace[1212181254] 'agreement among raft nodes before linearized reading' (duration: 186.872167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.972906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.439147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.972921Z","caller":"traceutil/trace.go:171","msg":"trace[2116747221] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:633; }","duration":"159.454707ms","start":"2026-04-23T16:57:35.813461Z","end":"2026-04-23T16:57:35.972916Z","steps":["trace[2116747221] 'agreement among raft nodes before linearized reading' (duration: 159.428676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.972965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.922627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.972977Z","caller":"traceutil/trace.go:171","msg":"trace[105311390] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:633; }","duration":"181.950138ms","start":"2026-04-23T16:57:35.791022Z","end":"2026-04-23T16:57:35.972973Z","steps":["trace[105311390] 'agreement among raft nodes before linearized reading' (duration: 181.931218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.973039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.304597ms","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-23T16:57:35.973052Z","caller":"traceutil/trace.go:171","msg":"trace[975787636] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:633; }","duration":"182.331688ms","start":"2026-04-23T16:57:35.790715Z","end":"2026-04-23T16:57:35.973047Z","steps":["trace[975787636] 'agreement among raft nodes before linearized reading' (duration: 182.311778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.973279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.96413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.973299Z","caller":"traceutil/trace.go:171","msg":"trace[192805046] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:633; }","duration":"134.993801ms","start":"2026-04-23T16:57:35.838297Z","end":"2026-04-23T16:57:35.973291Z","steps":["trace[192805046] 'agreement among raft nodes before linearized reading' (duration: 134.96728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.973326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.67134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.973343Z","caller":"traceutil/trace.go:171","msg":"trace[1952658144] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:633; }","duration":"135.700931ms","start":"2026-04-23T16:57:35.837637Z","end":"2026-04-23T16:57:35.973338Z","steps":["trace[1952658144] 'agreement among raft nodes before linearized reading' (duration: 135.67258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:35.973598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.99829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:57:35.973611Z","caller":"traceutil/trace.go:171","msg":"trace[319308510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:633; }","duration":"136.01176ms","start":"2026-04-23T16:57:35.837595Z","end":"2026-04-23T16:57:35.973607Z","steps":["trace[319308510] 'agreement among raft nodes before linearized reading' (duration: 135.9914ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:39.910987Z","caller":"traceutil/trace.go:171","msg":"trace[2132924744] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"112.729017ms","start":"2026-04-23T16:57:39.798227Z","end":"2026-04-23T16:57:39.910956Z","steps":["trace[2132924744] 'process raft request' (duration: 112.522411ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:48.554721Z","caller":"traceutil/trace.go:171","msg":"trace[49864424] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"196.900623ms","start":"2026-04-23T16:57:48.357793Z","end":"2026-04-23T16:57:48.554693Z","steps":["trace[49864424] 'process raft request' (duration: 196.686957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:51.570948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325096150083749,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:57:51.804372Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.138583556s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T16:57:51.804757Z","caller":"traceutil/trace.go:171","msg":"trace[1092584228] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"734.065116ms","start":"2026-04-23T16:57:51.070669Z","end":"2026-04-23T16:57:51.804734Z","steps":["trace[1092584228] 'read index received' (duration: 733.85292ms)","trace[1092584228] 'applied index is now lower than readState.Index' (duration: 211.426µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:57:51.804835Z","caller":"traceutil/trace.go:171","msg":"trace[62657557] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"1.139129541s","start":"2026-04-23T16:57:50.665696Z","end":"2026-04-23T16:57:51.804826Z","steps":["trace[62657557] 'process raft request' (duration: 1.138887884s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:51.804961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:50.665674Z","time spent":"1.139195223s","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:57:51.805125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.448677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:57:51.80516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.275616ms","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-23T16:57:51.805188Z","caller":"traceutil/trace.go:171","msg":"trace[1668757476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"734.54517ms","start":"2026-04-23T16:57:51.070628Z","end":"2026-04-23T16:57:51.805173Z","steps":["trace[1668757476] 'agreement among raft nodes before linearized reading' (duration: 734.454238ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:57:51.8052Z","caller":"traceutil/trace.go:171","msg":"trace[405145122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"266.342378ms","start":"2026-04-23T16:57:51.538846Z","end":"2026-04-23T16:57:51.805189Z","steps":["trace[405145122] 'agreement among raft nodes before linearized reading' (duration: 266.202054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:51.805223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:51.070607Z","time spent":"734.611022ms","remote":"127.0.0.1:50584","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-23T16:57:51.805106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.89543ms","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-23T16:57:51.805523Z","caller":"traceutil/trace.go:171","msg":"trace[1361545501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"437.329974ms","start":"2026-04-23T16:57:51.368167Z","end":"2026-04-23T16:57:51.805497Z","steps":["trace[1361545501] 'agreement among raft nodes before linearized reading' (duration: 436.729646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:51.805654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:51.368124Z","time spent":"437.501528ms","remote":"127.0.0.1:50874","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-23T16:57:51.80537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.546718ms","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-23T16:57:51.805906Z","caller":"traceutil/trace.go:171","msg":"trace[1216106604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"387.088493ms","start":"2026-04-23T16:57:51.418736Z","end":"2026-04-23T16:57:51.805825Z","steps":["trace[1216106604] 'agreement among raft nodes before linearized reading' (duration: 386.328171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:57:51.806008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:57:51.418726Z","time spent":"387.260268ms","remote":"127.0.0.1:50874","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-23T16:59:39.282058Z","caller":"traceutil/trace.go:171","msg":"trace[2050773432] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"160.401153ms","start":"2026-04-23T16:59:39.121631Z","end":"2026-04-23T16:59:39.282032Z","steps":["trace[2050773432] 'process raft request' (duration: 112.851484ms)","trace[2050773432] 'compare' (duration: 47.363864ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:00:35.02263Z","caller":"traceutil/trace.go:171","msg":"trace[1370408843] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"128.784735ms","start":"2026-04-23T17:00:34.893826Z","end":"2026-04-23T17:00:35.02261Z","steps":["trace[1370408843] 'process raft request' (duration: 128.658112ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:00:40.786858Z","caller":"traceutil/trace.go:171","msg":"trace[1182402240] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"178.097865ms","start":"2026-04-23T17:00:40.608433Z","end":"2026-04-23T17:00:40.786531Z","steps":["trace[1182402240] 'process raft request' (duration: 177.88472ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:00:40.927289Z","caller":"traceutil/trace.go:171","msg":"trace[1645379479] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"135.830268ms","start":"2026-04-23T17:00:40.791426Z","end":"2026-04-23T17:00:40.927256Z","steps":["trace[1645379479] 'process raft request' (duration: 120.817528ms)","trace[1645379479] 'compare' (duration: 14.887126ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:00:40.93011Z","caller":"traceutil/trace.go:171","msg":"trace[1813930247] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"138.139757ms","start":"2026-04-23T17:00:40.791764Z","end":"2026-04-23T17:00:40.929904Z","steps":["trace[1813930247] 'process raft request' (duration: 138.005224ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:00:40.930289Z","caller":"traceutil/trace.go:171","msg":"trace[1378532437] linearizableReadLoop","detail":"{readStateIndex:1499; appliedIndex:1496; }","duration":"137.46809ms","start":"2026-04-23T17:00:40.79281Z","end":"2026-04-23T17:00:40.930278Z","steps":["trace[1378532437] 'read index received' (duration: 119.430842ms)","trace[1378532437] 'applied index is now lower than readState.Index' (duration: 18.035868ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:00:40.930378Z","caller":"traceutil/trace.go:171","msg":"trace[470471811] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"138.575408ms","start":"2026-04-23T17:00:40.791788Z","end":"2026-04-23T17:00:40.930363Z","steps":["trace[470471811] 'process raft request' (duration: 138.082476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:00:40.93054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.711607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:00:40.930614Z","caller":"traceutil/trace.go:171","msg":"trace[928421752] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1439; }","duration":"137.802019ms","start":"2026-04-23T17:00:40.792794Z","end":"2026-04-23T17:00:40.930596Z","steps":["trace[928421752] 'agreement among raft nodes before linearized reading' (duration: 137.670755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:00:59.827585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.90313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:00:59.827728Z","caller":"traceutil/trace.go:171","msg":"trace[1283299517] linearizableReadLoop","detail":"{readStateIndex:1564; appliedIndex:1563; }","duration":"131.301889ms","start":"2026-04-23T17:00:59.696386Z","end":"2026-04-23T17:00:59.827688Z","steps":["trace[1283299517] 'read index received' (duration: 25.120652ms)","trace[1283299517] 'applied index is now lower than readState.Index' (duration: 106.180077ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:00:59.827772Z","caller":"traceutil/trace.go:171","msg":"trace[1183900961] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"172.13799ms","start":"2026-04-23T17:00:59.655601Z","end":"2026-04-23T17:00:59.827739Z","steps":["trace[1183900961] 'process raft request' (duration: 65.964614ms)","trace[1183900961] 'compare' (duration: 105.764286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:00:59.827803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.419292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:00:59.82784Z","caller":"traceutil/trace.go:171","msg":"trace[780081506] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1501; }","duration":"131.499003ms","start":"2026-04-23T17:00:59.696329Z","end":"2026-04-23T17:00:59.827828Z","steps":["trace[780081506] 'agreement among raft nodes before linearized reading' (duration: 131.429642ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:01.335788Z","caller":"traceutil/trace.go:171","msg":"trace[726243721] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"146.894294ms","start":"2026-04-23T17:01:01.18887Z","end":"2026-04-23T17:01:01.335765Z","steps":["trace[726243721] 'process raft request' (duration: 146.71816ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:13.246103Z","caller":"traceutil/trace.go:171","msg":"trace[1837547967] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"111.68359ms","start":"2026-04-23T17:01:13.134391Z","end":"2026-04-23T17:01:13.246074Z","steps":["trace[1837547967] 'process raft request' (duration: 95.4977ms)","trace[1837547967] 'compare' (duration: 16.073397ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:01:13.249514Z","caller":"traceutil/trace.go:171","msg":"trace[1031127507] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"113.707213ms","start":"2026-04-23T17:01:13.13579Z","end":"2026-04-23T17:01:13.249498Z","steps":["trace[1031127507] 'process raft request' (duration: 113.61824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:14.474736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.087183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T17:01:14.474811Z","caller":"traceutil/trace.go:171","msg":"trace[1509015217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1545; }","duration":"826.199346ms","start":"2026-04-23T17:01:13.648593Z","end":"2026-04-23T17:01:14.474792Z","steps":["trace[1509015217] 'range keys from in-memory index tree' (duration: 825.919518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:14.474851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:13.648576Z","time spent":"826.264547ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:01:14.569675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325096150085665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:01:15.070815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325096150085665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:01:15.121308Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.203187156s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T17:01:15.1216Z","caller":"traceutil/trace.go:171","msg":"trace[2138578617] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"1.203557944s","start":"2026-04-23T17:01:13.918019Z","end":"2026-04-23T17:01:15.121577Z","steps":["trace[2138578617] 'process raft request' (duration: 1.203452542s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:15.121709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:13.917998Z","time spent":"1.203642317s","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:01:15.149111Z","caller":"traceutil/trace.go:171","msg":"trace[471620056] linearizableReadLoop","detail":"{readStateIndex:1614; appliedIndex:1612; }","duration":"1.080213001s","start":"2026-04-23T17:01:14.06888Z","end":"2026-04-23T17:01:15.149093Z","steps":["trace[471620056] 'read index received' (duration: 1.052678686s)","trace[471620056] 'applied index is now lower than readState.Index' (duration: 27.533195ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:01:15.149141Z","caller":"traceutil/trace.go:171","msg":"trace[1363279740] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"673.755626ms","start":"2026-04-23T17:01:14.475362Z","end":"2026-04-23T17:01:15.149118Z","steps":["trace[1363279740] 'process raft request' (duration: 673.592902ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:15.149201Z","caller":"traceutil/trace.go:171","msg":"trace[166971465] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"670.615855ms","start":"2026-04-23T17:01:14.478575Z","end":"2026-04-23T17:01:15.149191Z","steps":["trace[166971465] 'process raft request' (duration: 670.472321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:15.149244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:14.475342Z","time spent":"673.842249ms","remote":"127.0.0.1:50874","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-23T17:01:15.149273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:14.478562Z","time spent":"670.661016ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:01:15.149459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08058054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:01:15.149494Z","caller":"traceutil/trace.go:171","msg":"trace[432378409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1548; }","duration":"1.080641202s","start":"2026-04-23T17:01:14.068843Z","end":"2026-04-23T17:01:15.149484Z","steps":["trace[432378409] 'agreement among raft nodes before linearized reading' (duration: 1.080593761s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:15.149521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:14.068827Z","time spent":"1.080687203s","remote":"127.0.0.1:50584","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-23T17:01:37.688541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.138718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:01:37.688628Z","caller":"traceutil/trace.go:171","msg":"trace[809263303] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1624; }","duration":"113.254101ms","start":"2026-04-23T17:01:37.575355Z","end":"2026-04-23T17:01:37.688609Z","steps":["trace[809263303] 'count revisions from in-memory index tree' (duration: 113.026875ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:39.339384Z","caller":"traceutil/trace.go:171","msg":"trace[372385666] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"108.883157ms","start":"2026-04-23T17:01:39.230461Z","end":"2026-04-23T17:01:39.339344Z","steps":["trace[372385666] 'process raft request' (duration: 107.224726ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:39.507047Z","caller":"traceutil/trace.go:171","msg":"trace[1553411008] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"163.2941ms","start":"2026-04-23T17:01:39.343724Z","end":"2026-04-23T17:01:39.507018Z","steps":["trace[1553411008] 'process raft request' (duration: 158.794497ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:39.528555Z","caller":"traceutil/trace.go:171","msg":"trace[1435728217] linearizableReadLoop","detail":"{readStateIndex:1699; appliedIndex:1697; }","duration":"150.701642ms","start":"2026-04-23T17:01:39.377829Z","end":"2026-04-23T17:01:39.528531Z","steps":["trace[1435728217] 'read index received' (duration: 124.689106ms)","trace[1435728217] 'applied index is now lower than readState.Index' (duration: 26.011186ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:01:39.528821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.99142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T17:01:39.528851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.995251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T17:01:39.528879Z","caller":"traceutil/trace.go:171","msg":"trace[132914670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1628; }","duration":"151.121473ms","start":"2026-04-23T17:01:39.377744Z","end":"2026-04-23T17:01:39.528865Z","steps":["trace[132914670] 'agreement among raft nodes before linearized reading' (duration: 150.908377ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:39.528891Z","caller":"traceutil/trace.go:171","msg":"trace[431232279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1628; }","duration":"136.099474ms","start":"2026-04-23T17:01:39.39278Z","end":"2026-04-23T17:01:39.528879Z","steps":["trace[431232279] 'agreement among raft nodes before linearized reading' (duration: 135.889618ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:39.528848Z","caller":"traceutil/trace.go:171","msg":"trace[1338276646] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"184.849925ms","start":"2026-04-23T17:01:39.343953Z","end":"2026-04-23T17:01:39.528802Z","steps":["trace[1338276646] 'process raft request' (duration: 184.408613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:40.806306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:40.488638Z","time spent":"317.664027ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T17:01:41.475106Z","caller":"traceutil/trace.go:171","msg":"trace[1940770837] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"130.191396ms","start":"2026-04-23T17:01:41.344886Z","end":"2026-04-23T17:01:41.475077Z","steps":["trace[1940770837] 'process raft request' (duration: 130.046612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:41.768142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.141174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:01:41.768227Z","caller":"traceutil/trace.go:171","msg":"trace[1192141266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1633; }","duration":"235.281278ms","start":"2026-04-23T17:01:41.532926Z","end":"2026-04-23T17:01:41.768207Z","steps":["trace[1192141266] 'range keys from in-memory index tree' (duration: 234.931138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:41.768441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.403781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T17:01:41.768701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.013951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T17:01:41.768765Z","caller":"traceutil/trace.go:171","msg":"trace[1893804586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1633; }","duration":"193.76739ms","start":"2026-04-23T17:01:41.574977Z","end":"2026-04-23T17:01:41.768744Z","steps":["trace[1893804586] 'range keys from in-memory index tree' (duration: 193.243437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:41.768741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.779559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T17:01:41.768863Z","caller":"traceutil/trace.go:171","msg":"trace[1961173254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1633; }","duration":"258.154515ms","start":"2026-04-23T17:01:41.510607Z","end":"2026-04-23T17:01:41.768761Z","steps":["trace[1961173254] 'range keys from in-memory index tree' (duration: 257.670883ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:41.768962Z","caller":"traceutil/trace.go:171","msg":"trace[394013280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1633; }","duration":"194.089548ms","start":"2026-04-23T17:01:41.574823Z","end":"2026-04-23T17:01:41.768913Z","steps":["trace[394013280] 'range keys from in-memory index tree' (duration: 193.387321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:41.768459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.820434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:01:41.769501Z","caller":"traceutil/trace.go:171","msg":"trace[1354813315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1633; }","duration":"206.721347ms","start":"2026-04-23T17:01:41.562572Z","end":"2026-04-23T17:01:41.769293Z","steps":["trace[1354813315] 'range keys from in-memory index tree' (duration: 205.664009ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:54.013193Z","caller":"traceutil/trace.go:171","msg":"trace[988419938] linearizableReadLoop","detail":"{readStateIndex:1801; appliedIndex:1800; }","duration":"110.266193ms","start":"2026-04-23T17:01:53.902909Z","end":"2026-04-23T17:01:54.013176Z","steps":["trace[988419938] 'read index received' (duration: 110.14456ms)","trace[988419938] 'applied index is now lower than readState.Index' (duration: 120.813µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:01:54.013237Z","caller":"traceutil/trace.go:171","msg":"trace[154706111] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"162.86321ms","start":"2026-04-23T17:01:53.850343Z","end":"2026-04-23T17:01:54.013206Z","steps":["trace[154706111] 'process raft request' (duration: 162.664875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:54.013379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.460448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:01:54.013403Z","caller":"traceutil/trace.go:171","msg":"trace[1201852379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1724; }","duration":"110.535189ms","start":"2026-04-23T17:01:53.90286Z","end":"2026-04-23T17:01:54.013395Z","steps":["trace[1201852379] 'agreement among raft nodes before linearized reading' (duration: 110.413036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:54.013988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.264388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:01:54.014062Z","caller":"traceutil/trace.go:171","msg":"trace[211046431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1724; }","duration":"109.37453ms","start":"2026-04-23T17:01:53.904668Z","end":"2026-04-23T17:01:54.014043Z","steps":["trace[211046431] 'agreement among raft nodes before linearized reading' (duration: 108.659982ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:55.80057Z","caller":"traceutil/trace.go:171","msg":"trace[553837014] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"262.822152ms","start":"2026-04-23T17:01:55.537724Z","end":"2026-04-23T17:01:55.800546Z","steps":["trace[553837014] 'process raft request' (duration: 262.688669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:56.530479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.586675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:01:56.530538Z","caller":"traceutil/trace.go:171","msg":"trace[204667939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1729; }","duration":"512.689298ms","start":"2026-04-23T17:01:56.017835Z","end":"2026-04-23T17:01:56.530524Z","steps":["trace[204667939] 'range keys from in-memory index tree' (duration: 512.37271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:56.530567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:56.017819Z","time spent":"512.741868ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T17:01:56.530905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.141768ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:01:56.531031Z","caller":"traceutil/trace.go:171","msg":"trace[301409712] linearizableReadLoop","detail":"{readStateIndex:1807; appliedIndex:1806; }","duration":"472.517004ms","start":"2026-04-23T17:01:56.058476Z","end":"2026-04-23T17:01:56.530993Z","steps":["trace[301409712] 'read index received' (duration: 470.575285ms)","trace[301409712] 'applied index is now lower than readState.Index' (duration: 1.940329ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:01:56.531056Z","caller":"traceutil/trace.go:171","msg":"trace[20282273] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1729; }","duration":"486.326553ms","start":"2026-04-23T17:01:56.044703Z","end":"2026-04-23T17:01:56.53103Z","steps":["trace[20282273] 'range keys from in-memory index tree' (duration: 486.111618ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:56.531136Z","caller":"traceutil/trace.go:171","msg":"trace[1296359915] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"478.786442ms","start":"2026-04-23T17:01:56.052331Z","end":"2026-04-23T17:01:56.531117Z","steps":["trace[1296359915] 'process raft request' (duration: 476.70885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:56.531203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:56.052304Z","time spent":"478.852305ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:01:56.531297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.80594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T17:01:56.531332Z","caller":"traceutil/trace.go:171","msg":"trace[289447942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1730; }","duration":"472.856092ms","start":"2026-04-23T17:01:56.058464Z","end":"2026-04-23T17:01:56.53132Z","steps":["trace[289447942] 'agreement among raft nodes before linearized reading' (duration: 472.659577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:56.531368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:56.058432Z","time spent":"472.927234ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:01:56.551564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.775148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:01:56.551611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.009715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:01:56.551657Z","caller":"traceutil/trace.go:171","msg":"trace[250692180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1730; }","duration":"483.894641ms","start":"2026-04-23T17:01:56.067744Z","end":"2026-04-23T17:01:56.551638Z","steps":["trace[250692180] 'agreement among raft nodes before linearized reading' (duration: 483.726807ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:56.551663Z","caller":"traceutil/trace.go:171","msg":"trace[1574833419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1730; }","duration":"492.063537ms","start":"2026-04-23T17:01:56.059585Z","end":"2026-04-23T17:01:56.551649Z","steps":["trace[1574833419] 'agreement among raft nodes before linearized reading' (duration: 491.902733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:56.5517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:56.059544Z","time spent":"492.14911ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T17:01:56.551702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:56.067736Z","time spent":"483.959284ms","remote":"127.0.0.1:50584","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-23T17:01:56.551721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.87711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:01:56.55179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.329234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:01:56.551814Z","caller":"traceutil/trace.go:171","msg":"trace[1890120720] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1730; }","duration":"241.058884ms","start":"2026-04-23T17:01:56.310732Z","end":"2026-04-23T17:01:56.551791Z","steps":["trace[1890120720] 'agreement among raft nodes before linearized reading' (duration: 240.879789ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:01:56.551833Z","caller":"traceutil/trace.go:171","msg":"trace[1232120339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1730; }","duration":"492.414587ms","start":"2026-04-23T17:01:56.059408Z","end":"2026-04-23T17:01:56.551823Z","steps":["trace[1232120339] 'agreement among raft nodes before linearized reading' (duration: 492.251622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:56.55186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:56.059389Z","time spent":"492.465988ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T17:01:56.551858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.506889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T17:01:56.551917Z","caller":"traceutil/trace.go:171","msg":"trace[1508714820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1730; }","duration":"492.58528ms","start":"2026-04-23T17:01:56.059315Z","end":"2026-04-23T17:01:56.551901Z","steps":["trace[1508714820] 'agreement among raft nodes before linearized reading' (duration: 492.378456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:01:56.551955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:01:56.05931Z","time spent":"492.634412ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T17:02:08.77403Z","caller":"traceutil/trace.go:171","msg":"trace[1268897089] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"106.387114ms","start":"2026-04-23T17:02:08.667619Z","end":"2026-04-23T17:02:08.774006Z","steps":["trace[1268897089] 'process raft request' (duration: 96.883124ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:08.790311Z","caller":"traceutil/trace.go:171","msg":"trace[1467360142] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"120.87461ms","start":"2026-04-23T17:02:08.669411Z","end":"2026-04-23T17:02:08.790286Z","steps":["trace[1467360142] 'process raft request' (duration: 120.738647ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:09.818767Z","caller":"traceutil/trace.go:171","msg":"trace[140941747] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"164.467491ms","start":"2026-04-23T17:02:09.654273Z","end":"2026-04-23T17:02:09.818741Z","steps":["trace[140941747] 'process raft request' (duration: 164.289857ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:10.158938Z","caller":"traceutil/trace.go:171","msg":"trace[601693420] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"276.692903ms","start":"2026-04-23T17:02:09.882213Z","end":"2026-04-23T17:02:10.158906Z","steps":["trace[601693420] 'process raft request' (duration: 250.977784ms)","trace[601693420] 'compare' (duration: 25.280178ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:02:15.222421Z","caller":"traceutil/trace.go:171","msg":"trace[888905000] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"411.224328ms","start":"2026-04-23T17:02:14.811173Z","end":"2026-04-23T17:02:15.222397Z","steps":["trace[888905000] 'read index received' (duration: 401.448181ms)","trace[888905000] 'applied index is now lower than readState.Index' (duration: 9.775257ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:02:15.222552Z","caller":"traceutil/trace.go:171","msg":"trace[463324667] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"420.301777ms","start":"2026-04-23T17:02:14.802239Z","end":"2026-04-23T17:02:15.222541Z","steps":["trace[463324667] 'process raft request' (duration: 410.487848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:15.222659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:14.802207Z","time spent":"420.371009ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:15.223061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.810792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:02:15.223142Z","caller":"traceutil/trace.go:171","msg":"trace[2146718820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1841; }","duration":"412.020537ms","start":"2026-04-23T17:02:14.811104Z","end":"2026-04-23T17:02:15.223125Z","steps":["trace[2146718820] 'agreement among raft nodes before linearized reading' (duration: 411.579806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:15.22318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:14.81108Z","time spent":"412.09196ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T17:02:15.223578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.419656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:02:15.223629Z","caller":"traceutil/trace.go:171","msg":"trace[102861645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1841; }","duration":"152.52333ms","start":"2026-04-23T17:02:15.071095Z","end":"2026-04-23T17:02:15.223618Z","steps":["trace[102861645] 'agreement among raft nodes before linearized reading' (duration: 151.592126ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:16.295505Z","caller":"traceutil/trace.go:171","msg":"trace[1801410895] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"113.861453ms","start":"2026-04-23T17:02:16.181619Z","end":"2026-04-23T17:02:16.29548Z","steps":["trace[1801410895] 'process raft request' (duration: 113.609897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.082859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.454536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:02:17.082942Z","caller":"traceutil/trace.go:171","msg":"trace[402062023] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1843; }","duration":"721.59263ms","start":"2026-04-23T17:02:16.361332Z","end":"2026-04-23T17:02:17.082924Z","steps":["trace[402062023] 'count revisions from in-memory index tree' (duration: 721.368223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.082931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.364727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T17:02:17.08297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.166457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-23T17:02:17.082984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:16.361312Z","time spent":"721.662271ms","remote":"127.0.0.1:50986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-04-23T17:02:17.083022Z","caller":"traceutil/trace.go:171","msg":"trace[643623938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1843; }","duration":"346.248799ms","start":"2026-04-23T17:02:16.73676Z","end":"2026-04-23T17:02:17.083009Z","steps":["trace[643623938] 'range keys from in-memory index tree' (duration: 346.069494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.083065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.775888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:02:17.083149Z","caller":"traceutil/trace.go:171","msg":"trace[138493013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1843; }","duration":"313.893262ms","start":"2026-04-23T17:02:16.769235Z","end":"2026-04-23T17:02:17.083128Z","steps":["trace[138493013] 'range keys from in-memory index tree' (duration: 313.633254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.083198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:16.76922Z","time spent":"313.968913ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T17:02:17.083093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:16.736743Z","time spent":"346.333161ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T17:02:17.083029Z","caller":"traceutil/trace.go:171","msg":"trace[1772740241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"414.46419ms","start":"2026-04-23T17:02:16.668534Z","end":"2026-04-23T17:02:17.082998Z","steps":["trace[1772740241] 'range keys from in-memory index tree' (duration: 414.192802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.083398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:16.668481Z","time spent":"414.90329ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T17:02:17.561223Z","caller":"traceutil/trace.go:171","msg":"trace[1119575813] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"547.629799ms","start":"2026-04-23T17:02:17.013551Z","end":"2026-04-23T17:02:17.561181Z","steps":["trace[1119575813] 'process raft request' (duration: 547.184669ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:17.561306Z","caller":"traceutil/trace.go:171","msg":"trace[1020417301] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1926; }","duration":"489.851802ms","start":"2026-04-23T17:02:17.071401Z","end":"2026-04-23T17:02:17.561252Z","steps":["trace[1020417301] 'read index received' (duration: 489.843992ms)","trace[1020417301] 'applied index is now lower than readState.Index' (duration: 6.59µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:02:17.561438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:17.013532Z","time spent":"547.799795ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1768,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:17.561449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.030867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:02:17.561618Z","caller":"traceutil/trace.go:171","msg":"trace[2134718247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1844; }","duration":"490.227161ms","start":"2026-04-23T17:02:17.071345Z","end":"2026-04-23T17:02:17.561572Z","steps":["trace[2134718247] 'agreement among raft nodes before linearized reading' (duration: 490.009286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.561781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:17.071324Z","time spent":"490.421265ms","remote":"127.0.0.1:50584","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-23T17:02:17.581236Z","caller":"traceutil/trace.go:171","msg":"trace[1172232330] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"493.848702ms","start":"2026-04-23T17:02:17.087366Z","end":"2026-04-23T17:02:17.581215Z","steps":["trace[1172232330] 'process raft request' (duration: 493.688568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.581592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:17.087339Z","time spent":"493.969445ms","remote":"127.0.0.1:50874","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-23T17:02:17.581604Z","caller":"traceutil/trace.go:171","msg":"trace[824404767] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"493.847201ms","start":"2026-04-23T17:02:17.087715Z","end":"2026-04-23T17:02:17.581562Z","steps":["trace[824404767] 'process raft request' (duration: 493.464762ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:17.581733Z","caller":"traceutil/trace.go:171","msg":"trace[408465416] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"432.69877ms","start":"2026-04-23T17:02:17.149022Z","end":"2026-04-23T17:02:17.581721Z","steps":["trace[408465416] 'process raft request' (duration: 432.600557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.581808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:17.149008Z","time spent":"432.766151ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:17.581859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:17.087694Z","time spent":"494.018067ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:02:17.581894Z","caller":"traceutil/trace.go:171","msg":"trace[821156814] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"494.125339ms","start":"2026-04-23T17:02:17.087758Z","end":"2026-04-23T17:02:17.581883Z","steps":["trace[821156814] 'process raft request' (duration: 493.678877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.58194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:17.087735Z","time spent":"494.179631ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:17.582043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.587993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-23T17:02:17.582061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.110076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:02:17.582127Z","caller":"traceutil/trace.go:171","msg":"trace[145650839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1848; }","duration":"310.245288ms","start":"2026-04-23T17:02:17.271868Z","end":"2026-04-23T17:02:17.582113Z","steps":["trace[145650839] 'agreement among raft nodes before linearized reading' (duration: 310.062824ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:17.582128Z","caller":"traceutil/trace.go:171","msg":"trace[1084651674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1848; }","duration":"355.716766ms","start":"2026-04-23T17:02:17.226386Z","end":"2026-04-23T17:02:17.582103Z","steps":["trace[1084651674] 'agreement among raft nodes before linearized reading' (duration: 355.558282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:17.58216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:17.271844Z","time spent":"310.30923ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T17:02:17.582176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:17.226366Z","time spent":"355.798429ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T17:02:18.527754Z","caller":"traceutil/trace.go:171","msg":"trace[127839981] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"225.216013ms","start":"2026-04-23T17:02:18.302506Z","end":"2026-04-23T17:02:18.527722Z","steps":["trace[127839981] 'process raft request' (duration: 225.05126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:19.129584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.667977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:02:19.12966Z","caller":"traceutil/trace.go:171","msg":"trace[1422404574] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1855; }","duration":"386.75739ms","start":"2026-04-23T17:02:18.742885Z","end":"2026-04-23T17:02:19.129642Z","steps":["trace[1422404574] 'count revisions from in-memory index tree' (duration: 386.546484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:19.129696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:18.742822Z","time spent":"386.865362ms","remote":"127.0.0.1:50884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-23T17:02:19.570212Z","caller":"traceutil/trace.go:171","msg":"trace[1290172707] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"174.786721ms","start":"2026-04-23T17:02:19.395398Z","end":"2026-04-23T17:02:19.570184Z","steps":["trace[1290172707] 'process raft request' (duration: 174.612715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:19.860173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.945201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:02:19.860342Z","caller":"traceutil/trace.go:171","msg":"trace[1942693410] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"320.531829ms","start":"2026-04-23T17:02:19.539797Z","end":"2026-04-23T17:02:19.860329Z","steps":["trace[1942693410] 'process raft request' (duration: 95.962722ms)","trace[1942693410] 'compare' (duration: 223.734706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:02:19.860411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:19.539767Z","time spent":"320.61387ms","remote":"127.0.0.1:50788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5614,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:19.860574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.725188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:02:19.86065Z","caller":"traceutil/trace.go:171","msg":"trace[224684464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1857; }","duration":"273.835241ms","start":"2026-04-23T17:02:19.586797Z","end":"2026-04-23T17:02:19.860632Z","steps":["trace[224684464] 'agreement among raft nodes before linearized reading' (duration: 273.598734ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:19.860292Z","caller":"traceutil/trace.go:171","msg":"trace[601190918] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"273.44702ms","start":"2026-04-23T17:02:19.586828Z","end":"2026-04-23T17:02:19.860275Z","steps":["trace[601190918] 'read index received' (duration: 48.840572ms)","trace[601190918] 'applied index is now lower than readState.Index' (duration: 224.604918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:02:19.861028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.089497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T17:02:19.861073Z","caller":"traceutil/trace.go:171","msg":"trace[2017667010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1857; }","duration":"274.172049ms","start":"2026-04-23T17:02:19.586891Z","end":"2026-04-23T17:02:19.861063Z","steps":["trace[2017667010] 'agreement among raft nodes before linearized reading' (duration: 273.981903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:19.861107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.456859ms","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-23T17:02:19.861145Z","caller":"traceutil/trace.go:171","msg":"trace[608130668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1857; }","duration":"202.516311ms","start":"2026-04-23T17:02:19.658617Z","end":"2026-04-23T17:02:19.861133Z","steps":["trace[608130668] 'agreement among raft nodes before linearized reading' (duration: 202.417568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:19.861221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.046394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:02:19.861246Z","caller":"traceutil/trace.go:171","msg":"trace[829696710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1857; }","duration":"203.112896ms","start":"2026-04-23T17:02:19.658125Z","end":"2026-04-23T17:02:19.861238Z","steps":["trace[829696710] 'agreement among raft nodes before linearized reading' (duration: 203.030764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:19.861317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.355213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:02:19.861345Z","caller":"traceutil/trace.go:171","msg":"trace[1813339078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1857; }","duration":"274.416474ms","start":"2026-04-23T17:02:19.58692Z","end":"2026-04-23T17:02:19.861336Z","steps":["trace[1813339078] 'agreement among raft nodes before linearized reading' (duration: 274.336082ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:20.267059Z","caller":"traceutil/trace.go:171","msg":"trace[645900349] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"401.373179ms","start":"2026-04-23T17:02:19.86566Z","end":"2026-04-23T17:02:20.267033Z","steps":["trace[645900349] 'process raft request' (duration: 401.166344ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:20.267281Z","caller":"traceutil/trace.go:171","msg":"trace[305735448] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"401.556444ms","start":"2026-04-23T17:02:19.865695Z","end":"2026-04-23T17:02:20.267251Z","steps":["trace[305735448] 'process raft request' (duration: 401.297157ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:20.267364Z","caller":"traceutil/trace.go:171","msg":"trace[2061137139] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1940; }","duration":"195.962625ms","start":"2026-04-23T17:02:20.071381Z","end":"2026-04-23T17:02:20.267343Z","steps":["trace[2061137139] 'read index received' (duration: 195.474463ms)","trace[2061137139] 'applied index is now lower than readState.Index' (duration: 487.062µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:02:20.267383Z","caller":"traceutil/trace.go:171","msg":"trace[1197003804] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"401.468381ms","start":"2026-04-23T17:02:19.865896Z","end":"2026-04-23T17:02:20.267364Z","steps":["trace[1197003804] 'process raft request' (duration: 401.321767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:20.267448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:19.86568Z","time spent":"401.682807ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:20.26777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.387055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:02:20.267827Z","caller":"traceutil/trace.go:171","msg":"trace[1287124664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"196.484678ms","start":"2026-04-23T17:02:20.071322Z","end":"2026-04-23T17:02:20.267807Z","steps":["trace[1287124664] 'agreement among raft nodes before linearized reading' (duration: 196.134799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:20.267851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:19.865652Z","time spent":"401.481781ms","remote":"127.0.0.1:50874","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-23T17:02:20.26828Z","caller":"traceutil/trace.go:171","msg":"trace[2079721603] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"401.078991ms","start":"2026-04-23T17:02:19.86719Z","end":"2026-04-23T17:02:20.268269Z","steps":["trace[2079721603] 'process raft request' (duration: 400.112857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:20.268396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:19.865887Z","time spent":"401.557093ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:02:20.268489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:19.867183Z","time spent":"401.259086ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:02:20.26808Z","caller":"traceutil/trace.go:171","msg":"trace[668693659] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"166.804539ms","start":"2026-04-23T17:02:20.101249Z","end":"2026-04-23T17:02:20.268054Z","steps":["trace[668693659] 'process raft request' (duration: 166.707627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:34.571691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325096150086598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:02:35.061549Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.065088828s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T17:02:35.062015Z","caller":"traceutil/trace.go:171","msg":"trace[554702004] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2041; }","duration":"991.177792ms","start":"2026-04-23T17:02:34.070815Z","end":"2026-04-23T17:02:35.061993Z","steps":["trace[554702004] 'read index received' (duration: 991.046739ms)","trace[554702004] 'applied index is now lower than readState.Index' (duration: 130.033µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:02:35.06207Z","caller":"traceutil/trace.go:171","msg":"trace[1585645813] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"1.065670362s","start":"2026-04-23T17:02:33.996367Z","end":"2026-04-23T17:02:35.062037Z","steps":["trace[1585645813] 'process raft request' (duration: 1.065487558s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.062188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.351967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:02:35.062237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:33.996355Z","time spent":"1.065797836s","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:02:35.062265Z","caller":"traceutil/trace.go:171","msg":"trace[1506303267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1957; }","duration":"991.47586ms","start":"2026-04-23T17:02:34.070771Z","end":"2026-04-23T17:02:35.062246Z","steps":["trace[1506303267] 'agreement among raft nodes before linearized reading' (duration: 991.322136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.062316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:34.070752Z","time spent":"991.553822ms","remote":"127.0.0.1:50584","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-23T17:02:35.073723Z","caller":"traceutil/trace.go:171","msg":"trace[1477931872] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"490.98989ms","start":"2026-04-23T17:02:34.582717Z","end":"2026-04-23T17:02:35.073707Z","steps":["trace[1477931872] 'process raft request' (duration: 490.61365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.073829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:34.582696Z","time spent":"491.073143ms","remote":"127.0.0.1:50874","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-23T17:02:35.074156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.940653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:02:35.074191Z","caller":"traceutil/trace.go:171","msg":"trace[1411066906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1958; }","duration":"742.031985ms","start":"2026-04-23T17:02:34.33215Z","end":"2026-04-23T17:02:35.074182Z","steps":["trace[1411066906] 'agreement among raft nodes before linearized reading' (duration: 741.901562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.07422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:34.332128Z","time spent":"742.082907ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T17:02:35.074328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.121783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T17:02:35.074348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.215551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:02:35.074368Z","caller":"traceutil/trace.go:171","msg":"trace[1227450979] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1958; }","duration":"131.268724ms","start":"2026-04-23T17:02:34.943093Z","end":"2026-04-23T17:02:35.074362Z","steps":["trace[1227450979] 'agreement among raft nodes before linearized reading' (duration: 131.238853ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:02:35.074369Z","caller":"traceutil/trace.go:171","msg":"trace[1795801067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1958; }","duration":"743.184834ms","start":"2026-04-23T17:02:34.331173Z","end":"2026-04-23T17:02:35.074358Z","steps":["trace[1795801067] 'agreement among raft nodes before linearized reading' (duration: 742.99817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.074666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:34.33116Z","time spent":"743.493873ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T17:02:35.074438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.331328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-23T17:02:35.074503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.604595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T17:02:35.074815Z","caller":"traceutil/trace.go:171","msg":"trace[995752841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1958; }","duration":"741.932503ms","start":"2026-04-23T17:02:34.332871Z","end":"2026-04-23T17:02:35.074803Z","steps":["trace[995752841] 'agreement among raft nodes before linearized reading' (duration: 741.577344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.074864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:34.332857Z","time spent":"741.990265ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:02:35.074512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.427582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-23T17:02:35.074955Z","caller":"traceutil/trace.go:171","msg":"trace[1613189415] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1958; }","duration":"665.900284ms","start":"2026-04-23T17:02:34.409046Z","end":"2026-04-23T17:02:35.074946Z","steps":["trace[1613189415] 'agreement among raft nodes before linearized reading' (duration: 665.368151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.074983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:34.409034Z","time spent":"665.941065ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-23T17:02:35.0746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.799603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-23T17:02:35.074763Z","caller":"traceutil/trace.go:171","msg":"trace[1449314104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1958; }","duration":"745.681848ms","start":"2026-04-23T17:02:34.329054Z","end":"2026-04-23T17:02:35.074735Z","steps":["trace[1449314104] 'agreement among raft nodes before linearized reading' (duration: 745.302158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.075452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:34.329036Z","time spent":"746.401777ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T17:02:35.075132Z","caller":"traceutil/trace.go:171","msg":"trace[1072845055] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1958; }","duration":"483.340677ms","start":"2026-04-23T17:02:34.59177Z","end":"2026-04-23T17:02:35.075111Z","steps":["trace[1072845055] 'agreement among raft nodes before linearized reading' (duration: 482.771923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:02:35.075504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:02:34.59176Z","time spent":"483.725697ms","remote":"127.0.0.1:50874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-23T17:02:45.848089Z","caller":"traceutil/trace.go:171","msg":"trace[220052344] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2104; }","duration":"125.866922ms","start":"2026-04-23T17:02:45.722194Z","end":"2026-04-23T17:02:45.848061Z","steps":["trace[220052344] 'read index received' (duration: 41.180205ms)","trace[220052344] 'applied index is now lower than readState.Index' (duration: 84.685087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:02:45.848255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.038897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-04-23T17:02:45.848315Z","caller":"traceutil/trace.go:171","msg":"trace[766243581] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:2017; }","duration":"126.15206ms","start":"2026-04-23T17:02:45.722149Z","end":"2026-04-23T17:02:45.848301Z","steps":["trace[766243581] 'agreement among raft nodes before linearized reading' (duration: 126.002426ms)"],"step_count":1}