{"level":"warn","ts":"2026-07-14T16:57:37.573218Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T16:57:37.573323Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-07-14T16:57:37.5734Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T16:57:37.573412Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-14T16:57:37.573436Z","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-07-14T16:57:37.574072Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-07-14T16:57:37.574151Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-07-14T16:57:37.578641Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.247559ms"} {"level":"info","ts":"2026-07-14T16:57:37.592431Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-07-14T16:57:37.592565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T16:57:37.592593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-07-14T16:57:37.592616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T16:57:37.592636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-07-14T16:57:37.59268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-07-14T16:57:37.60001Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T16:57:37.601407Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T16:57:37.602654Z","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-07-14T16:57:37.605139Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T16:57:37.606853Z","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-07-14T16:57:37.607001Z","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-07-14T16:57:37.607017Z","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-07-14T16:57:37.607962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-07-14T16:57:37.605425Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T16:57:37.609395Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-14T16:57:37.610752Z","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-07-14T16:57:37.610839Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-14T16:57:37.610886Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-14T16:57:37.610959Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T16:57:37.610988Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T16:57:38.393423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T16:57:38.393484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T16:57:38.3935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-07-14T16:57:38.393514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-07-14T16:57:38.393522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-14T16:57:38.393533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-07-14T16:57:38.393543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-14T16:57:38.39462Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T16:57:38.394811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:57:38.394833Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:57:38.395396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:57:38.395593Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T16:57:38.395624Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T16:57:38.397463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T16:57:38.398171Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:57:38.398248Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:57:38.39827Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:57:38.402513Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"warn","ts":"2026-07-14T16:58:01.605059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:01.21795Z","time spent":"387.102204ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-14T16:58:02.053979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.69981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:58:02.054097Z","caller":"traceutil/trace.go:171","msg":"trace[59680971] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"447.553063ms","start":"2026-07-14T16:58:01.606531Z","end":"2026-07-14T16:58:02.054084Z","steps":["trace[59680971] 'process raft request' (duration: 312.312205ms)","trace[59680971] 'compare' (duration: 134.097ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:58:02.054151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:01.60651Z","time spent":"447.620924ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:58:02.058659Z","caller":"traceutil/trace.go:171","msg":"trace[1684972158] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"448.910977ms","start":"2026-07-14T16:58:01.609735Z","end":"2026-07-14T16:58:02.058646Z","steps":["trace[1684972158] 'process raft request' (duration: 448.612227ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:02.05855Z","caller":"traceutil/trace.go:171","msg":"trace[2075967770] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"441.936168ms","start":"2026-07-14T16:58:01.616593Z","end":"2026-07-14T16:58:02.058529Z","steps":["trace[2075967770] 'process raft request' (duration: 441.862076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:02.058802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:01.60971Z","time spent":"449.04217ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:58:02.058906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:01.616578Z","time spent":"442.245999ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:58:06.793125Z","caller":"traceutil/trace.go:171","msg":"trace[1155239984] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"134.956228ms","start":"2026-07-14T16:58:06.658149Z","end":"2026-07-14T16:58:06.793105Z","steps":["trace[1155239984] 'read index received' (duration: 133.617544ms)","trace[1155239984] 'applied index is now lower than readState.Index' (duration: 1.337374ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:58:06.793262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.086272ms","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-07-14T16:58:06.793384Z","caller":"traceutil/trace.go:171","msg":"trace[739166388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"135.245307ms","start":"2026-07-14T16:58:06.658116Z","end":"2026-07-14T16:58:06.793362Z","steps":["trace[739166388] 'agreement among raft nodes before linearized reading' (duration: 135.054901ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:16.132116Z","caller":"traceutil/trace.go:171","msg":"trace[2069804899] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"883.977428ms","start":"2026-07-14T16:58:15.248094Z","end":"2026-07-14T16:58:16.132072Z","steps":["trace[2069804899] 'process raft request' (duration: 877.001865ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:16.132161Z","caller":"traceutil/trace.go:171","msg":"trace[1291317650] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"877.646625ms","start":"2026-07-14T16:58:15.254493Z","end":"2026-07-14T16:58:16.132139Z","steps":["trace[1291317650] 'process raft request' (duration: 877.494711ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:16.132175Z","caller":"traceutil/trace.go:171","msg":"trace[127367804] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"805.859804ms","start":"2026-07-14T16:58:15.326288Z","end":"2026-07-14T16:58:16.132148Z","steps":["trace[127367804] 'process raft request' (duration: 805.786441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:16.132276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:15.25447Z","time spent":"877.739179ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:58:16.132295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:15.326255Z","time spent":"805.986058ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:58:16.132326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:15.248076Z","time spent":"884.126173ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:58:21.585941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021755534359843,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T16:58:21.61869Z","caller":"traceutil/trace.go:171","msg":"trace[971645465] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"536.931472ms","start":"2026-07-14T16:58:21.081736Z","end":"2026-07-14T16:58:21.618668Z","steps":["trace[971645465] 'read index received' (duration: 536.741626ms)","trace[971645465] 'applied index is now lower than readState.Index' (duration: 189.096µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:58:21.618795Z","caller":"traceutil/trace.go:171","msg":"trace[2136154643] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"593.241618ms","start":"2026-07-14T16:58:21.025521Z","end":"2026-07-14T16:58:21.618762Z","steps":["trace[2136154643] 'process raft request' (duration: 593.011901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:21.618871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.099218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:58:21.618956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:21.025505Z","time spent":"593.371222ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:58:21.618949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.094756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:58:21.619001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.411476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-14T16:58:21.619047Z","caller":"traceutil/trace.go:171","msg":"trace[1520794641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"434.295583ms","start":"2026-07-14T16:58:21.18473Z","end":"2026-07-14T16:58:21.619026Z","steps":["trace[1520794641] 'agreement among raft nodes before linearized reading' (duration: 434.103367ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:21.618986Z","caller":"traceutil/trace.go:171","msg":"trace[508936846] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:576; }","duration":"537.268773ms","start":"2026-07-14T16:58:21.081704Z","end":"2026-07-14T16:58:21.618973Z","steps":["trace[508936846] 'agreement among raft nodes before linearized reading' (duration: 537.064316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:21.619099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:21.184709Z","time spent":"434.380125ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T16:58:21.619122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:21.081689Z","time spent":"537.414868ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-07-14T16:58:21.619404Z","caller":"traceutil/trace.go:171","msg":"trace[422865866] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:576; }","duration":"400.547131ms","start":"2026-07-14T16:58:21.218523Z","end":"2026-07-14T16:58:21.61907Z","steps":["trace[422865866] 'agreement among raft nodes before linearized reading' (duration: 400.356535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:21.619429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:21.218502Z","time spent":"400.921202ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"info","ts":"2026-07-14T16:58:22.221817Z","caller":"traceutil/trace.go:171","msg":"trace[972280488] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"169.806994ms","start":"2026-07-14T16:58:22.051971Z","end":"2026-07-14T16:58:22.221778Z","steps":["trace[972280488] 'process raft request' (duration: 169.402421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:22.840058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.816701ms","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-07-14T16:58:22.840169Z","caller":"traceutil/trace.go:171","msg":"trace[1342954512] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:584; }","duration":"323.937784ms","start":"2026-07-14T16:58:22.516214Z","end":"2026-07-14T16:58:22.840151Z","steps":["trace[1342954512] 'range keys from in-memory index tree' (duration: 323.80338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:22.840257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.656699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:58:22.840675Z","caller":"traceutil/trace.go:171","msg":"trace[2056789586] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"552.691749ms","start":"2026-07-14T16:58:22.287963Z","end":"2026-07-14T16:58:22.840655Z","steps":["trace[2056789586] 'read index received' (duration: 348.533534ms)","trace[2056789586] 'applied index is now lower than readState.Index' (duration: 204.157095ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:58:22.840717Z","caller":"traceutil/trace.go:171","msg":"trace[263065970] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"614.330424ms","start":"2026-07-14T16:58:22.226345Z","end":"2026-07-14T16:58:22.840675Z","steps":["trace[263065970] 'process raft request' (duration: 614.039555ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:22.840706Z","caller":"traceutil/trace.go:171","msg":"trace[1711411765] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"614.376576ms","start":"2026-07-14T16:58:22.226284Z","end":"2026-07-14T16:58:22.84066Z","steps":["trace[1711411765] 'process raft request' (duration: 410.19866ms)","trace[1711411765] 'compare' (duration: 203.119872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:58:22.840845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.879624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5w8fz\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-07-14T16:58:22.840851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:22.226328Z","time spent":"614.457548ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:58:22.840884Z","caller":"traceutil/trace.go:171","msg":"trace[1159090473] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5w8fz; range_end:; response_count:1; response_revision:586; }","duration":"552.967458ms","start":"2026-07-14T16:58:22.287905Z","end":"2026-07-14T16:58:22.840872Z","steps":["trace[1159090473] 'agreement among raft nodes before linearized reading' (duration: 552.844804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:22.840912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:22.287886Z","time spent":"553.019619ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-5w8fz\" "} {"level":"warn","ts":"2026-07-14T16:58:22.840914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:22.226261Z","time spent":"614.545531ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:58:22.841066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.33397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T16:58:22.841134Z","caller":"traceutil/trace.go:171","msg":"trace[1506529084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"306.427953ms","start":"2026-07-14T16:58:22.534688Z","end":"2026-07-14T16:58:22.841116Z","steps":["trace[1506529084] 'agreement among raft nodes before linearized reading' (duration: 306.303289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:22.84117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:22.534669Z","time spent":"306.492346ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T16:58:22.841551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.744204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:58:22.841591Z","caller":"traceutil/trace.go:171","msg":"trace[1163119583] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:586; }","duration":"549.821356ms","start":"2026-07-14T16:58:22.291757Z","end":"2026-07-14T16:58:22.841578Z","steps":["trace[1163119583] 'agreement among raft nodes before linearized reading' (duration: 549.182566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:22.841625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:22.29173Z","time spent":"549.888709ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-14T16:58:24.815016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633161475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:58:24.81508Z","caller":"traceutil/trace.go:171","msg":"trace[1597571108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.633271248s","start":"2026-07-14T16:58:23.181794Z","end":"2026-07-14T16:58:24.815066Z","steps":["trace[1597571108] 'range keys from in-memory index tree' (duration: 1.633062051s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:24.81512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:23.181768Z","time spent":"1.63334165s","remote":"127.0.0.1:34744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T16:58:24.886885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.611303394s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T16:58:24.890404Z","caller":"traceutil/trace.go:171","msg":"trace[1443176379] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.616573704s","start":"2026-07-14T16:58:23.273807Z","end":"2026-07-14T16:58:24.89038Z","steps":["trace[1443176379] 'process raft request' (duration: 1.614181897s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:24.890545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:23.273731Z","time spent":"1.616746509s","remote":"127.0.0.1:34846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:58:24.907986Z","caller":"traceutil/trace.go:171","msg":"trace[1477872070] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"277.687972ms","start":"2026-07-14T16:58:24.630253Z","end":"2026-07-14T16:58:24.907941Z","steps":["trace[1477872070] 'read index received' (duration: 256.976379ms)","trace[1477872070] 'applied index is now lower than readState.Index' (duration: 20.710413ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:58:24.908193Z","caller":"traceutil/trace.go:171","msg":"trace[1607468059] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.144433937s","start":"2026-07-14T16:58:23.763752Z","end":"2026-07-14T16:58:24.908186Z","steps":["trace[1607468059] 'process raft request' (duration: 1.144054835s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:24.908323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:23.763692Z","time spent":"1.144572312s","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:58:24.908631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.374594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:58:24.908697Z","caller":"traceutil/trace.go:171","msg":"trace[2023050546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"278.471697ms","start":"2026-07-14T16:58:24.630203Z","end":"2026-07-14T16:58:24.908674Z","steps":["trace[2023050546] 'agreement among raft nodes before linearized reading' (duration: 278.391274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:26.869284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.988877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:58:26.869391Z","caller":"traceutil/trace.go:171","msg":"trace[168693162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:612; }","duration":"363.142012ms","start":"2026-07-14T16:58:26.506238Z","end":"2026-07-14T16:58:26.86938Z","steps":["trace[168693162] 'range keys from in-memory index tree' (duration: 362.909104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:26.869435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:26.506219Z","time spent":"363.208224ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T16:58:26.869779Z","caller":"traceutil/trace.go:171","msg":"trace[424240572] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"354.839266ms","start":"2026-07-14T16:58:26.514914Z","end":"2026-07-14T16:58:26.869753Z","steps":["trace[424240572] 'process raft request' (duration: 347.643945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:26.86989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:26.514893Z","time spent":"354.947669ms","remote":"127.0.0.1:35082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T16:58:28.507858Z","caller":"traceutil/trace.go:171","msg":"trace[1172403239] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"114.975205ms","start":"2026-07-14T16:58:28.392863Z","end":"2026-07-14T16:58:28.507838Z","steps":["trace[1172403239] 'process raft request' (duration: 114.926734ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:28.507866Z","caller":"traceutil/trace.go:171","msg":"trace[1904900575] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"137.084225ms","start":"2026-07-14T16:58:28.370755Z","end":"2026-07-14T16:58:28.507839Z","steps":["trace[1904900575] 'process raft request' (duration: 96.273776ms)","trace[1904900575] 'compare' (duration: 40.618842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:58:40.981329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021755534360210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T16:58:41.482174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021755534360210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T16:58:41.503194Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.113440234s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T16:58:41.5038Z","caller":"traceutil/trace.go:171","msg":"trace[314203544] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.114113246s","start":"2026-07-14T16:58:40.389662Z","end":"2026-07-14T16:58:41.503775Z","steps":["trace[314203544] 'process raft request' (duration: 1.113903269s)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:41.503869Z","caller":"traceutil/trace.go:171","msg":"trace[159201491] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"1.02281994s","start":"2026-07-14T16:58:40.481011Z","end":"2026-07-14T16:58:41.503831Z","steps":["trace[159201491] 'read index received' (duration: 1.022338554s)","trace[159201491] 'applied index is now lower than readState.Index' (duration: 477.536µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:58:41.503954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:40.389645Z","time spent":"1.11421799s","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:58:41.504064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023042446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:58:41.504193Z","caller":"traceutil/trace.go:171","msg":"trace[467662832] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:764; }","duration":"1.023215313s","start":"2026-07-14T16:58:40.480957Z","end":"2026-07-14T16:58:41.504172Z","steps":["trace[467662832] 'agreement among raft nodes before linearized reading' (duration: 1.023054128s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.504189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.154466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:58:41.504237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:40.480937Z","time spent":"1.023288894s","remote":"127.0.0.1:34830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-07-14T16:58:41.504251Z","caller":"traceutil/trace.go:171","msg":"trace[439577240] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:764; }","duration":"496.27872ms","start":"2026-07-14T16:58:41.007954Z","end":"2026-07-14T16:58:41.504233Z","steps":["trace[439577240] 'agreement among raft nodes before linearized reading' (duration: 496.098944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.504327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:41.007933Z","time spent":"496.375642ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-14T16:58:41.504438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.473845ms","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-07-14T16:58:41.504485Z","caller":"traceutil/trace.go:171","msg":"trace[1139557071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"359.560167ms","start":"2026-07-14T16:58:41.144913Z","end":"2026-07-14T16:58:41.504474Z","steps":["trace[1139557071] 'agreement among raft nodes before linearized reading' (duration: 359.372941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.504514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:41.14489Z","time spent":"359.617468ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T16:58:41.504622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.988356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-14T16:58:41.50464Z","caller":"traceutil/trace.go:171","msg":"trace[963885644] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:764; }","duration":"285.049569ms","start":"2026-07-14T16:58:41.219585Z","end":"2026-07-14T16:58:41.504635Z","steps":["trace[963885644] 'agreement among raft nodes before linearized reading' (duration: 284.980436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.504641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.585485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:58:41.504687Z","caller":"traceutil/trace.go:171","msg":"trace[1928158119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"319.661218ms","start":"2026-07-14T16:58:41.185012Z","end":"2026-07-14T16:58:41.504674Z","steps":["trace[1928158119] 'agreement among raft nodes before linearized reading' (duration: 319.591006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.504719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:41.184996Z","time spent":"319.717369ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T16:58:41.504801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.215128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-14T16:58:41.504858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.739247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-14T16:58:41.50488Z","caller":"traceutil/trace.go:171","msg":"trace[330211248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"361.808659ms","start":"2026-07-14T16:58:41.143066Z","end":"2026-07-14T16:58:41.504875Z","steps":["trace[330211248] 'agreement among raft nodes before linearized reading' (duration: 361.744957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.504897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:41.143041Z","time spent":"361.85102ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T16:58:41.504857Z","caller":"traceutil/trace.go:171","msg":"trace[2140854197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"360.28703ms","start":"2026-07-14T16:58:41.144555Z","end":"2026-07-14T16:58:41.504842Z","steps":["trace[2140854197] 'agreement among raft nodes before linearized reading' (duration: 360.082964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.504947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:41.144545Z","time spent":"360.387623ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T16:58:41.504954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.779749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T16:58:41.504987Z","caller":"traceutil/trace.go:171","msg":"trace[2144791549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"361.862891ms","start":"2026-07-14T16:58:41.143114Z","end":"2026-07-14T16:58:41.504977Z","steps":["trace[2144791549] 'agreement among raft nodes before linearized reading' (duration: 361.772368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.505017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:41.143091Z","time spent":"361.920163ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T16:58:41.505132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.239126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T16:58:41.505155Z","caller":"traceutil/trace.go:171","msg":"trace[2046488405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"461.297088ms","start":"2026-07-14T16:58:41.04385Z","end":"2026-07-14T16:58:41.505147Z","steps":["trace[2046488405] 'agreement among raft nodes before linearized reading' (duration: 461.231346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:41.505178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:41.043829Z","time spent":"461.34439ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T16:59:16.092542Z","caller":"traceutil/trace.go:171","msg":"trace[1907934167] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"325.204463ms","start":"2026-07-14T16:59:15.767309Z","end":"2026-07-14T16:59:16.092513Z","steps":["trace[1907934167] 'process raft request' (duration: 324.929895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:16.0927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:15.767287Z","time spent":"325.344748ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:59:17.912569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.892816ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-14T16:59:17.913477Z","caller":"traceutil/trace.go:171","msg":"trace[1772638604] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1023; }","duration":"906.0833ms","start":"2026-07-14T16:59:17.007353Z","end":"2026-07-14T16:59:17.913436Z","steps":["trace[1772638604] 'read index received' (duration: 328.314428ms)","trace[1772638604] 'applied index is now lower than readState.Index' (duration: 577.765252ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:17.913663Z","caller":"traceutil/trace.go:171","msg":"trace[1227490393] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"1.116668469s","start":"2026-07-14T16:59:16.796978Z","end":"2026-07-14T16:59:17.913647Z","steps":["trace[1227490393] 'process raft request' (duration: 1.115729331s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:17.913801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:16.796959Z","time spent":"1.116745632s","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:59:18.032945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025584883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T16:59:18.032945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.151513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:59:18.033021Z","caller":"traceutil/trace.go:171","msg":"trace[2074140906] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:987; }","duration":"494.280127ms","start":"2026-07-14T16:59:17.538728Z","end":"2026-07-14T16:59:18.033008Z","steps":["trace[2074140906] 'agreement among raft nodes before linearized reading' (duration: 375.018621ms)","trace[2074140906] 'count revisions from in-memory index tree' (duration: 119.153463ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:18.033037Z","caller":"traceutil/trace.go:171","msg":"trace[1542009151] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:987; }","duration":"1.025702686s","start":"2026-07-14T16:59:17.007297Z","end":"2026-07-14T16:59:18.033Z","steps":["trace[1542009151] 'agreement among raft nodes before linearized reading' (duration: 906.460792ms)","trace[1542009151] 'count revisions from in-memory index tree' (duration: 119.144182ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:18.033054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:17.538678Z","time spent":"494.36685ms","remote":"127.0.0.1:34964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-14T16:59:18.03308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:17.007277Z","time spent":"1.02579191s","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-14T16:59:18.033205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.245945ms","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-07-14T16:59:18.033249Z","caller":"traceutil/trace.go:171","msg":"trace[2080174367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"306.302807ms","start":"2026-07-14T16:59:17.726932Z","end":"2026-07-14T16:59:18.033235Z","steps":["trace[2080174367] 'agreement among raft nodes before linearized reading' (duration: 186.819254ms)","trace[2080174367] 'range keys from in-memory index tree' (duration: 118.655827ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:18.033278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:17.726925Z","time spent":"306.344648ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T16:59:18.033366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.157744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:59:18.033406Z","caller":"traceutil/trace.go:171","msg":"trace[1436777345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"848.234286ms","start":"2026-07-14T16:59:17.185161Z","end":"2026-07-14T16:59:18.033395Z","steps":["trace[1436777345] 'agreement among raft nodes before linearized reading' (duration: 728.555908ms)","trace[1436777345] 'range keys from in-memory index tree' (duration: 119.623286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:18.033434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:17.185139Z","time spent":"848.287228ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T16:59:18.033446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.564774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-14T16:59:18.033454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.796432ms","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-07-14T16:59:18.033498Z","caller":"traceutil/trace.go:171","msg":"trace[544420095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"307.853844ms","start":"2026-07-14T16:59:17.725632Z","end":"2026-07-14T16:59:18.033486Z","steps":["trace[544420095] 'agreement among raft nodes before linearized reading' (duration: 188.092294ms)","trace[544420095] 'range keys from in-memory index tree' (duration: 119.651077ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:18.033516Z","caller":"traceutil/trace.go:171","msg":"trace[1764031488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"304.620876ms","start":"2026-07-14T16:59:17.728855Z","end":"2026-07-14T16:59:18.033476Z","steps":["trace[1764031488] 'agreement among raft nodes before linearized reading' (duration: 184.880906ms)","trace[1764031488] 'range keys from in-memory index tree' (duration: 119.645198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:18.033529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:17.725624Z","time spent":"307.897655ms","remote":"127.0.0.1:35014","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-07-14T16:59:18.033552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.579426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-14T16:59:18.03356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:17.728846Z","time spent":"304.704188ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T16:59:18.033345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.438616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T16:59:18.033589Z","caller":"traceutil/trace.go:171","msg":"trace[677377169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"308.636818ms","start":"2026-07-14T16:59:17.724938Z","end":"2026-07-14T16:59:18.033575Z","steps":["trace[677377169] 'agreement among raft nodes before linearized reading' (duration: 188.794915ms)","trace[677377169] 'range keys from in-memory index tree' (duration: 119.760201ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:18.033621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:17.724918Z","time spent":"308.69551ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T16:59:18.033623Z","caller":"traceutil/trace.go:171","msg":"trace[892696334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"317.732656ms","start":"2026-07-14T16:59:17.715877Z","end":"2026-07-14T16:59:18.03361Z","steps":["trace[892696334] 'agreement among raft nodes before linearized reading' (duration: 197.860462ms)","trace[892696334] 'range keys from in-memory index tree' (duration: 119.456352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:18.033658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:17.71582Z","time spent":"317.827368ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T16:59:36.943388Z","caller":"traceutil/trace.go:171","msg":"trace[523998825] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"279.146419ms","start":"2026-07-14T16:59:36.664226Z","end":"2026-07-14T16:59:36.943372Z","steps":["trace[523998825] 'process raft request' (duration: 279.057386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:38.338954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.383458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T16:59:38.339069Z","caller":"traceutil/trace.go:171","msg":"trace[694529483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1053; }","duration":"198.539122ms","start":"2026-07-14T16:59:38.140512Z","end":"2026-07-14T16:59:38.339052Z","steps":["trace[694529483] 'range keys from in-memory index tree' (duration: 198.205432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:38.339347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.631165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:59:38.339491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.986151ms","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-07-14T16:59:38.33953Z","caller":"traceutil/trace.go:171","msg":"trace[410310578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1053; }","duration":"210.051523ms","start":"2026-07-14T16:59:38.129469Z","end":"2026-07-14T16:59:38.33952Z","steps":["trace[410310578] 'range keys from in-memory index tree' (duration: 209.831657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:38.339692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.474135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-14T16:59:38.339762Z","caller":"traceutil/trace.go:171","msg":"trace[1267261903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"209.586469ms","start":"2026-07-14T16:59:38.130167Z","end":"2026-07-14T16:59:38.339753Z","steps":["trace[1267261903] 'range keys from in-memory index tree' (duration: 209.337001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:38.339914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.685242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T16:59:38.339948Z","caller":"traceutil/trace.go:171","msg":"trace[976563064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"209.727433ms","start":"2026-07-14T16:59:38.130213Z","end":"2026-07-14T16:59:38.33994Z","steps":["trace[976563064] 'range keys from in-memory index tree' (duration: 209.550358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:38.340087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.206014ms","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-07-14T16:59:38.34012Z","caller":"traceutil/trace.go:171","msg":"trace[1904882177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"202.278306ms","start":"2026-07-14T16:59:38.137833Z","end":"2026-07-14T16:59:38.340112Z","steps":["trace[1904882177] 'range keys from in-memory index tree' (duration: 202.07211ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:38.339516Z","caller":"traceutil/trace.go:171","msg":"trace[687357803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"156.840181ms","start":"2026-07-14T16:59:38.182617Z","end":"2026-07-14T16:59:38.339457Z","steps":["trace[687357803] 'range keys from in-memory index tree' (duration: 156.553582ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:38.687908Z","caller":"traceutil/trace.go:171","msg":"trace[561322816] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"342.310854ms","start":"2026-07-14T16:59:38.34558Z","end":"2026-07-14T16:59:38.687891Z","steps":["trace[561322816] 'process raft request' (duration: 342.239462ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:38.687983Z","caller":"traceutil/trace.go:171","msg":"trace[1624210543] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"342.445089ms","start":"2026-07-14T16:59:38.3455Z","end":"2026-07-14T16:59:38.687945Z","steps":["trace[1624210543] 'process raft request' (duration: 342.291184ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:38.68804Z","caller":"traceutil/trace.go:171","msg":"trace[1435846707] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"342.404198ms","start":"2026-07-14T16:59:38.345625Z","end":"2026-07-14T16:59:38.688029Z","steps":["trace[1435846707] 'process raft request' (duration: 342.240433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:38.688088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:38.345569Z","time spent":"342.447169ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:59:38.688107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:38.345492Z","time spent":"342.552622ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:59:38.688132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:38.3456Z","time spent":"342.47102ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:59:38.687915Z","caller":"traceutil/trace.go:171","msg":"trace[663857824] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"342.564302ms","start":"2026-07-14T16:59:38.345321Z","end":"2026-07-14T16:59:38.687885Z","steps":["trace[663857824] 'process raft request' (duration: 342.292214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:38.688267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:38.345304Z","time spent":"342.878602ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:59:42.246692Z","caller":"traceutil/trace.go:171","msg":"trace[1396597413] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"108.073944ms","start":"2026-07-14T16:59:42.138595Z","end":"2026-07-14T16:59:42.246669Z","steps":["trace[1396597413] 'read index received' (duration: 107.856778ms)","trace[1396597413] 'applied index is now lower than readState.Index' (duration: 216.296µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:42.246732Z","caller":"traceutil/trace.go:171","msg":"trace[730846598] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"147.467166ms","start":"2026-07-14T16:59:42.099245Z","end":"2026-07-14T16:59:42.246712Z","steps":["trace[730846598] 'process raft request' (duration: 147.28728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:42.246837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.227419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T16:59:42.246906Z","caller":"traceutil/trace.go:171","msg":"trace[1487361081] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1066; }","duration":"108.353063ms","start":"2026-07-14T16:59:42.138543Z","end":"2026-07-14T16:59:42.246896Z","steps":["trace[1487361081] 'agreement among raft nodes before linearized reading' (duration: 108.24139ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:42.601084Z","caller":"traceutil/trace.go:171","msg":"trace[110012599] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"141.050019ms","start":"2026-07-14T16:59:42.460007Z","end":"2026-07-14T16:59:42.601057Z","steps":["trace[110012599] 'process raft request' (duration: 140.891734ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:56.469349Z","caller":"traceutil/trace.go:171","msg":"trace[706589735] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"148.806677ms","start":"2026-07-14T16:59:56.320523Z","end":"2026-07-14T16:59:56.469329Z","steps":["trace[706589735] 'process raft request' (duration: 148.675833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:56.651309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.657522ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-14T16:59:58.718479Z","caller":"traceutil/trace.go:171","msg":"trace[1465592187] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"244.582965ms","start":"2026-07-14T16:59:58.473867Z","end":"2026-07-14T16:59:58.71845Z","steps":["trace[1465592187] 'process raft request' (duration: 244.371928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:59.64524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.730135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:59:59.645308Z","caller":"traceutil/trace.go:171","msg":"trace[1894909856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"460.845599ms","start":"2026-07-14T16:59:59.184449Z","end":"2026-07-14T16:59:59.645295Z","steps":["trace[1894909856] 'range keys from in-memory index tree' (duration: 460.674903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:59.645336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:59.184435Z","time spent":"460.89547ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T16:59:59.64556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.821106ms","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-07-14T16:59:59.645578Z","caller":"traceutil/trace.go:171","msg":"trace[1942256153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1171; }","duration":"810.873598ms","start":"2026-07-14T16:59:58.834699Z","end":"2026-07-14T16:59:59.645572Z","steps":["trace[1942256153] 'range keys from in-memory index tree' (duration: 810.686403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:59.645595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:58.834678Z","time spent":"810.91308ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T16:59:59.645716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.745663ms","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-07-14T16:59:59.645733Z","caller":"traceutil/trace.go:171","msg":"trace[1903615925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"807.798465ms","start":"2026-07-14T16:59:58.83793Z","end":"2026-07-14T16:59:59.645728Z","steps":["trace[1903615925] 'range keys from in-memory index tree' (duration: 807.616849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:59.645749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:58.83791Z","time spent":"807.835246ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T16:59:59.64585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.842656ms","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-07-14T16:59:59.645868Z","caller":"traceutil/trace.go:171","msg":"trace[896839856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1171; }","duration":"807.897688ms","start":"2026-07-14T16:59:58.837964Z","end":"2026-07-14T16:59:59.645862Z","steps":["trace[896839856] 'range keys from in-memory index tree' (duration: 807.745513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:59.645907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:58.837943Z","time spent":"807.95827ms","remote":"127.0.0.1:35014","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-07-14T16:59:59.646016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.558664ms","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-07-14T16:59:59.646032Z","caller":"traceutil/trace.go:171","msg":"trace[1182003803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"801.591965ms","start":"2026-07-14T16:59:58.844435Z","end":"2026-07-14T16:59:59.646027Z","steps":["trace[1182003803] 'range keys from in-memory index tree' (duration: 801.489433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:59.646048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:58.844426Z","time spent":"801.618246ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T17:00:12.936932Z","caller":"traceutil/trace.go:171","msg":"trace[1898119240] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"103.124214ms","start":"2026-07-14T17:00:12.833773Z","end":"2026-07-14T17:00:12.936897Z","steps":["trace[1898119240] 'process raft request' (duration: 71.469059ms)","trace[1898119240] 'compare' (duration: 31.343485ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:00:15.497445Z","caller":"traceutil/trace.go:171","msg":"trace[1270487077] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"123.147044ms","start":"2026-07-14T17:00:15.374264Z","end":"2026-07-14T17:00:15.497411Z","steps":["trace[1270487077] 'process raft request' (duration: 122.916087ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:15.960952Z","caller":"traceutil/trace.go:171","msg":"trace[300921573] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"155.992955ms","start":"2026-07-14T17:00:15.804937Z","end":"2026-07-14T17:00:15.96093Z","steps":["trace[300921573] 'read index received' (duration: 155.8274ms)","trace[300921573] 'applied index is now lower than readState.Index' (duration: 164.695µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:00:15.961189Z","caller":"traceutil/trace.go:171","msg":"trace[416884904] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"189.341952ms","start":"2026-07-14T17:00:15.771831Z","end":"2026-07-14T17:00:15.961173Z","steps":["trace[416884904] 'process raft request' (duration: 188.985301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:15.96152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.296933ms","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-07-14T17:00:15.961566Z","caller":"traceutil/trace.go:171","msg":"trace[2085073271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"153.356485ms","start":"2026-07-14T17:00:15.808198Z","end":"2026-07-14T17:00:15.961554Z","steps":["trace[2085073271] 'agreement among raft nodes before linearized reading' (duration: 153.21681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:15.961752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.8106ms","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-07-14T17:00:15.961784Z","caller":"traceutil/trace.go:171","msg":"trace[1559030369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"156.868182ms","start":"2026-07-14T17:00:15.804908Z","end":"2026-07-14T17:00:15.961776Z","steps":["trace[1559030369] 'agreement among raft nodes before linearized reading' (duration: 156.78565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:15.961921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.312112ms","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-07-14T17:00:15.961956Z","caller":"traceutil/trace.go:171","msg":"trace[192178321] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1245; }","duration":"110.389995ms","start":"2026-07-14T17:00:15.851557Z","end":"2026-07-14T17:00:15.961947Z","steps":["trace[192178321] 'agreement among raft nodes before linearized reading' (duration: 110.334453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:15.962168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.908352ms","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-07-14T17:00:15.962203Z","caller":"traceutil/trace.go:171","msg":"trace[527191511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"153.954273ms","start":"2026-07-14T17:00:15.808239Z","end":"2026-07-14T17:00:15.962194Z","steps":["trace[527191511] 'agreement among raft nodes before linearized reading' (duration: 153.86561ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:16.523862Z","caller":"traceutil/trace.go:171","msg":"trace[759351346] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"558.573658ms","start":"2026-07-14T17:00:15.96526Z","end":"2026-07-14T17:00:16.523834Z","steps":["trace[759351346] 'process raft request' (duration: 558.381202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:16.524051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:15.965243Z","time spent":"558.720872ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:16.54251Z","caller":"traceutil/trace.go:171","msg":"trace[1361489613] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"576.543305ms","start":"2026-07-14T17:00:15.965946Z","end":"2026-07-14T17:00:16.542489Z","steps":["trace[1361489613] 'process raft request' (duration: 576.390231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:16.542646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:15.965938Z","time spent":"576.635038ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:16.542772Z","caller":"traceutil/trace.go:171","msg":"trace[1121465384] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"576.747572ms","start":"2026-07-14T17:00:15.966014Z","end":"2026-07-14T17:00:16.542761Z","steps":["trace[1121465384] 'process raft request' (duration: 576.443812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:16.54285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:15.965985Z","time spent":"576.843185ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:16.579702Z","caller":"traceutil/trace.go:171","msg":"trace[1218995981] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1296; }","duration":"471.41401ms","start":"2026-07-14T17:00:16.108263Z","end":"2026-07-14T17:00:16.579677Z","steps":["trace[1218995981] 'read index received' (duration: 415.489325ms)","trace[1218995981] 'applied index is now lower than readState.Index' (duration: 55.922985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:16.580044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.763331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:00:16.580086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.238193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:00:16.580115Z","caller":"traceutil/trace.go:171","msg":"trace[1009356556] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1248; }","duration":"471.878275ms","start":"2026-07-14T17:00:16.108219Z","end":"2026-07-14T17:00:16.580097Z","steps":["trace[1009356556] 'agreement among raft nodes before linearized reading' (duration: 471.758651ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:16.58013Z","caller":"traceutil/trace.go:171","msg":"trace[1998688242] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1248; }","duration":"465.337315ms","start":"2026-07-14T17:00:16.11478Z","end":"2026-07-14T17:00:16.580118Z","steps":["trace[1998688242] 'agreement among raft nodes before linearized reading' (duration: 465.244303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:16.58015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:16.108202Z","time spent":"471.940306ms","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:00:16.580168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:16.114759Z","time spent":"465.400507ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:00:16.580375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.454255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:00:16.580404Z","caller":"traceutil/trace.go:171","msg":"trace[748117836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"395.516067ms","start":"2026-07-14T17:00:16.184879Z","end":"2026-07-14T17:00:16.580395Z","steps":["trace[748117836] 'agreement among raft nodes before linearized reading' (duration: 395.471706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:16.580429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:16.184859Z","time spent":"395.564789ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:00:16.86455Z","caller":"traceutil/trace.go:171","msg":"trace[199025361] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"150.383175ms","start":"2026-07-14T17:00:16.714128Z","end":"2026-07-14T17:00:16.864511Z","steps":["trace[199025361] 'process raft request' (duration: 95.166972ms)","trace[199025361] 'compare' (duration: 54.932904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:17.578116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.204546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:00:17.578183Z","caller":"traceutil/trace.go:171","msg":"trace[1270190572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"393.304689ms","start":"2026-07-14T17:00:17.184863Z","end":"2026-07-14T17:00:17.578168Z","steps":["trace[1270190572] 'range keys from in-memory index tree' (duration: 393.148075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:17.57816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.172282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:00:17.578217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:17.184848Z","time spent":"393.361671ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:00:17.578283Z","caller":"traceutil/trace.go:171","msg":"trace[1802993377] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1253; }","duration":"448.285235ms","start":"2026-07-14T17:00:17.129951Z","end":"2026-07-14T17:00:17.578237Z","steps":["trace[1802993377] 'range keys from in-memory index tree' (duration: 448.095119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:17.578343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:17.129893Z","time spent":"448.43321ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-14T17:00:17.844655Z","caller":"traceutil/trace.go:171","msg":"trace[298332254] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"329.621637ms","start":"2026-07-14T17:00:17.514999Z","end":"2026-07-14T17:00:17.844621Z","steps":["trace[298332254] 'process raft request' (duration: 329.412811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:17.84485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:17.514973Z","time spent":"329.774002ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:00:18.353044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021755534361321,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:00:18.460777Z","caller":"traceutil/trace.go:171","msg":"trace[1464606053] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1306; }","duration":"608.701975ms","start":"2026-07-14T17:00:17.852047Z","end":"2026-07-14T17:00:18.460749Z","steps":["trace[1464606053] 'read index received' (duration: 608.296323ms)","trace[1464606053] 'applied index is now lower than readState.Index' (duration: 404.402µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:00:18.460914Z","caller":"traceutil/trace.go:171","msg":"trace[844808409] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"878.955493ms","start":"2026-07-14T17:00:17.581944Z","end":"2026-07-14T17:00:18.460899Z","steps":["trace[844808409] 'process raft request' (duration: 878.557771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:18.461019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:17.58192Z","time spent":"879.031975ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:00:18.461022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.943322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:00:18.461103Z","caller":"traceutil/trace.go:171","msg":"trace[407830263] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1256; }","duration":"609.077296ms","start":"2026-07-14T17:00:17.852005Z","end":"2026-07-14T17:00:18.461083Z","steps":["trace[407830263] 'agreement among raft nodes before linearized reading' (duration: 608.889601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:18.461145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:17.851985Z","time spent":"609.149579ms","remote":"127.0.0.1:35180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:00:18.461207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.180401ms","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-07-14T17:00:18.461283Z","caller":"traceutil/trace.go:171","msg":"trace[1370204470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"487.301885ms","start":"2026-07-14T17:00:17.973962Z","end":"2026-07-14T17:00:18.461264Z","steps":["trace[1370204470] 'agreement among raft nodes before linearized reading' (duration: 487.070938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:18.461327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:17.973943Z","time spent":"487.375747ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T17:00:18.461344Z","caller":"traceutil/trace.go:171","msg":"trace[1403575415] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"870.173986ms","start":"2026-07-14T17:00:17.591155Z","end":"2026-07-14T17:00:18.461329Z","steps":["trace[1403575415] 'process raft request' (duration: 869.530146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:18.461425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:17.591136Z","time spent":"870.241317ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:00:18.461574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.686034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:00:18.461602Z","caller":"traceutil/trace.go:171","msg":"trace[564679163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"277.739326ms","start":"2026-07-14T17:00:18.183853Z","end":"2026-07-14T17:00:18.461592Z","steps":["trace[564679163] 'agreement among raft nodes before linearized reading' (duration: 277.695165ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:18.592797Z","caller":"traceutil/trace.go:171","msg":"trace[947084616] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"120.871655ms","start":"2026-07-14T17:00:18.471898Z","end":"2026-07-14T17:00:18.592769Z","steps":["trace[947084616] 'process raft request' (duration: 120.72965ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:18.593055Z","caller":"traceutil/trace.go:171","msg":"trace[1977386466] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"123.101922ms","start":"2026-07-14T17:00:18.46994Z","end":"2026-07-14T17:00:18.593042Z","steps":["trace[1977386466] 'process raft request' (duration: 122.619287ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:18.593185Z","caller":"traceutil/trace.go:171","msg":"trace[119125013] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"123.277768ms","start":"2026-07-14T17:00:18.469863Z","end":"2026-07-14T17:00:18.59314Z","steps":["trace[119125013] 'process raft request' (duration: 73.896133ms)","trace[119125013] 'compare' (duration: 48.631912ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:29.471704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.378397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:00:29.471755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.283051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-14T17:00:29.471821Z","caller":"traceutil/trace.go:171","msg":"trace[178359474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"286.548942ms","start":"2026-07-14T17:00:29.185252Z","end":"2026-07-14T17:00:29.471801Z","steps":["trace[178359474] 'range keys from in-memory index tree' (duration: 286.274013ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:29.47184Z","caller":"traceutil/trace.go:171","msg":"trace[441784920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1336; }","duration":"367.453556ms","start":"2026-07-14T17:00:29.104369Z","end":"2026-07-14T17:00:29.471823Z","steps":["trace[441784920] 'range keys from in-memory index tree' (duration: 367.128436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:29.471971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:29.104356Z","time spent":"367.604731ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:00:30.13276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.997671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-14T17:00:30.13286Z","caller":"traceutil/trace.go:171","msg":"trace[83950607] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1337; }","duration":"346.161176ms","start":"2026-07-14T17:00:29.786673Z","end":"2026-07-14T17:00:30.132834Z","steps":["trace[83950607] 'range keys from in-memory index tree' (duration: 345.807025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:30.132903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:29.786648Z","time spent":"346.245759ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-14T17:00:30.249912Z","caller":"traceutil/trace.go:171","msg":"trace[935475713] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"112.952582ms","start":"2026-07-14T17:00:30.136936Z","end":"2026-07-14T17:00:30.249888Z","steps":["trace[935475713] 'process raft request' (duration: 112.795987ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:53.130565Z","caller":"traceutil/trace.go:171","msg":"trace[136568681] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"156.022207ms","start":"2026-07-14T17:00:52.97451Z","end":"2026-07-14T17:00:53.130532Z","steps":["trace[136568681] 'process raft request' (duration: 155.865122ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:53.793935Z","caller":"traceutil/trace.go:171","msg":"trace[2085134908] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"188.254141ms","start":"2026-07-14T17:00:53.605657Z","end":"2026-07-14T17:00:53.793911Z","steps":["trace[2085134908] 'process raft request' (duration: 188.114076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:53.794554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.76604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-14T17:00:53.794603Z","caller":"traceutil/trace.go:171","msg":"trace[1414351321] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1448; }","duration":"142.888934ms","start":"2026-07-14T17:00:53.651702Z","end":"2026-07-14T17:00:53.794591Z","steps":["trace[1414351321] 'agreement among raft nodes before linearized reading' (duration: 142.724369ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:53.794377Z","caller":"traceutil/trace.go:171","msg":"trace[1429296993] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1507; }","duration":"142.521472ms","start":"2026-07-14T17:00:53.651759Z","end":"2026-07-14T17:00:53.79428Z","steps":["trace[1429296993] 'read index received' (duration: 142.516432ms)","trace[1429296993] 'applied index is now lower than readState.Index' (duration: 4.07µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:54.154671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.630884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:00:54.154864Z","caller":"traceutil/trace.go:171","msg":"trace[1899312885] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"355.605404ms","start":"2026-07-14T17:00:53.799172Z","end":"2026-07-14T17:00:54.154777Z","steps":["trace[1899312885] 'process raft request' (duration: 248.776154ms)","trace[1899312885] 'compare' (duration: 106.50631ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:54.155051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:53.799149Z","time spent":"355.81051ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:59.94696Z","caller":"traceutil/trace.go:171","msg":"trace[2844603] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"127.015145ms","start":"2026-07-14T17:00:59.819918Z","end":"2026-07-14T17:00:59.946933Z","steps":["trace[2844603] 'process raft request' (duration: 126.870141ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:15.519099Z","caller":"traceutil/trace.go:171","msg":"trace[106971884] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"108.675478ms","start":"2026-07-14T17:01:15.410393Z","end":"2026-07-14T17:01:15.519068Z","steps":["trace[106971884] 'process raft request' (duration: 69.541527ms)","trace[106971884] 'compare' (duration: 38.80517ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:16.727144Z","caller":"traceutil/trace.go:171","msg":"trace[2126632503] linearizableReadLoop","detail":"{readStateIndex:1614; appliedIndex:1613; }","duration":"153.678614ms","start":"2026-07-14T17:01:16.573441Z","end":"2026-07-14T17:01:16.72712Z","steps":["trace[2126632503] 'read index received' (duration: 74.133722ms)","trace[2126632503] 'applied index is now lower than readState.Index' (duration: 79.543392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:16.727306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.842628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:01:16.727401Z","caller":"traceutil/trace.go:171","msg":"trace[300458481] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1550; }","duration":"153.995733ms","start":"2026-07-14T17:01:16.573388Z","end":"2026-07-14T17:01:16.727383Z","steps":["trace[300458481] 'agreement among raft nodes before linearized reading' (duration: 153.809447ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:23.618718Z","caller":"traceutil/trace.go:171","msg":"trace[445299489] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"101.523832ms","start":"2026-07-14T17:01:23.517169Z","end":"2026-07-14T17:01:23.618693Z","steps":["trace[445299489] 'process raft request' (duration: 42.356471ms)","trace[445299489] 'compare' (duration: 58.471379ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:25.635075Z","caller":"traceutil/trace.go:171","msg":"trace[693565195] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"136.600161ms","start":"2026-07-14T17:01:25.498439Z","end":"2026-07-14T17:01:25.635039Z","steps":["trace[693565195] 'process raft request' (duration: 136.393794ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:33.47454Z","caller":"traceutil/trace.go:171","msg":"trace[23837647] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"108.100671ms","start":"2026-07-14T17:01:33.366421Z","end":"2026-07-14T17:01:33.474522Z","steps":["trace[23837647] 'process raft request' (duration: 84.807151ms)","trace[23837647] 'compare' (duration: 22.951919ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:33.674954Z","caller":"traceutil/trace.go:171","msg":"trace[216286720] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"134.361261ms","start":"2026-07-14T17:01:33.540562Z","end":"2026-07-14T17:01:33.674924Z","steps":["trace[216286720] 'process raft request' (duration: 60.308673ms)","trace[216286720] 'compare' (duration: 73.941655ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:33.990086Z","caller":"traceutil/trace.go:171","msg":"trace[1024180245] linearizableReadLoop","detail":"{readStateIndex:1696; appliedIndex:1695; }","duration":"200.753393ms","start":"2026-07-14T17:01:33.789289Z","end":"2026-07-14T17:01:33.990042Z","steps":["trace[1024180245] 'read index received' (duration: 120.542725ms)","trace[1024180245] 'applied index is now lower than readState.Index' (duration: 80.209338ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:33.990239Z","caller":"traceutil/trace.go:171","msg":"trace[205438793] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"258.113316ms","start":"2026-07-14T17:01:33.732098Z","end":"2026-07-14T17:01:33.990212Z","steps":["trace[205438793] 'process raft request' (duration: 177.787444ms)","trace[205438793] 'compare' (duration: 80.025742ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:33.990439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.47231ms","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-07-14T17:01:33.990498Z","caller":"traceutil/trace.go:171","msg":"trace[837830849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1629; }","duration":"162.558353ms","start":"2026-07-14T17:01:33.827924Z","end":"2026-07-14T17:01:33.990483Z","steps":["trace[837830849] 'agreement among raft nodes before linearized reading' (duration: 162.411779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:33.990712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.268819ms","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-07-14T17:01:33.99074Z","caller":"traceutil/trace.go:171","msg":"trace[232307741] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1629; }","duration":"123.323131ms","start":"2026-07-14T17:01:33.867408Z","end":"2026-07-14T17:01:33.990731Z","steps":["trace[232307741] 'agreement among raft nodes before linearized reading' (duration: 123.243588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:33.990824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.514647ms","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-07-14T17:01:33.990868Z","caller":"traceutil/trace.go:171","msg":"trace[2138800977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1629; }","duration":"201.59376ms","start":"2026-07-14T17:01:33.789261Z","end":"2026-07-14T17:01:33.990855Z","steps":["trace[2138800977] 'agreement among raft nodes before linearized reading' (duration: 201.515697ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:35.520255Z","caller":"traceutil/trace.go:171","msg":"trace[1993855880] linearizableReadLoop","detail":"{readStateIndex:1702; appliedIndex:1701; }","duration":"117.049966ms","start":"2026-07-14T17:01:35.403181Z","end":"2026-07-14T17:01:35.520231Z","steps":["trace[1993855880] 'read index received' (duration: 116.906172ms)","trace[1993855880] 'applied index is now lower than readState.Index' (duration: 142.984µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:35.520362Z","caller":"traceutil/trace.go:171","msg":"trace[1184172036] transaction","detail":"{read_only:false; response_revision:1635; number_of_response:1; }","duration":"334.712603ms","start":"2026-07-14T17:01:35.185621Z","end":"2026-07-14T17:01:35.520334Z","steps":["trace[1184172036] 'process raft request' (duration: 334.461115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:35.520475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.269703ms","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-07-14T17:01:35.520527Z","caller":"traceutil/trace.go:171","msg":"trace[1799009791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1635; }","duration":"117.371017ms","start":"2026-07-14T17:01:35.403141Z","end":"2026-07-14T17:01:35.520512Z","steps":["trace[1799009791] 'agreement among raft nodes before linearized reading' (duration: 117.191832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:35.52053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:35.1856Z","time spent":"334.843157ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:01:36.15998Z","caller":"traceutil/trace.go:171","msg":"trace[2004362592] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"122.787435ms","start":"2026-07-14T17:01:36.03715Z","end":"2026-07-14T17:01:36.159937Z","steps":["trace[2004362592] 'process raft request' (duration: 122.544277ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:06.273838Z","caller":"traceutil/trace.go:171","msg":"trace[1676219149] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"309.347298ms","start":"2026-07-14T17:02:05.964466Z","end":"2026-07-14T17:02:06.273813Z","steps":["trace[1676219149] 'process raft request' (duration: 309.207583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:06.274007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:05.964445Z","time spent":"309.494063ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:06.279337Z","caller":"traceutil/trace.go:171","msg":"trace[653621553] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"313.689822ms","start":"2026-07-14T17:02:05.96563Z","end":"2026-07-14T17:02:06.279319Z","steps":["trace[653621553] 'process raft request' (duration: 313.574919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:06.279433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:05.965603Z","time spent":"313.785465ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:02:12.673281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.617725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:02:12.673376Z","caller":"traceutil/trace.go:171","msg":"trace[1451047871] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1807; }","duration":"107.773439ms","start":"2026-07-14T17:02:12.565585Z","end":"2026-07-14T17:02:12.673359Z","steps":["trace[1451047871] 'count revisions from in-memory index tree' (duration: 107.45169ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:14.758548Z","caller":"traceutil/trace.go:171","msg":"trace[2144265775] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"222.415472ms","start":"2026-07-14T17:02:14.536107Z","end":"2026-07-14T17:02:14.758522Z","steps":["trace[2144265775] 'process raft request' (duration: 170.644222ms)","trace[2144265775] 'compare' (duration: 51.588224ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:14.773424Z","caller":"traceutil/trace.go:171","msg":"trace[462761055] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"237.052074ms","start":"2026-07-14T17:02:14.536338Z","end":"2026-07-14T17:02:14.77339Z","steps":["trace[462761055] 'process raft request' (duration: 236.869859ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:14.917876Z","caller":"traceutil/trace.go:171","msg":"trace[671126331] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"139.666057ms","start":"2026-07-14T17:02:14.77818Z","end":"2026-07-14T17:02:14.917846Z","steps":["trace[671126331] 'process raft request' (duration: 137.625264ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:19.974807Z","caller":"traceutil/trace.go:171","msg":"trace[670438382] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"116.274012ms","start":"2026-07-14T17:02:19.85851Z","end":"2026-07-14T17:02:19.974784Z","steps":["trace[670438382] 'read index received' (duration: 116.165509ms)","trace[670438382] 'applied index is now lower than readState.Index' (duration: 107.073µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:19.974911Z","caller":"traceutil/trace.go:171","msg":"trace[1868644212] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"136.089874ms","start":"2026-07-14T17:02:19.838795Z","end":"2026-07-14T17:02:19.974885Z","steps":["trace[1868644212] 'process raft request' (duration: 135.846437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:19.975026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.493809ms","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-07-14T17:02:19.975066Z","caller":"traceutil/trace.go:171","msg":"trace[424455906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1837; }","duration":"116.572382ms","start":"2026-07-14T17:02:19.858485Z","end":"2026-07-14T17:02:19.975057Z","steps":["trace[424455906] 'agreement among raft nodes before linearized reading' (duration: 116.399436ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:20.134016Z","caller":"traceutil/trace.go:171","msg":"trace[547559914] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"154.331019ms","start":"2026-07-14T17:02:19.979657Z","end":"2026-07-14T17:02:20.133988Z","steps":["trace[547559914] 'process raft request' (duration: 132.36473ms)","trace[547559914] 'compare' (duration: 21.838955ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:22.612075Z","caller":"traceutil/trace.go:171","msg":"trace[161269542] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"155.534306ms","start":"2026-07-14T17:02:22.456514Z","end":"2026-07-14T17:02:22.612048Z","steps":["trace[161269542] 'process raft request' (duration: 155.33415ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:24.531782Z","caller":"traceutil/trace.go:171","msg":"trace[1028093860] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"176.532404ms","start":"2026-07-14T17:02:24.355224Z","end":"2026-07-14T17:02:24.531756Z","steps":["trace[1028093860] 'read index received' (duration: 92.351293ms)","trace[1028093860] 'applied index is now lower than readState.Index' (duration: 84.179801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:24.532254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.009388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:02:24.532415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.871381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:02:24.532502Z","caller":"traceutil/trace.go:171","msg":"trace[1688541174] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1857; }","duration":"149.996335ms","start":"2026-07-14T17:02:24.382485Z","end":"2026-07-14T17:02:24.532481Z","steps":["trace[1688541174] 'agreement among raft nodes before linearized reading' (duration: 149.860331ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:24.532682Z","caller":"traceutil/trace.go:171","msg":"trace[590252024] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1857; }","duration":"177.272416ms","start":"2026-07-14T17:02:24.355173Z","end":"2026-07-14T17:02:24.532445Z","steps":["trace[590252024] 'agreement among raft nodes before linearized reading' (duration: 177.003138ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:24.532816Z","caller":"traceutil/trace.go:171","msg":"trace[1951828827] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"379.434983ms","start":"2026-07-14T17:02:24.153338Z","end":"2026-07-14T17:02:24.532773Z","steps":["trace[1951828827] 'process raft request' (duration: 294.316983ms)","trace[1951828827] 'compare' (duration: 83.855971ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:24.533022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:24.153318Z","time spent":"379.611309ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:02:24.743973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.184485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:02:24.744062Z","caller":"traceutil/trace.go:171","msg":"trace[1155759624] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"115.820209ms","start":"2026-07-14T17:02:24.628229Z","end":"2026-07-14T17:02:24.744049Z","steps":["trace[1155759624] 'read index received' (duration: 5.435988ms)","trace[1155759624] 'applied index is now lower than readState.Index' (duration: 110.383281ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:24.744132Z","caller":"traceutil/trace.go:171","msg":"trace[1486035311] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"127.567662ms","start":"2026-07-14T17:02:24.616557Z","end":"2026-07-14T17:02:24.744124Z","steps":["trace[1486035311] 'process raft request' (duration: 17.14419ms)","trace[1486035311] 'compare' (duration: 110.082262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:24.744286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.041716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:02:24.744385Z","caller":"traceutil/trace.go:171","msg":"trace[1040220993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"116.144229ms","start":"2026-07-14T17:02:24.628196Z","end":"2026-07-14T17:02:24.74434Z","steps":["trace[1040220993] 'agreement among raft nodes before linearized reading' (duration: 116.033976ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:25.026946Z","caller":"traceutil/trace.go:171","msg":"trace[1827207737] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"103.126736ms","start":"2026-07-14T17:02:24.923795Z","end":"2026-07-14T17:02:25.026922Z","steps":["trace[1827207737] 'process raft request' (duration: 102.984322ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:27.034085Z","caller":"traceutil/trace.go:171","msg":"trace[1321222245] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"106.720898ms","start":"2026-07-14T17:02:26.92733Z","end":"2026-07-14T17:02:27.034051Z","steps":["trace[1321222245] 'process raft request' (duration: 106.204282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:27.435798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.424961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:02:27.435972Z","caller":"traceutil/trace.go:171","msg":"trace[1778117820] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1873; }","duration":"109.665759ms","start":"2026-07-14T17:02:27.32629Z","end":"2026-07-14T17:02:27.435956Z","steps":["trace[1778117820] 'count revisions from in-memory index tree' (duration: 109.235975ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:35.240049Z","caller":"traceutil/trace.go:171","msg":"trace[723990023] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"161.305455ms","start":"2026-07-14T17:02:35.078714Z","end":"2026-07-14T17:02:35.240019Z","steps":["trace[723990023] 'process raft request' (duration: 161.13278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:35.4266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.120822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-14T17:02:35.426687Z","caller":"traceutil/trace.go:171","msg":"trace[1542516042] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1907; }","duration":"163.258567ms","start":"2026-07-14T17:02:35.263407Z","end":"2026-07-14T17:02:35.426666Z","steps":["trace[1542516042] 'range keys from in-memory index tree' (duration: 162.892536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:35.426758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.09969ms","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-07-14T17:02:35.426839Z","caller":"traceutil/trace.go:171","msg":"trace[765497925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1907; }","duration":"122.222613ms","start":"2026-07-14T17:02:35.304597Z","end":"2026-07-14T17:02:35.42682Z","steps":["trace[765497925] 'range keys from in-memory index tree' (duration: 121.715828ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:35.599421Z","caller":"traceutil/trace.go:171","msg":"trace[1809355734] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"167.808849ms","start":"2026-07-14T17:02:35.431584Z","end":"2026-07-14T17:02:35.599393Z","steps":["trace[1809355734] 'process raft request' (duration: 103.4353ms)","trace[1809355734] 'compare' (duration: 64.241114ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:39.045578Z","caller":"traceutil/trace.go:171","msg":"trace[1227281170] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"107.970461ms","start":"2026-07-14T17:02:38.937572Z","end":"2026-07-14T17:02:39.045543Z","steps":["trace[1227281170] 'process raft request' (duration: 107.705623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:41.832449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.636713ms","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-07-14T17:02:41.832549Z","caller":"traceutil/trace.go:171","msg":"trace[1622091995] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1938; }","duration":"110.816119ms","start":"2026-07-14T17:02:41.721715Z","end":"2026-07-14T17:02:41.832531Z","steps":["trace[1622091995] 'range keys from in-memory index tree' (duration: 110.417736ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:51.479217Z","caller":"traceutil/trace.go:171","msg":"trace[709654458] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"118.718984ms","start":"2026-07-14T17:02:51.360478Z","end":"2026-07-14T17:02:51.479197Z","steps":["trace[709654458] 'read index received' (duration: 118.564339ms)","trace[709654458] 'applied index is now lower than readState.Index' (duration: 153.515µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:51.479407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.928791ms","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-07-14T17:02:51.479467Z","caller":"traceutil/trace.go:171","msg":"trace[1388185639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1980; }","duration":"119.038014ms","start":"2026-07-14T17:02:51.360415Z","end":"2026-07-14T17:02:51.479453Z","steps":["trace[1388185639] 'agreement among raft nodes before linearized reading' (duration: 118.876809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:52.373651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.476712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-14T17:02:52.373757Z","caller":"traceutil/trace.go:171","msg":"trace[2046595034] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1985; }","duration":"248.598566ms","start":"2026-07-14T17:02:52.125131Z","end":"2026-07-14T17:02:52.37373Z","steps":["trace[2046595034] 'range keys from in-memory index tree' (duration: 248.234414ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:55.283192Z","caller":"traceutil/trace.go:171","msg":"trace[1050819508] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2080; }","duration":"130.442519ms","start":"2026-07-14T17:02:55.152732Z","end":"2026-07-14T17:02:55.283175Z","steps":["trace[1050819508] 'read index received' (duration: 130.320715ms)","trace[1050819508] 'applied index is now lower than readState.Index' (duration: 121.074µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:55.283225Z","caller":"traceutil/trace.go:171","msg":"trace[1546827559] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"154.62397ms","start":"2026-07-14T17:02:55.128575Z","end":"2026-07-14T17:02:55.283199Z","steps":["trace[1546827559] 'process raft request' (duration: 154.488355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:55.283356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.617964ms","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-07-14T17:02:55.283388Z","caller":"traceutil/trace.go:171","msg":"trace[1267878343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1998; }","duration":"130.692046ms","start":"2026-07-14T17:02:55.152686Z","end":"2026-07-14T17:02:55.283378Z","steps":["trace[1267878343] 'agreement among raft nodes before linearized reading' (duration: 130.585773ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:56.923292Z","caller":"traceutil/trace.go:171","msg":"trace[921058013] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"285.570133ms","start":"2026-07-14T17:02:56.637698Z","end":"2026-07-14T17:02:56.923269Z","steps":["trace[921058013] 'process raft request' (duration: 285.427989ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:57.030714Z","caller":"traceutil/trace.go:171","msg":"trace[79848006] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2086; }","duration":"282.835538ms","start":"2026-07-14T17:02:56.747853Z","end":"2026-07-14T17:02:57.030688Z","steps":["trace[79848006] 'read index received' (duration: 175.330152ms)","trace[79848006] 'applied index is now lower than readState.Index' (duration: 107.504026ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:57.03096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.091247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:02:57.031047Z","caller":"traceutil/trace.go:171","msg":"trace[928633835] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2004; }","duration":"283.239741ms","start":"2026-07-14T17:02:56.747788Z","end":"2026-07-14T17:02:57.031027Z","steps":["trace[928633835] 'agreement among raft nodes before linearized reading' (duration: 283.023884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:57.031093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.315719ms","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-07-14T17:02:57.031166Z","caller":"traceutil/trace.go:171","msg":"trace[439857534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2004; }","duration":"250.445834ms","start":"2026-07-14T17:02:56.7807Z","end":"2026-07-14T17:02:57.031146Z","steps":["trace[439857534] 'agreement among raft nodes before linearized reading' (duration: 250.133584ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:59.933596Z","caller":"traceutil/trace.go:171","msg":"trace[1799098339] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"178.270903ms","start":"2026-07-14T17:02:59.755292Z","end":"2026-07-14T17:02:59.933563Z","steps":["trace[1799098339] 'process raft request' (duration: 93.037478ms)","trace[1799098339] 'compare' (duration: 85.100781ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:01.130101Z","caller":"traceutil/trace.go:171","msg":"trace[23717108] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"103.306236ms","start":"2026-07-14T17:03:01.026766Z","end":"2026-07-14T17:03:01.130073Z","steps":["trace[23717108] 'process raft request' (duration: 103.12958ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:01.540729Z","caller":"traceutil/trace.go:171","msg":"trace[1999390537] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2110; }","duration":"179.988176ms","start":"2026-07-14T17:03:01.360721Z","end":"2026-07-14T17:03:01.540709Z","steps":["trace[1999390537] 'read index received' (duration: 131.598014ms)","trace[1999390537] 'applied index is now lower than readState.Index' (duration: 48.389292ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:01.54083Z","caller":"traceutil/trace.go:171","msg":"trace[2145676778] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"199.635017ms","start":"2026-07-14T17:03:01.341183Z","end":"2026-07-14T17:03:01.540818Z","steps":["trace[2145676778] 'process raft request' (duration: 151.024028ms)","trace[2145676778] 'compare' (duration: 48.380321ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:03:01.540869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.144612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:03:01.540898Z","caller":"traceutil/trace.go:171","msg":"trace[696938941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2026; }","duration":"180.224684ms","start":"2026-07-14T17:03:01.360666Z","end":"2026-07-14T17:03:01.540891Z","steps":["trace[696938941] 'agreement among raft nodes before linearized reading' (duration: 180.11469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:02.543929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.681399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-14T17:03:02.544026Z","caller":"traceutil/trace.go:171","msg":"trace[1900178503] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2030; }","duration":"422.827053ms","start":"2026-07-14T17:03:02.121177Z","end":"2026-07-14T17:03:02.544004Z","steps":["trace[1900178503] 'range keys from in-memory index tree' (duration: 422.531965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:02.544078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:02.121161Z","time spent":"422.905456ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-14T17:03:02.544096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.131169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:02.544163Z","caller":"traceutil/trace.go:171","msg":"trace[1443546019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2030; }","duration":"361.250032ms","start":"2026-07-14T17:03:02.182895Z","end":"2026-07-14T17:03:02.544145Z","steps":["trace[1443546019] 'range keys from in-memory index tree' (duration: 361.038205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:02.544189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:02.182873Z","time spent":"361.310285ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:03:13.850227Z","caller":"traceutil/trace.go:171","msg":"trace[1589232306] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"101.79969ms","start":"2026-07-14T17:03:13.748393Z","end":"2026-07-14T17:03:13.850192Z","steps":["trace[1589232306] 'process raft request' (duration: 49.113725ms)","trace[1589232306] 'compare' (duration: 52.243511ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:18.0541Z","caller":"traceutil/trace.go:171","msg":"trace[438623149] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"109.53395ms","start":"2026-07-14T17:03:17.944541Z","end":"2026-07-14T17:03:18.054074Z","steps":["trace[438623149] 'process raft request' (duration: 109.396766ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:27.991557Z","caller":"traceutil/trace.go:171","msg":"trace[77958662] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"100.761537ms","start":"2026-07-14T17:03:27.890778Z","end":"2026-07-14T17:03:27.991539Z","steps":["trace[77958662] 'read index received' (duration: 100.684705ms)","trace[77958662] 'applied index is now lower than readState.Index' (duration: 75.772µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:27.9918Z","caller":"traceutil/trace.go:171","msg":"trace[1659110536] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"146.476216ms","start":"2026-07-14T17:03:27.845315Z","end":"2026-07-14T17:03:27.991791Z","steps":["trace[1659110536] 'process raft request' (duration: 146.047173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:27.991998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.214892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:03:27.992032Z","caller":"traceutil/trace.go:171","msg":"trace[1382572369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2145; }","duration":"101.291404ms","start":"2026-07-14T17:03:27.890733Z","end":"2026-07-14T17:03:27.992024Z","steps":["trace[1382572369] 'agreement among raft nodes before linearized reading' (duration: 101.187651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:28.039811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.359166ms","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-07-14T17:03:28.04011Z","caller":"traceutil/trace.go:171","msg":"trace[1735504644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2146; }","duration":"120.787289ms","start":"2026-07-14T17:03:27.919304Z","end":"2026-07-14T17:03:28.040092Z","steps":["trace[1735504644] 'agreement among raft nodes before linearized reading' (duration: 120.16574ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:28.174514Z","caller":"traceutil/trace.go:171","msg":"trace[414365774] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"178.234292ms","start":"2026-07-14T17:03:27.996247Z","end":"2026-07-14T17:03:28.174482Z","steps":["trace[414365774] 'process raft request' (duration: 129.949013ms)","trace[414365774] 'compare' (duration: 47.870176ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:28.174696Z","caller":"traceutil/trace.go:171","msg":"trace[1524676702] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"129.283242ms","start":"2026-07-14T17:03:28.045392Z","end":"2026-07-14T17:03:28.174675Z","steps":["trace[1524676702] 'process raft request' (duration: 129.018794ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:34.154206Z","caller":"traceutil/trace.go:171","msg":"trace[427954285] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"140.200462ms","start":"2026-07-14T17:03:34.013973Z","end":"2026-07-14T17:03:34.154174Z","steps":["trace[427954285] 'process raft request' (duration: 121.285745ms)","trace[427954285] 'compare' (duration: 18.773953ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:34.364111Z","caller":"traceutil/trace.go:171","msg":"trace[291043352] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2265; }","duration":"133.106833ms","start":"2026-07-14T17:03:34.230989Z","end":"2026-07-14T17:03:34.364096Z","steps":["trace[291043352] 'read index received' (duration: 59.7272ms)","trace[291043352] 'applied index is now lower than readState.Index' (duration: 73.378793ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:34.364287Z","caller":"traceutil/trace.go:171","msg":"trace[1947105283] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"165.039305ms","start":"2026-07-14T17:03:34.199238Z","end":"2026-07-14T17:03:34.364277Z","steps":["trace[1947105283] 'process raft request' (duration: 91.506528ms)","trace[1947105283] 'compare' (duration: 73.097174ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:34.364497Z","caller":"traceutil/trace.go:171","msg":"trace[2143731374] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"164.017083ms","start":"2026-07-14T17:03:34.200473Z","end":"2026-07-14T17:03:34.36449Z","steps":["trace[2143731374] 'process raft request' (duration: 163.59708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:34.36458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.094329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-14T17:03:34.364656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.66133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:34.364678Z","caller":"traceutil/trace.go:171","msg":"trace[667301608] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2176; }","duration":"133.740883ms","start":"2026-07-14T17:03:34.230931Z","end":"2026-07-14T17:03:34.364672Z","steps":["trace[667301608] 'agreement among raft nodes before linearized reading' (duration: 133.689311ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:34.36469Z","caller":"traceutil/trace.go:171","msg":"trace[768924775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2176; }","duration":"125.244034ms","start":"2026-07-14T17:03:34.239419Z","end":"2026-07-14T17:03:34.364663Z","steps":["trace[768924775] 'agreement among raft nodes before linearized reading' (duration: 124.956444ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:35.227777Z","caller":"traceutil/trace.go:171","msg":"trace[1481130521] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"158.854017ms","start":"2026-07-14T17:03:35.068907Z","end":"2026-07-14T17:03:35.227761Z","steps":["trace[1481130521] 'read index received' (duration: 158.697402ms)","trace[1481130521] 'applied index is now lower than readState.Index' (duration: 155.845µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:35.227937Z","caller":"traceutil/trace.go:171","msg":"trace[1206685386] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"246.080737ms","start":"2026-07-14T17:03:34.981823Z","end":"2026-07-14T17:03:35.227904Z","steps":["trace[1206685386] 'process raft request' (duration: 245.785938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:35.228028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.107155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-14T17:03:35.228063Z","caller":"traceutil/trace.go:171","msg":"trace[1579999717] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2179; }","duration":"159.188997ms","start":"2026-07-14T17:03:35.068865Z","end":"2026-07-14T17:03:35.228054Z","steps":["trace[1579999717] 'agreement among raft nodes before linearized reading' (duration: 159.028002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:35.228081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.095791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:35.228189Z","caller":"traceutil/trace.go:171","msg":"trace[456605240] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2179; }","duration":"114.252675ms","start":"2026-07-14T17:03:35.113902Z","end":"2026-07-14T17:03:35.228154Z","steps":["trace[456605240] 'agreement among raft nodes before linearized reading' (duration: 114.08529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:35.52957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.323133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:03:35.529808Z","caller":"traceutil/trace.go:171","msg":"trace[831147359] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"297.04351ms","start":"2026-07-14T17:03:35.232736Z","end":"2026-07-14T17:03:35.529779Z","steps":["trace[831147359] 'process raft request' (duration: 118.435339ms)","trace[831147359] 'compare' (duration: 178.085145ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:38.306519Z","caller":"traceutil/trace.go:171","msg":"trace[1624059890] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"140.146625ms","start":"2026-07-14T17:03:38.166351Z","end":"2026-07-14T17:03:38.306497Z","steps":["trace[1624059890] 'process raft request' (duration: 140.011161ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:39.030001Z","caller":"traceutil/trace.go:171","msg":"trace[415680091] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"144.493123ms","start":"2026-07-14T17:03:38.885486Z","end":"2026-07-14T17:03:39.029979Z","steps":["trace[415680091] 'process raft request' (duration: 143.800121ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:47.274642Z","caller":"traceutil/trace.go:171","msg":"trace[1291196670] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"121.380601ms","start":"2026-07-14T17:03:47.153232Z","end":"2026-07-14T17:03:47.274613Z","steps":["trace[1291196670] 'process raft request' (duration: 78.254137ms)","trace[1291196670] 'compare' (duration: 42.635239ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:48.899236Z","caller":"traceutil/trace.go:171","msg":"trace[1731414112] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"121.168575ms","start":"2026-07-14T17:03:48.778044Z","end":"2026-07-14T17:03:48.899212Z","steps":["trace[1731414112] 'process raft request' (duration: 117.353264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:50.872479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.895583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:03:50.8726Z","caller":"traceutil/trace.go:171","msg":"trace[1496982343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2247; }","duration":"156.055788ms","start":"2026-07-14T17:03:50.716524Z","end":"2026-07-14T17:03:50.87258Z","steps":["trace[1496982343] 'range keys from in-memory index tree' (duration: 155.656146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:55.875404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.006129ms","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-07-14T17:03:55.875486Z","caller":"traceutil/trace.go:171","msg":"trace[1132067103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2272; }","duration":"133.135273ms","start":"2026-07-14T17:03:55.742333Z","end":"2026-07-14T17:03:55.875468Z","steps":["trace[1132067103] 'range keys from in-memory index tree' (duration: 132.850744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:59.79512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.16702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6771"} {"level":"info","ts":"2026-07-14T17:03:59.795324Z","caller":"traceutil/trace.go:171","msg":"trace[315516168] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2290; }","duration":"112.416817ms","start":"2026-07-14T17:03:59.682881Z","end":"2026-07-14T17:03:59.795298Z","steps":["trace[315516168] 'range keys from in-memory index tree' (duration: 111.948463ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:00.756389Z","caller":"traceutil/trace.go:171","msg":"trace[1869097139] linearizableReadLoop","detail":"{readStateIndex:2388; appliedIndex:2387; }","duration":"117.22923ms","start":"2026-07-14T17:04:00.639134Z","end":"2026-07-14T17:04:00.756363Z","steps":["trace[1869097139] 'read index received' (duration: 117.088235ms)","trace[1869097139] 'applied index is now lower than readState.Index' (duration: 139.985µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:04:00.756463Z","caller":"traceutil/trace.go:171","msg":"trace[1363948784] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"214.087495ms","start":"2026-07-14T17:04:00.542337Z","end":"2026-07-14T17:04:00.756424Z","steps":["trace[1363948784] 'process raft request' (duration: 213.871828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:00.756621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.483878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-14T17:04:00.756665Z","caller":"traceutil/trace.go:171","msg":"trace[963346848] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2292; }","duration":"117.587301ms","start":"2026-07-14T17:04:00.639067Z","end":"2026-07-14T17:04:00.756654Z","steps":["trace[963346848] 'agreement among raft nodes before linearized reading' (duration: 117.398605ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:01.600129Z","caller":"traceutil/trace.go:171","msg":"trace[1849031401] linearizableReadLoop","detail":"{readStateIndex:2395; appliedIndex:2394; }","duration":"126.209274ms","start":"2026-07-14T17:04:01.473896Z","end":"2026-07-14T17:04:01.600105Z","steps":["trace[1849031401] 'read index received' (duration: 126.017288ms)","trace[1849031401] 'applied index is now lower than readState.Index' (duration: 191.096µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:04:01.600238Z","caller":"traceutil/trace.go:171","msg":"trace[1530378210] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"182.33169ms","start":"2026-07-14T17:04:01.417846Z","end":"2026-07-14T17:04:01.600178Z","steps":["trace[1530378210] 'process raft request' (duration: 182.119163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:01.600295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.37418ms","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-07-14T17:04:01.600469Z","caller":"traceutil/trace.go:171","msg":"trace[1439623481] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2298; }","duration":"126.602327ms","start":"2026-07-14T17:04:01.473849Z","end":"2026-07-14T17:04:01.600452Z","steps":["trace[1439623481] 'agreement among raft nodes before linearized reading' (duration: 126.37929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:02.292067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.704967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:04:02.292179Z","caller":"traceutil/trace.go:171","msg":"trace[1067360361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2300; }","duration":"106.898112ms","start":"2026-07-14T17:04:02.185261Z","end":"2026-07-14T17:04:02.292159Z","steps":["trace[1067360361] 'range keys from in-memory index tree' (duration: 106.584763ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:02.436044Z","caller":"traceutil/trace.go:171","msg":"trace[117604160] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"107.843613ms","start":"2026-07-14T17:04:02.328184Z","end":"2026-07-14T17:04:02.436028Z","steps":["trace[117604160] 'process raft request' (duration: 107.735329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:02.712189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.475845ms","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-07-14T17:04:02.712315Z","caller":"traceutil/trace.go:171","msg":"trace[1541243239] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2301; }","duration":"195.61239ms","start":"2026-07-14T17:04:02.516684Z","end":"2026-07-14T17:04:02.712296Z","steps":["trace[1541243239] 'range keys from in-memory index tree' (duration: 195.454815ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:02.89331Z","caller":"traceutil/trace.go:171","msg":"trace[1839244549] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"129.621882ms","start":"2026-07-14T17:04:02.763667Z","end":"2026-07-14T17:04:02.893289Z","steps":["trace[1839244549] 'process raft request' (duration: 118.873682ms)","trace[1839244549] 'compare' (duration: 10.657688ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:04:02.935128Z","caller":"traceutil/trace.go:171","msg":"trace[1077832205] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"144.206883ms","start":"2026-07-14T17:04:02.790893Z","end":"2026-07-14T17:04:02.935099Z","steps":["trace[1077832205] 'process raft request' (duration: 144.063699ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:11.009011Z","caller":"traceutil/trace.go:171","msg":"trace[1601434810] transaction","detail":"{read_only:false; response_revision:2338; number_of_response:1; }","duration":"191.614504ms","start":"2026-07-14T17:04:10.817378Z","end":"2026-07-14T17:04:11.008993Z","steps":["trace[1601434810] 'process raft request' (duration: 191.50214ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:11.023415Z","caller":"traceutil/trace.go:171","msg":"trace[330601184] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"165.529118ms","start":"2026-07-14T17:04:10.857861Z","end":"2026-07-14T17:04:11.02339Z","steps":["trace[330601184] 'process raft request' (duration: 165.398334ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:12.229871Z","caller":"traceutil/trace.go:171","msg":"trace[1609583398] transaction","detail":"{read_only:false; response_revision:2346; number_of_response:1; }","duration":"117.85153ms","start":"2026-07-14T17:04:12.111994Z","end":"2026-07-14T17:04:12.229846Z","steps":["trace[1609583398] 'process raft request' (duration: 117.659624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:24.520046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.215423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:04:24.520207Z","caller":"traceutil/trace.go:171","msg":"trace[534806803] linearizableReadLoop","detail":"{readStateIndex:2504; appliedIndex:2503; }","duration":"120.230284ms","start":"2026-07-14T17:04:24.399962Z","end":"2026-07-14T17:04:24.520192Z","steps":["trace[534806803] 'read index received' (duration: 12.790425ms)","trace[534806803] 'applied index is now lower than readState.Index' (duration: 107.438359ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:04:24.520231Z","caller":"traceutil/trace.go:171","msg":"trace[434647089] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"137.020396ms","start":"2026-07-14T17:04:24.38319Z","end":"2026-07-14T17:04:24.52021Z","steps":["trace[434647089] 'process raft request' (duration: 29.551195ms)","trace[434647089] 'compare' (duration: 106.620324ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:04:24.520354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.364629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:04:24.520452Z","caller":"traceutil/trace.go:171","msg":"trace[1552170688] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2403; }","duration":"120.484393ms","start":"2026-07-14T17:04:24.399948Z","end":"2026-07-14T17:04:24.520432Z","steps":["trace[1552170688] 'agreement among raft nodes before linearized reading' (duration: 120.296117ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:34.03362Z","caller":"traceutil/trace.go:171","msg":"trace[942377557] linearizableReadLoop","detail":"{readStateIndex:2549; appliedIndex:2548; }","duration":"148.653074ms","start":"2026-07-14T17:04:33.884951Z","end":"2026-07-14T17:04:34.033604Z","steps":["trace[942377557] 'read index received' (duration: 148.476848ms)","trace[942377557] 'applied index is now lower than readState.Index' (duration: 175.546µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:04:34.033723Z","caller":"traceutil/trace.go:171","msg":"trace[564611689] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"173.056296ms","start":"2026-07-14T17:04:33.860629Z","end":"2026-07-14T17:04:34.033685Z","steps":["trace[564611689] 'process raft request' (duration: 172.85177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:34.033744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.772808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:04:34.033867Z","caller":"traceutil/trace.go:171","msg":"trace[2040388341] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2446; }","duration":"148.907403ms","start":"2026-07-14T17:04:33.884943Z","end":"2026-07-14T17:04:34.033851Z","steps":["trace[2040388341] 'agreement among raft nodes before linearized reading' (duration: 148.733167ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:37.812778Z","caller":"traceutil/trace.go:171","msg":"trace[1642169192] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"116.943911ms","start":"2026-07-14T17:04:37.69579Z","end":"2026-07-14T17:04:37.812734Z","steps":["trace[1642169192] 'process raft request' (duration: 63.960134ms)","trace[1642169192] 'compare' (duration: 52.834352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:04:41.82929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.779802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:04:41.829366Z","caller":"traceutil/trace.go:171","msg":"trace[983476596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2479; }","duration":"169.910066ms","start":"2026-07-14T17:04:41.659438Z","end":"2026-07-14T17:04:41.829348Z","steps":["trace[983476596] 'range keys from in-memory index tree' (duration: 169.615517ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:42.019546Z","caller":"traceutil/trace.go:171","msg":"trace[1963012242] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"186.478591ms","start":"2026-07-14T17:04:41.833043Z","end":"2026-07-14T17:04:42.019521Z","steps":["trace[1963012242] 'process raft request' (duration: 186.322066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:42.497953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.237168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:04:42.498066Z","caller":"traceutil/trace.go:171","msg":"trace[192539632] linearizableReadLoop","detail":"{readStateIndex:2586; appliedIndex:2585; }","duration":"420.021468ms","start":"2026-07-14T17:04:42.078027Z","end":"2026-07-14T17:04:42.498049Z","steps":["trace[192539632] 'read index received' (duration: 255.504251ms)","trace[192539632] 'applied index is now lower than readState.Index' (duration: 164.515797ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:04:42.49828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.121087ms","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-07-14T17:04:42.498166Z","caller":"traceutil/trace.go:171","msg":"trace[1225020768] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"664.325236ms","start":"2026-07-14T17:04:41.833808Z","end":"2026-07-14T17:04:42.498133Z","steps":["trace[1225020768] 'process raft request' (duration: 499.823909ms)","trace[1225020768] 'compare' (duration: 163.937859ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:04:42.49832Z","caller":"traceutil/trace.go:171","msg":"trace[349997243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2481; }","duration":"279.193979ms","start":"2026-07-14T17:04:42.219114Z","end":"2026-07-14T17:04:42.498308Z","steps":["trace[349997243] 'agreement among raft nodes before linearized reading' (duration: 279.031224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:42.498461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:41.833786Z","time spent":"664.602594ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:04:42.498703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.665468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-14T17:04:42.498799Z","caller":"traceutil/trace.go:171","msg":"trace[1965866330] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2481; }","duration":"420.806523ms","start":"2026-07-14T17:04:42.077969Z","end":"2026-07-14T17:04:42.498776Z","steps":["trace[1965866330] 'agreement among raft nodes before linearized reading' (duration: 420.169223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:42.498851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:42.077946Z","time spent":"420.891625ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-14T17:04:42.811558Z","caller":"traceutil/trace.go:171","msg":"trace[1273229097] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"308.602698ms","start":"2026-07-14T17:04:42.502932Z","end":"2026-07-14T17:04:42.811534Z","steps":["trace[1273229097] 'process raft request' (duration: 217.969106ms)","trace[1273229097] 'compare' (duration: 90.532919ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:04:42.811696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:42.502916Z","time spent":"308.712801ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:04:42.837542Z","caller":"traceutil/trace.go:171","msg":"trace[1973272863] linearizableReadLoop","detail":"{readStateIndex:2589; appliedIndex:2586; }","duration":"289.495034ms","start":"2026-07-14T17:04:42.548023Z","end":"2026-07-14T17:04:42.837518Z","steps":["trace[1973272863] 'read index received' (duration: 173.008635ms)","trace[1973272863] 'applied index is now lower than readState.Index' (duration: 116.485389ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:04:42.837593Z","caller":"traceutil/trace.go:171","msg":"trace[1185713120] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"333.776043ms","start":"2026-07-14T17:04:42.503786Z","end":"2026-07-14T17:04:42.837562Z","steps":["trace[1185713120] 'process raft request' (duration: 333.601468ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:42.837681Z","caller":"traceutil/trace.go:171","msg":"trace[868098544] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"333.749772ms","start":"2026-07-14T17:04:42.503905Z","end":"2026-07-14T17:04:42.837655Z","steps":["trace[868098544] 'process raft request' (duration: 333.582887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:42.837718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.683821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-14T17:04:42.837747Z","caller":"traceutil/trace.go:171","msg":"trace[588773053] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2484; }","duration":"289.750783ms","start":"2026-07-14T17:04:42.547986Z","end":"2026-07-14T17:04:42.837737Z","steps":["trace[588773053] 'agreement among raft nodes before linearized reading' (duration: 289.598668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:42.837755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:42.50377Z","time spent":"333.898147ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:04:42.837776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:42.503879Z","time spent":"333.848296ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:04:56.533956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.931264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:04:56.534198Z","caller":"traceutil/trace.go:171","msg":"trace[2058467673] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"401.307687ms","start":"2026-07-14T17:04:56.13286Z","end":"2026-07-14T17:04:56.534167Z","steps":["trace[2058467673] 'process raft request' (duration: 133.096644ms)","trace[2058467673] 'compare' (duration: 267.735368ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:04:56.534303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:56.132834Z","time spent":"401.420201ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:04:56.534358Z","caller":"traceutil/trace.go:171","msg":"trace[343223411] linearizableReadLoop","detail":"{readStateIndex:2674; appliedIndex:2673; }","duration":"351.037469ms","start":"2026-07-14T17:04:56.183301Z","end":"2026-07-14T17:04:56.534338Z","steps":["trace[343223411] 'read index received' (duration: 82.62925ms)","trace[343223411] 'applied index is now lower than readState.Index' (duration: 268.405989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:04:56.538954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.627804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:04:56.539059Z","caller":"traceutil/trace.go:171","msg":"trace[288568148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2566; }","duration":"355.770649ms","start":"2026-07-14T17:04:56.183264Z","end":"2026-07-14T17:04:56.539035Z","steps":["trace[288568148] 'agreement among raft nodes before linearized reading' (duration: 351.154093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:56.539114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:56.183247Z","time spent":"355.857801ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:04:58.405764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.99262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:04:58.405828Z","caller":"traceutil/trace.go:171","msg":"trace[1771083587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2573; }","duration":"221.108583ms","start":"2026-07-14T17:04:58.184706Z","end":"2026-07-14T17:04:58.405815Z","steps":["trace[1771083587] 'range keys from in-memory index tree' (duration: 220.893976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:58.405766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.38778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-14T17:04:58.406102Z","caller":"traceutil/trace.go:171","msg":"trace[1988339071] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2573; }","duration":"339.757191ms","start":"2026-07-14T17:04:58.066311Z","end":"2026-07-14T17:04:58.406068Z","steps":["trace[1988339071] 'range keys from in-memory index tree' (duration: 339.205714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:58.406138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:58.066296Z","time spent":"339.830434ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-14T17:04:58.406267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.664327ms","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-07-14T17:04:58.406361Z","caller":"traceutil/trace.go:171","msg":"trace[1185534165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2573; }","duration":"319.872704ms","start":"2026-07-14T17:04:58.086464Z","end":"2026-07-14T17:04:58.406337Z","steps":["trace[1185534165] 'range keys from in-memory index tree' (duration: 319.483711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:04:58.406452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:04:58.086428Z","time spent":"319.989737ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T17:05:00.69931Z","caller":"traceutil/trace.go:171","msg":"trace[1733740750] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"109.897401ms","start":"2026-07-14T17:05:00.589387Z","end":"2026-07-14T17:05:00.699285Z","steps":["trace[1733740750] 'process raft request' (duration: 96.641843ms)","trace[1733740750] 'compare' (duration: 13.163626ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:05:06.440078Z","caller":"traceutil/trace.go:171","msg":"trace[681502679] linearizableReadLoop","detail":"{readStateIndex:2726; appliedIndex:2725; }","duration":"254.478229ms","start":"2026-07-14T17:05:06.185573Z","end":"2026-07-14T17:05:06.440051Z","steps":["trace[681502679] 'read index received' (duration: 254.440607ms)","trace[681502679] 'applied index is now lower than readState.Index' (duration: 36.422µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:05:06.440443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.85612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T17:05:06.440552Z","caller":"traceutil/trace.go:171","msg":"trace[795039080] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2616; }","duration":"254.986074ms","start":"2026-07-14T17:05:06.185503Z","end":"2026-07-14T17:05:06.440489Z","steps":["trace[795039080] 'agreement among raft nodes before linearized reading' (duration: 254.656714ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:06.44097Z","caller":"traceutil/trace.go:171","msg":"trace[11857928] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"760.866134ms","start":"2026-07-14T17:05:05.680085Z","end":"2026-07-14T17:05:06.440951Z","steps":["trace[11857928] 'process raft request' (duration: 759.808541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:05:06.441207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:05:05.680073Z","time spent":"760.972248ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:05:17.202749Z","caller":"traceutil/trace.go:171","msg":"trace[142183022] linearizableReadLoop","detail":"{readStateIndex:2789; appliedIndex:2787; }","duration":"101.493966ms","start":"2026-07-14T17:05:17.10123Z","end":"2026-07-14T17:05:17.202724Z","steps":["trace[142183022] 'read index received' (duration: 4.428409ms)","trace[142183022] 'applied index is now lower than readState.Index' (duration: 97.064377ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:05:17.202887Z","caller":"traceutil/trace.go:171","msg":"trace[1893769528] transaction","detail":"{read_only:false; response_revision:2675; number_of_response:1; }","duration":"111.976537ms","start":"2026-07-14T17:05:17.090898Z","end":"2026-07-14T17:05:17.202874Z","steps":["trace[1893769528] 'process raft request' (duration: 111.266975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:05:17.203271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.004423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-07-14T17:05:17.203487Z","caller":"traceutil/trace.go:171","msg":"trace[1875170282] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:2675; }","duration":"102.25376ms","start":"2026-07-14T17:05:17.101202Z","end":"2026-07-14T17:05:17.203456Z","steps":["trace[1875170282] 'agreement among raft nodes before linearized reading' (duration: 101.92978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:05:31.979391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.681089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-07-14T17:05:31.979484Z","caller":"traceutil/trace.go:171","msg":"trace[759848285] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:2742; }","duration":"162.818364ms","start":"2026-07-14T17:05:31.816646Z","end":"2026-07-14T17:05:31.979465Z","steps":["trace[759848285] 'range keys from in-memory index tree' (duration: 162.587036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:05:31.979489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.660645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"info","ts":"2026-07-14T17:05:31.979586Z","caller":"traceutil/trace.go:171","msg":"trace[489016470] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:2742; }","duration":"141.79993ms","start":"2026-07-14T17:05:31.837764Z","end":"2026-07-14T17:05:31.979564Z","steps":["trace[489016470] 'range keys from in-memory index tree' (duration: 141.547691ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:32.833982Z","caller":"traceutil/trace.go:171","msg":"trace[1666294174] linearizableReadLoop","detail":"{readStateIndex:2860; appliedIndex:2859; }","duration":"257.98944ms","start":"2026-07-14T17:05:32.575972Z","end":"2026-07-14T17:05:32.833961Z","steps":["trace[1666294174] 'read index received' (duration: 257.886347ms)","trace[1666294174] 'applied index is now lower than readState.Index' (duration: 102.253µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:05:32.834106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.119284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-14T17:05:32.834091Z","caller":"traceutil/trace.go:171","msg":"trace[505053973] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"266.321743ms","start":"2026-07-14T17:05:32.567742Z","end":"2026-07-14T17:05:32.834064Z","steps":["trace[505053973] 'process raft request' (duration: 266.102446ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:32.834134Z","caller":"traceutil/trace.go:171","msg":"trace[2088695325] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:2743; }","duration":"258.158405ms","start":"2026-07-14T17:05:32.575967Z","end":"2026-07-14T17:05:32.834125Z","steps":["trace[2088695325] 'agreement among raft nodes before linearized reading' (duration: 258.096963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:05:32.834254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.126297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubelet-config\" ","response":"range_response_count:1 size:345"} {"level":"warn","ts":"2026-07-14T17:05:32.834322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.93773ms","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-07-14T17:05:32.83434Z","caller":"traceutil/trace.go:171","msg":"trace[994834648] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:2743; }","duration":"203.259841ms","start":"2026-07-14T17:05:32.63106Z","end":"2026-07-14T17:05:32.83432Z","steps":["trace[994834648] 'agreement among raft nodes before linearized reading' (duration: 203.084336ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:32.83436Z","caller":"traceutil/trace.go:171","msg":"trace[2045963493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2743; }","duration":"108.021202ms","start":"2026-07-14T17:05:32.726329Z","end":"2026-07-14T17:05:32.83435Z","steps":["trace[2045963493] 'agreement among raft nodes before linearized reading' (duration: 107.832826ms)"],"step_count":1}