{"level":"warn","ts":"2026-08-16T12:50:17.631536Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:50:17.631617Z","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-08-16T12:50:17.631785Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:50:17.631802Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-16T12:50:17.631895Z","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-08-16T12:50:17.632567Z","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-08-16T12:50:17.632705Z","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-08-16T12:50:17.635255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.275634ms"} {"level":"info","ts":"2026-08-16T12:50:17.640419Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-08-16T12:50:17.640725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:50:17.640765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-08-16T12:50:17.640787Z","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-08-16T12:50:17.640804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-08-16T12:50:17.640856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-08-16T12:50:17.645332Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:50:17.64668Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:50:17.647887Z","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-08-16T12:50:17.65045Z","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-08-16T12:50:17.650676Z","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-08-16T12:50:17.650953Z","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-08-16T12:50:17.651743Z","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-08-16T12:50:17.651827Z","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-08-16T12:50:17.652726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-08-16T12:50:17.654807Z","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-08-16T12:50:17.659744Z","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-08-16T12:50:17.659866Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-16T12:50:17.659901Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-16T12:50:17.659979Z","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-08-16T12:50:17.660007Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:50:17.841845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:50:17.841913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:50:17.841958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-08-16T12:50:17.842054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:50:17.842111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-16T12:50:17.842128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-08-16T12:50:17.842187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-16T12:50:17.851886Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:50:17.854652Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:50:17.855576Z","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-08-16T12:50:17.855874Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:50:17.856012Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:50:17.856532Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:50:17.85709Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:50:17.857151Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-16T12:50:17.857836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-08-16T12:50:17.854612Z","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-08-16T12:50:17.862551Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:50:28.96155Z","caller":"traceutil/trace.go:171","msg":"trace[498198188] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"138.824404ms","start":"2026-08-16T12:50:28.822696Z","end":"2026-08-16T12:50:28.96152Z","steps":["trace[498198188] 'process raft request' (duration: 138.27172ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:28.961921Z","caller":"traceutil/trace.go:171","msg":"trace[165977191] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"122.857112ms","start":"2026-08-16T12:50:28.839035Z","end":"2026-08-16T12:50:28.961892Z","steps":["trace[165977191] 'process raft request' (duration: 122.739289ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:34.411026Z","caller":"traceutil/trace.go:171","msg":"trace[1712796316] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"127.619406ms","start":"2026-08-16T12:50:34.283377Z","end":"2026-08-16T12:50:34.410996Z","steps":["trace[1712796316] 'process raft request' (duration: 127.409672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:35.07834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642531935,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:50:35.578743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642531935,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:50:35.739809Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.298149607s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:50:35.740224Z","caller":"traceutil/trace.go:171","msg":"trace[931453259] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"1.162357415s","start":"2026-08-16T12:50:34.577844Z","end":"2026-08-16T12:50:35.740202Z","steps":["trace[931453259] 'read index received' (duration: 1.16214561s)","trace[931453259] 'applied index is now lower than readState.Index' (duration: 210.745µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:50:35.7407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162846548s","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-08-16T12:50:35.740838Z","caller":"traceutil/trace.go:171","msg":"trace[575662510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"1.163040663s","start":"2026-08-16T12:50:34.577779Z","end":"2026-08-16T12:50:35.74082Z","steps":["trace[575662510] 'agreement among raft nodes before linearized reading' (duration: 1.162829298s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:35.740862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.961934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:50:35.740885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:34.577757Z","time spent":"1.163115495s","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-16T12:50:35.74089Z","caller":"traceutil/trace.go:171","msg":"trace[1726536470] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.316964388s","start":"2026-08-16T12:50:34.423881Z","end":"2026-08-16T12:50:35.740845Z","steps":["trace[1726536470] 'process raft request' (duration: 1.316198209s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:35.741008Z","caller":"traceutil/trace.go:171","msg":"trace[1909893009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"865.110587ms","start":"2026-08-16T12:50:34.875824Z","end":"2026-08-16T12:50:35.740935Z","steps":["trace[1909893009] 'agreement among raft nodes before linearized reading' (duration: 864.945593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:35.741099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:34.875793Z","time spent":"865.293272ms","remote":"127.0.0.1:45254","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-08-16T12:50:35.741394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.513641ms","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-08-16T12:50:35.741452Z","caller":"traceutil/trace.go:171","msg":"trace[896270768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"144.612993ms","start":"2026-08-16T12:50:35.596826Z","end":"2026-08-16T12:50:35.741439Z","steps":["trace[896270768] 'agreement among raft nodes before linearized reading' (duration: 144.511851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:35.741505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:34.423857Z","time spent":"1.317114552s","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:50:35.741684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.734896ms","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-08-16T12:50:35.741727Z","caller":"traceutil/trace.go:171","msg":"trace[1271898941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"177.84073ms","start":"2026-08-16T12:50:35.563875Z","end":"2026-08-16T12:50:35.741716Z","steps":["trace[1271898941] 'agreement among raft nodes before linearized reading' (duration: 177.685346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:35.741756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.820656ms","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-08-16T12:50:35.741791Z","caller":"traceutil/trace.go:171","msg":"trace[10014376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"759.889677ms","start":"2026-08-16T12:50:34.98189Z","end":"2026-08-16T12:50:35.74178Z","steps":["trace[10014376] 'agreement among raft nodes before linearized reading' (duration: 759.834786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:35.741843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:34.98187Z","time spent":"759.966349ms","remote":"127.0.0.1:45524","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-08-16T12:50:36.487338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.281071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:50:36.487622Z","caller":"traceutil/trace.go:171","msg":"trace[389314185] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"741.427674ms","start":"2026-08-16T12:50:35.746162Z","end":"2026-08-16T12:50:36.487589Z","steps":["trace[389314185] 'process raft request' (duration: 741.283461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:36.487782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:35.74615Z","time spent":"741.547598ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:50:36.487769Z","caller":"traceutil/trace.go:171","msg":"trace[1319190377] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"740.666727ms","start":"2026-08-16T12:50:35.747065Z","end":"2026-08-16T12:50:36.487731Z","steps":["trace[1319190377] 'process raft request' (duration: 740.477792ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:36.487813Z","caller":"traceutil/trace.go:171","msg":"trace[5043085] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"741.543448ms","start":"2026-08-16T12:50:35.746234Z","end":"2026-08-16T12:50:36.487777Z","steps":["trace[5043085] 'process raft request' (duration: 741.262291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:36.487942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:35.747054Z","time spent":"740.793589ms","remote":"127.0.0.1:45524","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-08-16T12:50:36.48794Z","caller":"traceutil/trace.go:171","msg":"trace[851486725] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"742.836139ms","start":"2026-08-16T12:50:35.745062Z","end":"2026-08-16T12:50:36.487898Z","steps":["trace[851486725] 'process raft request' (duration: 241.764329ms)","trace[851486725] 'compare' (duration: 500.112407ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:50:36.487993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:35.746226Z","time spent":"741.670621ms","remote":"127.0.0.1:45524","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-08-16T12:50:36.487992Z","caller":"traceutil/trace.go:171","msg":"trace[277751667] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:385; }","duration":"611.845182ms","start":"2026-08-16T12:50:35.876098Z","end":"2026-08-16T12:50:36.487943Z","steps":["trace[277751667] 'read index received' (duration: 110.748541ms)","trace[277751667] 'applied index is now lower than readState.Index' (duration: 501.092781ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:50:36.488135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:35.745041Z","time spent":"742.992533ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:50:36.493876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.764923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:50:36.493931Z","caller":"traceutil/trace.go:171","msg":"trace[1370086853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"617.868446ms","start":"2026-08-16T12:50:35.876049Z","end":"2026-08-16T12:50:36.493917Z","steps":["trace[1370086853] 'agreement among raft nodes before linearized reading' (duration: 612.021576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:36.49397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:35.876029Z","time spent":"617.934019ms","remote":"127.0.0.1:45254","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-08-16T12:51:02.079548Z","caller":"traceutil/trace.go:171","msg":"trace[1023399783] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"242.070658ms","start":"2026-08-16T12:51:01.83745Z","end":"2026-08-16T12:51:02.079521Z","steps":["trace[1023399783] 'read index received' (duration: 239.447185ms)","trace[1023399783] 'applied index is now lower than readState.Index' (duration: 2.622553ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:51:02.079873Z","caller":"traceutil/trace.go:171","msg":"trace[366561963] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"338.408574ms","start":"2026-08-16T12:51:01.741424Z","end":"2026-08-16T12:51:02.079833Z","steps":["trace[366561963] 'process raft request' (duration: 336.395376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:02.080055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:01.741407Z","time spent":"338.553737ms","remote":"127.0.0.1:45338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:51:02.080289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.241662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q6trk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-16T12:51:02.080384Z","caller":"traceutil/trace.go:171","msg":"trace[1356473148] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q6trk; range_end:; response_count:1; response_revision:577; }","duration":"242.924478ms","start":"2026-08-16T12:51:01.837433Z","end":"2026-08-16T12:51:02.080358Z","steps":["trace[1356473148] 'agreement among raft nodes before linearized reading' (duration: 242.18849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:03.129443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642532404,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:51:03.230406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.724672ms","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-08-16T12:51:03.230506Z","caller":"traceutil/trace.go:171","msg":"trace[1625923694] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:579; }","duration":"462.832254ms","start":"2026-08-16T12:51:02.767658Z","end":"2026-08-16T12:51:03.23049Z","steps":["trace[1625923694] 'range keys from in-memory index tree' (duration: 462.710492ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:51:03.231362Z","caller":"traceutil/trace.go:171","msg":"trace[1651474350] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"602.469677ms","start":"2026-08-16T12:51:02.628868Z","end":"2026-08-16T12:51:03.231337Z","steps":["trace[1651474350] 'read index received' (duration: 600.176202ms)","trace[1651474350] 'applied index is now lower than readState.Index' (duration: 2.292495ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:51:03.231765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.893027ms","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-08-16T12:51:03.231803Z","caller":"traceutil/trace.go:171","msg":"trace[1728234520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"602.932768ms","start":"2026-08-16T12:51:02.62886Z","end":"2026-08-16T12:51:03.231793Z","steps":["trace[1728234520] 'agreement among raft nodes before linearized reading' (duration: 602.788375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:03.231852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:02.628801Z","time spent":"603.042521ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-16T12:51:03.231855Z","caller":"traceutil/trace.go:171","msg":"trace[345964481] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"693.036286ms","start":"2026-08-16T12:51:02.538787Z","end":"2026-08-16T12:51:03.231824Z","steps":["trace[345964481] 'process raft request' (duration: 690.543726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:03.231886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.180349ms","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-08-16T12:51:03.231941Z","caller":"traceutil/trace.go:171","msg":"trace[1759976508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"599.24579ms","start":"2026-08-16T12:51:02.632681Z","end":"2026-08-16T12:51:03.231927Z","steps":["trace[1759976508] 'agreement among raft nodes before linearized reading' (duration: 599.125877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:03.231986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:02.632616Z","time spent":"599.362704ms","remote":"127.0.0.1:45524","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-08-16T12:51:03.23199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:02.538731Z","time spent":"693.19405ms","remote":"127.0.0.1:45338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:51:03.232226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.775339ms","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-08-16T12:51:03.232251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.449133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:51:03.232235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.600946ms","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-08-16T12:51:03.232286Z","caller":"traceutil/trace.go:171","msg":"trace[1028362930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"355.551824ms","start":"2026-08-16T12:51:02.876721Z","end":"2026-08-16T12:51:03.232273Z","steps":["trace[1028362930] 'agreement among raft nodes before linearized reading' (duration: 355.500243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:03.232314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:02.876695Z","time spent":"355.613966ms","remote":"127.0.0.1:45254","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-08-16T12:51:03.232317Z","caller":"traceutil/trace.go:171","msg":"trace[1605674339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"593.713928ms","start":"2026-08-16T12:51:02.63858Z","end":"2026-08-16T12:51:03.232293Z","steps":["trace[1605674339] 'agreement among raft nodes before linearized reading' (duration: 593.502163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:03.232357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:02.638569Z","time spent":"593.78008ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-16T12:51:03.232263Z","caller":"traceutil/trace.go:171","msg":"trace[1027849148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"593.852131ms","start":"2026-08-16T12:51:02.6384Z","end":"2026-08-16T12:51:03.232253Z","steps":["trace[1027849148] 'agreement among raft nodes before linearized reading' (duration: 593.781749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:03.232505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:02.638378Z","time spent":"594.060466ms","remote":"127.0.0.1:45524","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-08-16T12:51:31.638055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.832017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:51:31.638233Z","caller":"traceutil/trace.go:171","msg":"trace[1666603026] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"261.719507ms","start":"2026-08-16T12:51:31.3765Z","end":"2026-08-16T12:51:31.638219Z","steps":["trace[1666603026] 'process raft request' (duration: 261.659416ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:51:31.638292Z","caller":"traceutil/trace.go:171","msg":"trace[925371650] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"261.80556ms","start":"2026-08-16T12:51:31.376456Z","end":"2026-08-16T12:51:31.638262Z","steps":["trace[925371650] 'process raft request' (duration: 128.714387ms)","trace[925371650] 'compare' (duration: 132.637333ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:51:31.867647Z","caller":"traceutil/trace.go:171","msg":"trace[779013769] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"479.829003ms","start":"2026-08-16T12:51:31.387796Z","end":"2026-08-16T12:51:31.867625Z","steps":["trace[779013769] 'process raft request' (duration: 479.782722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:31.867774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:31.387777Z","time spent":"479.929996ms","remote":"127.0.0.1:45524","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-08-16T12:51:31.868087Z","caller":"traceutil/trace.go:171","msg":"trace[1814478100] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"480.569411ms","start":"2026-08-16T12:51:31.387509Z","end":"2026-08-16T12:51:31.868078Z","steps":["trace[1814478100] 'process raft request' (duration: 397.086616ms)","trace[1814478100] 'compare' (duration: 82.880791ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:51:31.868183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:31.387479Z","time spent":"480.626153ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:51:43.87447Z","caller":"traceutil/trace.go:171","msg":"trace[1558063834] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"145.642199ms","start":"2026-08-16T12:51:43.728792Z","end":"2026-08-16T12:51:43.874434Z","steps":["trace[1558063834] 'read index received' (duration: 144.050091ms)","trace[1558063834] 'applied index is now lower than readState.Index' (duration: 1.590878ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:51:43.874637Z","caller":"traceutil/trace.go:171","msg":"trace[1894208213] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"430.258335ms","start":"2026-08-16T12:51:43.444368Z","end":"2026-08-16T12:51:43.874626Z","steps":["trace[1894208213] 'process raft request' (duration: 428.502653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:43.874788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:43.44435Z","time spent":"430.325867ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:51:43.8749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.068979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-16T12:51:43.874897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.521508ms","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-08-16T12:51:43.874984Z","caller":"traceutil/trace.go:171","msg":"trace[744894932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"147.010282ms","start":"2026-08-16T12:51:43.727952Z","end":"2026-08-16T12:51:43.874962Z","steps":["trace[744894932] 'agreement among raft nodes before linearized reading' (duration: 146.744416ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:51:43.87523Z","caller":"traceutil/trace.go:171","msg":"trace[1664153053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"143.672691ms","start":"2026-08-16T12:51:43.731306Z","end":"2026-08-16T12:51:43.874979Z","steps":["trace[1664153053] 'agreement among raft nodes before linearized reading' (duration: 143.373794ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:51:43.998267Z","caller":"traceutil/trace.go:171","msg":"trace[25763517] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"118.626601ms","start":"2026-08-16T12:51:43.879591Z","end":"2026-08-16T12:51:43.998218Z","steps":["trace[25763517] 'process raft request' (duration: 118.438306ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:51:43.998918Z","caller":"traceutil/trace.go:171","msg":"trace[1235305763] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"119.091552ms","start":"2026-08-16T12:51:43.879095Z","end":"2026-08-16T12:51:43.998186Z","steps":["trace[1235305763] 'process raft request' (duration: 89.903261ms)","trace[1235305763] 'compare' (duration: 28.535965ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:51:45.180927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642533112,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:51:45.292667Z","caller":"traceutil/trace.go:171","msg":"trace[1848536773] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"611.980855ms","start":"2026-08-16T12:51:44.680663Z","end":"2026-08-16T12:51:45.292644Z","steps":["trace[1848536773] 'read index received' (duration: 611.849221ms)","trace[1848536773] 'applied index is now lower than readState.Index' (duration: 130.294µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:51:45.292738Z","caller":"traceutil/trace.go:171","msg":"trace[41651681] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"661.833469ms","start":"2026-08-16T12:51:44.630879Z","end":"2026-08-16T12:51:45.292712Z","steps":["trace[41651681] 'process raft request' (duration: 661.623454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:45.292865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.197919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:51:45.292886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:44.630844Z","time spent":"661.944832ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:51:45.292898Z","caller":"traceutil/trace.go:171","msg":"trace[926825056] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:939; }","duration":"612.342373ms","start":"2026-08-16T12:51:44.680546Z","end":"2026-08-16T12:51:45.292889Z","steps":["trace[926825056] 'agreement among raft nodes before linearized reading' (duration: 612.231711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:45.292931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:44.680529Z","time spent":"612.393854ms","remote":"127.0.0.1:45604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:51:45.293012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.805486ms","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-08-16T12:51:45.29309Z","caller":"traceutil/trace.go:171","msg":"trace[153805825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"557.913788ms","start":"2026-08-16T12:51:44.735157Z","end":"2026-08-16T12:51:45.293071Z","steps":["trace[153805825] 'agreement among raft nodes before linearized reading' (duration: 557.673821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:45.293134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:44.735146Z","time spent":"557.979139ms","remote":"127.0.0.1:45524","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-08-16T12:51:45.689925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.447983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:51:45.690009Z","caller":"traceutil/trace.go:171","msg":"trace[1113966448] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"380.067482ms","start":"2026-08-16T12:51:45.309927Z","end":"2026-08-16T12:51:45.689995Z","steps":["trace[1113966448] 'process raft request' (duration: 49.478985ms)","trace[1113966448] 'compare' (duration: 330.353281ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:51:45.690047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:45.309906Z","time spent":"380.123134ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:51:46.096407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.806893ms","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-08-16T12:51:46.096513Z","caller":"traceutil/trace.go:171","msg":"trace[905856514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"216.909466ms","start":"2026-08-16T12:51:45.879579Z","end":"2026-08-16T12:51:46.096489Z","steps":["trace[905856514] 'range keys from in-memory index tree' (duration: 216.624469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:46.095538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.123045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:51:46.09703Z","caller":"traceutil/trace.go:171","msg":"trace[1620294251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"221.701224ms","start":"2026-08-16T12:51:45.875316Z","end":"2026-08-16T12:51:46.097017Z","steps":["trace[1620294251] 'range keys from in-memory index tree' (duration: 220.016052ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:51:46.624578Z","caller":"traceutil/trace.go:171","msg":"trace[731969642] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"522.507954ms","start":"2026-08-16T12:51:46.102048Z","end":"2026-08-16T12:51:46.624556Z","steps":["trace[731969642] 'process raft request' (duration: 522.474253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:46.624715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:46.10204Z","time spent":"522.597407ms","remote":"127.0.0.1:45524","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-08-16T12:51:46.625021Z","caller":"traceutil/trace.go:171","msg":"trace[46334194] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"524.137583ms","start":"2026-08-16T12:51:46.100877Z","end":"2026-08-16T12:51:46.625014Z","steps":["trace[46334194] 'process raft request' (duration: 523.477927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:46.625072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:46.100855Z","time spent":"524.189535ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:51:46.625138Z","caller":"traceutil/trace.go:171","msg":"trace[415868107] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"524.105563ms","start":"2026-08-16T12:51:46.101026Z","end":"2026-08-16T12:51:46.625132Z","steps":["trace[415868107] 'process raft request' (duration: 523.465668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:46.625183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:46.101018Z","time spent":"524.132454ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:51:47.472833Z","caller":"traceutil/trace.go:171","msg":"trace[1166443675] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.217717287s","start":"2026-08-16T12:51:46.255089Z","end":"2026-08-16T12:51:47.472806Z","steps":["trace[1166443675] 'process raft request' (duration: 1.167761869s)","trace[1166443675] 'compare' (duration: 49.087616ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:51:47.472984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:46.255061Z","time spent":"1.217854041s","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:51:47.494448Z","caller":"traceutil/trace.go:171","msg":"trace[764305337] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:982; }","duration":"1.170309432s","start":"2026-08-16T12:51:46.324113Z","end":"2026-08-16T12:51:47.494422Z","steps":["trace[764305337] 'read index received' (duration: 300.971475ms)","trace[764305337] 'applied index is now lower than readState.Index' (duration: 869.330526ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:51:47.494726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170600308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:51:47.494756Z","caller":"traceutil/trace.go:171","msg":"trace[1728896842] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:945; }","duration":"1.17068441s","start":"2026-08-16T12:51:46.324056Z","end":"2026-08-16T12:51:47.494741Z","steps":["trace[1728896842] 'agreement among raft nodes before linearized reading' (duration: 1.170500336s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:47.494779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:46.324024Z","time spent":"1.170748112s","remote":"127.0.0.1:45474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:51:47.494814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.778596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:51:47.494863Z","caller":"traceutil/trace.go:171","msg":"trace[2079218993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"617.863628ms","start":"2026-08-16T12:51:46.876988Z","end":"2026-08-16T12:51:47.494852Z","steps":["trace[2079218993] 'agreement among raft nodes before linearized reading' (duration: 617.587441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:47.49489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:51:46.876952Z","time spent":"617.92809ms","remote":"127.0.0.1:45254","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-08-16T12:51:50.163385Z","caller":"traceutil/trace.go:171","msg":"trace[899166062] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"144.336088ms","start":"2026-08-16T12:51:50.019021Z","end":"2026-08-16T12:51:50.163357Z","steps":["trace[899166062] 'process raft request' (duration: 144.177604ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:04.82404Z","caller":"traceutil/trace.go:171","msg":"trace[494922193] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"105.50732ms","start":"2026-08-16T12:52:04.718505Z","end":"2026-08-16T12:52:04.824013Z","steps":["trace[494922193] 'read index received' (duration: 105.318626ms)","trace[494922193] 'applied index is now lower than readState.Index' (duration: 187.614µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:04.824166Z","caller":"traceutil/trace.go:171","msg":"trace[924903439] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"107.255403ms","start":"2026-08-16T12:52:04.71687Z","end":"2026-08-16T12:52:04.824125Z","steps":["trace[924903439] 'process raft request' (duration: 107.002067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:04.824288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.756617ms","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-08-16T12:52:04.824329Z","caller":"traceutil/trace.go:171","msg":"trace[342105182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1005; }","duration":"105.82584ms","start":"2026-08-16T12:52:04.71849Z","end":"2026-08-16T12:52:04.824316Z","steps":["trace[342105182] 'agreement among raft nodes before linearized reading' (duration: 105.665915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:04.824424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.936602ms","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-08-16T12:52:04.824492Z","caller":"traceutil/trace.go:171","msg":"trace[2051087670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1005; }","duration":"106.032645ms","start":"2026-08-16T12:52:04.718441Z","end":"2026-08-16T12:52:04.824474Z","steps":["trace[2051087670] 'agreement among raft nodes before linearized reading' (duration: 105.85527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:05.096456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.344029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:52:05.096588Z","caller":"traceutil/trace.go:171","msg":"trace[133348730] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1048; }","duration":"220.102285ms","start":"2026-08-16T12:52:04.876466Z","end":"2026-08-16T12:52:05.096568Z","steps":["trace[133348730] 'read index received' (duration: 23.940294ms)","trace[133348730] 'applied index is now lower than readState.Index' (duration: 196.160371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:05.096676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.205567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:05.096711Z","caller":"traceutil/trace.go:171","msg":"trace[1483673462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"220.276229ms","start":"2026-08-16T12:52:04.876424Z","end":"2026-08-16T12:52:05.0967Z","steps":["trace[1483673462] 'agreement among raft nodes before linearized reading' (duration: 220.194347ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:05.096906Z","caller":"traceutil/trace.go:171","msg":"trace[287197173] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"267.707215ms","start":"2026-08-16T12:52:04.829146Z","end":"2026-08-16T12:52:05.096853Z","steps":["trace[287197173] 'process raft request' (duration: 140.864533ms)","trace[287197173] 'compare' (duration: 125.968901ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:05.693524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.215389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:52:05.693674Z","caller":"traceutil/trace.go:171","msg":"trace[307257953] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"154.256749ms","start":"2026-08-16T12:52:05.539395Z","end":"2026-08-16T12:52:05.693652Z","steps":["trace[307257953] 'process raft request' (duration: 24.824115ms)","trace[307257953] 'compare' (duration: 129.070635ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:06.862904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.188886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-16T12:52:06.86313Z","caller":"traceutil/trace.go:171","msg":"trace[834624459] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"1.06434894s","start":"2026-08-16T12:52:05.79876Z","end":"2026-08-16T12:52:06.863109Z","steps":["trace[834624459] 'read index received' (duration: 207.946779ms)","trace[834624459] 'applied index is now lower than readState.Index' (duration: 856.400711ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:06.863342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064570525s","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-08-16T12:52:06.863381Z","caller":"traceutil/trace.go:171","msg":"trace[569109884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"1.064654537s","start":"2026-08-16T12:52:05.798716Z","end":"2026-08-16T12:52:06.863371Z","steps":["trace[569109884] 'agreement among raft nodes before linearized reading' (duration: 1.064517494s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:06.863422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:05.798696Z","time spent":"1.064714888s","remote":"127.0.0.1:45524","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-08-16T12:52:06.863425Z","caller":"traceutil/trace.go:171","msg":"trace[1797920065] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"943.025431ms","start":"2026-08-16T12:52:05.920369Z","end":"2026-08-16T12:52:06.863395Z","steps":["trace[1797920065] 'process raft request' (duration: 942.639242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:06.863569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:05.920354Z","time spent":"943.134475ms","remote":"127.0.0.1:45524","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-08-16T12:52:06.949511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.722053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-16T12:52:06.949591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072935258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:06.9496Z","caller":"traceutil/trace.go:171","msg":"trace[623399819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"644.873206ms","start":"2026-08-16T12:52:06.304709Z","end":"2026-08-16T12:52:06.949582Z","steps":["trace[623399819] 'agreement among raft nodes before linearized reading' (duration: 558.758037ms)","trace[623399819] 'range keys from in-memory index tree' (duration: 85.936375ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:06.949682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:06.304684Z","time spent":"644.989688ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-16T12:52:06.949682Z","caller":"traceutil/trace.go:171","msg":"trace[1350917813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"1.073026071s","start":"2026-08-16T12:52:05.87663Z","end":"2026-08-16T12:52:06.949656Z","steps":["trace[1350917813] 'agreement among raft nodes before linearized reading' (duration: 986.729378ms)","trace[1350917813] 'range keys from in-memory index tree' (duration: 86.206341ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:06.949734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:05.876619Z","time spent":"1.073106653s","remote":"127.0.0.1:45254","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-08-16T12:52:06.949528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.763443ms","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-08-16T12:52:06.949885Z","caller":"traceutil/trace.go:171","msg":"trace[2123933131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"121.160823ms","start":"2026-08-16T12:52:06.828691Z","end":"2026-08-16T12:52:06.949852Z","steps":["trace[2123933131] 'agreement among raft nodes before linearized reading' (duration: 34.729776ms)","trace[2123933131] 'range keys from in-memory index tree' (duration: 85.913694ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:23.899733Z","caller":"traceutil/trace.go:171","msg":"trace[677343345] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"102.218905ms","start":"2026-08-16T12:52:23.797491Z","end":"2026-08-16T12:52:23.899709Z","steps":["trace[677343345] 'process raft request' (duration: 102.080471ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:33.324158Z","caller":"traceutil/trace.go:171","msg":"trace[1913749142] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"102.311996ms","start":"2026-08-16T12:52:33.22182Z","end":"2026-08-16T12:52:33.324132Z","steps":["trace[1913749142] 'process raft request' (duration: 85.888775ms)","trace[1913749142] 'compare' (duration: 16.326509ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:40.400841Z","caller":"traceutil/trace.go:171","msg":"trace[715884854] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"368.491609ms","start":"2026-08-16T12:52:40.032323Z","end":"2026-08-16T12:52:40.400815Z","steps":["trace[715884854] 'read index received' (duration: 309.627413ms)","trace[715884854] 'applied index is now lower than readState.Index' (duration: 58.862976ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:40.400906Z","caller":"traceutil/trace.go:171","msg":"trace[110897801] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"418.52252ms","start":"2026-08-16T12:52:39.982354Z","end":"2026-08-16T12:52:40.400876Z","steps":["trace[110897801] 'process raft request' (duration: 359.656334ms)","trace[110897801] 'compare' (duration: 58.687012ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:40.40095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.619333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:52:40.401008Z","caller":"traceutil/trace.go:171","msg":"trace[417184512] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1188; }","duration":"368.685344ms","start":"2026-08-16T12:52:40.03231Z","end":"2026-08-16T12:52:40.400995Z","steps":["trace[417184512] 'agreement among raft nodes before linearized reading' (duration: 368.596982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:40.401031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:40.032291Z","time spent":"368.734555ms","remote":"127.0.0.1:57378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:52:40.401045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:39.982185Z","time spent":"418.784597ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:52:40.401138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.312288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xdqwb\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-08-16T12:52:40.401244Z","caller":"traceutil/trace.go:171","msg":"trace[1194080433] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xdqwb; range_end:; response_count:1; response_revision:1188; }","duration":"148.466682ms","start":"2026-08-16T12:52:40.252759Z","end":"2026-08-16T12:52:40.401226Z","steps":["trace[1194080433] 'agreement among raft nodes before linearized reading' (duration: 148.209196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:45.634746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.312508ms","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-08-16T12:52:45.634837Z","caller":"traceutil/trace.go:171","msg":"trace[440969056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1231; }","duration":"266.438511ms","start":"2026-08-16T12:52:45.368378Z","end":"2026-08-16T12:52:45.634817Z","steps":["trace[440969056] 'range keys from in-memory index tree' (duration: 266.120382ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:45.856486Z","caller":"traceutil/trace.go:171","msg":"trace[2025344100] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"216.343938ms","start":"2026-08-16T12:52:45.640123Z","end":"2026-08-16T12:52:45.856467Z","steps":["trace[2025344100] 'process raft request' (duration: 216.229286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:47.176319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.145679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:52:47.176584Z","caller":"traceutil/trace.go:171","msg":"trace[1806597166] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"690.954157ms","start":"2026-08-16T12:52:46.485576Z","end":"2026-08-16T12:52:47.17653Z","steps":["trace[1806597166] 'process raft request' (duration: 343.49155ms)","trace[1806597166] 'compare' (duration: 346.801931ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:47.17668Z","caller":"traceutil/trace.go:171","msg":"trace[1209562835] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"682.46298ms","start":"2026-08-16T12:52:46.494174Z","end":"2026-08-16T12:52:47.176637Z","steps":["trace[1209562835] 'process raft request' (duration: 682.276725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:47.17675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.117687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:47.176754Z","caller":"traceutil/trace.go:171","msg":"trace[1119570613] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"681.952207ms","start":"2026-08-16T12:52:46.494784Z","end":"2026-08-16T12:52:47.176736Z","steps":["trace[1119570613] 'process raft request' (duration: 681.777853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:47.176773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:46.48556Z","time spent":"691.128442ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-16T12:52:47.176786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:46.494159Z","time spent":"682.564203ms","remote":"127.0.0.1:45524","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-08-16T12:52:47.176803Z","caller":"traceutil/trace.go:171","msg":"trace[1568210334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"301.179688ms","start":"2026-08-16T12:52:46.87561Z","end":"2026-08-16T12:52:47.17679Z","steps":["trace[1568210334] 'agreement among raft nodes before linearized reading' (duration: 301.064355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:47.176845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:46.875553Z","time spent":"301.284111ms","remote":"127.0.0.1:45254","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-08-16T12:52:47.176623Z","caller":"traceutil/trace.go:171","msg":"trace[454133193] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1292; }","duration":"300.987974ms","start":"2026-08-16T12:52:46.875617Z","end":"2026-08-16T12:52:47.176605Z","steps":["trace[454133193] 'read index received' (duration: 284.474981ms)","trace[454133193] 'applied index is now lower than readState.Index' (duration: 16.512043ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:47.176846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:46.494776Z","time spent":"682.024659ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":861,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-16T12:52:47.764749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.034325ms","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-08-16T12:52:47.764826Z","caller":"traceutil/trace.go:171","msg":"trace[601099860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"483.156198ms","start":"2026-08-16T12:52:47.281651Z","end":"2026-08-16T12:52:47.764807Z","steps":["trace[601099860] 'range keys from in-memory index tree' (duration: 482.808369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:47.764864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:47.281631Z","time spent":"483.224559ms","remote":"127.0.0.1:45524","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-08-16T12:52:47.765154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.883911ms","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-08-16T12:52:47.765257Z","caller":"traceutil/trace.go:171","msg":"trace[52735327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"472.017195ms","start":"2026-08-16T12:52:47.293213Z","end":"2026-08-16T12:52:47.76523Z","steps":["trace[52735327] 'range keys from in-memory index tree' (duration: 471.761149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:47.765315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:47.293199Z","time spent":"472.100717ms","remote":"127.0.0.1:45524","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-08-16T12:52:47.765154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.33115ms","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-08-16T12:52:47.765405Z","caller":"traceutil/trace.go:171","msg":"trace[1142750173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"476.625507ms","start":"2026-08-16T12:52:47.288754Z","end":"2026-08-16T12:52:47.76538Z","steps":["trace[1142750173] 'range keys from in-memory index tree' (duration: 476.190227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:47.765561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:47.288733Z","time spent":"476.771111ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:52:47.765664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.40944ms","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-08-16T12:52:47.765715Z","caller":"traceutil/trace.go:171","msg":"trace[1338039983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"471.477142ms","start":"2026-08-16T12:52:47.294225Z","end":"2026-08-16T12:52:47.765702Z","steps":["trace[1338039983] 'range keys from in-memory index tree' (duration: 470.637551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:47.765748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:47.294217Z","time spent":"471.523633ms","remote":"127.0.0.1:45524","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-08-16T12:52:48.224147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.128326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:48.224249Z","caller":"traceutil/trace.go:171","msg":"trace[567155240] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1249; }","duration":"210.27138ms","start":"2026-08-16T12:52:48.013953Z","end":"2026-08-16T12:52:48.224225Z","steps":["trace[567155240] 'range keys from in-memory index tree' (duration: 210.017184ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:48.387461Z","caller":"traceutil/trace.go:171","msg":"trace[1428042224] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"106.271763ms","start":"2026-08-16T12:52:48.281164Z","end":"2026-08-16T12:52:48.387435Z","steps":["trace[1428042224] 'process raft request' (duration: 59.031781ms)","trace[1428042224] 'compare' (duration: 47.113459ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:49.487971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.563728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-08-16T12:52:49.488045Z","caller":"traceutil/trace.go:171","msg":"trace[1548059320] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1256; }","duration":"208.66917ms","start":"2026-08-16T12:52:49.279361Z","end":"2026-08-16T12:52:49.48803Z","steps":["trace[1548059320] 'range keys from in-memory index tree' (duration: 208.442795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:50.961424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642533852,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:52:51.329765Z","caller":"traceutil/trace.go:171","msg":"trace[2089315911] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"869.089163ms","start":"2026-08-16T12:52:50.460649Z","end":"2026-08-16T12:52:51.329738Z","steps":["trace[2089315911] 'read index received' (duration: 868.928159ms)","trace[2089315911] 'applied index is now lower than readState.Index' (duration: 159.854µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:51.329815Z","caller":"traceutil/trace.go:171","msg":"trace[1496393308] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"873.453789ms","start":"2026-08-16T12:52:50.456329Z","end":"2026-08-16T12:52:51.329782Z","steps":["trace[1496393308] 'process raft request' (duration: 873.190262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:51.329952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.290168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:51.330016Z","caller":"traceutil/trace.go:171","msg":"trace[1149212788] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1264; }","duration":"869.403431ms","start":"2026-08-16T12:52:50.460598Z","end":"2026-08-16T12:52:51.330001Z","steps":["trace[1149212788] 'agreement among raft nodes before linearized reading' (duration: 869.279488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:51.330025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.947465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-x5cxg\" ","response":"range_response_count:1 size:12641"} {"level":"warn","ts":"2026-08-16T12:52:51.330059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:50.460577Z","time spent":"869.469153ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-08-16T12:52:51.330078Z","caller":"traceutil/trace.go:171","msg":"trace[1492621335] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-x5cxg; range_end:; response_count:1; response_revision:1264; }","duration":"811.053587ms","start":"2026-08-16T12:52:50.519013Z","end":"2026-08-16T12:52:51.330067Z","steps":["trace[1492621335] 'agreement among raft nodes before linearized reading' (duration: 810.877453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:51.330073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.861808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:52:51.330117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:50.518996Z","time spent":"811.112778ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":12663,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-x5cxg\" "} {"level":"info","ts":"2026-08-16T12:52:51.330164Z","caller":"traceutil/trace.go:171","msg":"trace[933081719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1264; }","duration":"379.97079ms","start":"2026-08-16T12:52:50.950171Z","end":"2026-08-16T12:52:51.330141Z","steps":["trace[933081719] 'agreement among raft nodes before linearized reading' (duration: 379.790486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:51.330212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:50.950134Z","time spent":"380.067403ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:52:51.329967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:50.456303Z","time spent":"873.578372ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:52:51.330092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.826832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:52:51.330297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.554462ms","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-08-16T12:52:51.330374Z","caller":"traceutil/trace.go:171","msg":"trace[896867814] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:1264; }","duration":"550.688525ms","start":"2026-08-16T12:52:50.779667Z","end":"2026-08-16T12:52:51.330355Z","steps":["trace[896867814] 'agreement among raft nodes before linearized reading' (duration: 550.429559ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:51.330354Z","caller":"traceutil/trace.go:171","msg":"trace[1532295379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"454.095228ms","start":"2026-08-16T12:52:50.87624Z","end":"2026-08-16T12:52:51.330335Z","steps":["trace[1532295379] 'agreement among raft nodes before linearized reading' (duration: 453.787411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:51.330421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:50.779645Z","time spent":"550.768138ms","remote":"127.0.0.1:45298","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":"warn","ts":"2026-08-16T12:52:51.330424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:50.876191Z","time spent":"454.219912ms","remote":"127.0.0.1:45254","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-08-16T12:53:06.661988Z","caller":"traceutil/trace.go:171","msg":"trace[1550952212] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1398; }","duration":"171.70618ms","start":"2026-08-16T12:53:06.490255Z","end":"2026-08-16T12:53:06.661961Z","steps":["trace[1550952212] 'read index received' (duration: 89.025312ms)","trace[1550952212] 'applied index is now lower than readState.Index' (duration: 82.679548ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:06.662276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.049435ms","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-08-16T12:53:06.662317Z","caller":"traceutil/trace.go:171","msg":"trace[1646302213] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1341; }","duration":"160.146567ms","start":"2026-08-16T12:53:06.50216Z","end":"2026-08-16T12:53:06.662307Z","steps":["trace[1646302213] 'agreement among raft nodes before linearized reading' (duration: 159.953963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:06.662373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.086329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-16T12:53:06.662476Z","caller":"traceutil/trace.go:171","msg":"trace[980312734] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1341; }","duration":"172.242193ms","start":"2026-08-16T12:53:06.490209Z","end":"2026-08-16T12:53:06.662451Z","steps":["trace[980312734] 'agreement among raft nodes before linearized reading' (duration: 171.873074ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:07.017797Z","caller":"traceutil/trace.go:171","msg":"trace[226532632] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"351.193808ms","start":"2026-08-16T12:53:06.666574Z","end":"2026-08-16T12:53:07.017768Z","steps":["trace[226532632] 'process raft request' (duration: 350.950482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.017945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:06.666544Z","time spent":"351.321492ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:07.160842Z","caller":"traceutil/trace.go:171","msg":"trace[1137708405] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1399; }","duration":"284.632064ms","start":"2026-08-16T12:53:06.876186Z","end":"2026-08-16T12:53:07.160818Z","steps":["trace[1137708405] 'read index received' (duration: 141.38994ms)","trace[1137708405] 'applied index is now lower than readState.Index' (duration: 143.240954ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:07.160976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.773017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:07.161007Z","caller":"traceutil/trace.go:171","msg":"trace[1403444471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"284.844299ms","start":"2026-08-16T12:53:06.876153Z","end":"2026-08-16T12:53:07.160998Z","steps":["trace[1403444471] 'agreement among raft nodes before linearized reading' (duration: 284.750447ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:07.161066Z","caller":"traceutil/trace.go:171","msg":"trace[2116842646] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"494.492004ms","start":"2026-08-16T12:53:06.666551Z","end":"2026-08-16T12:53:07.161043Z","steps":["trace[2116842646] 'process raft request' (duration: 448.880201ms)","trace[2116842646] 'compare' (duration: 45.195603ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:07.161178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:06.66652Z","time spent":"494.596677ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:49.3908Z","caller":"traceutil/trace.go:171","msg":"trace[420695202] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"103.046102ms","start":"2026-08-16T12:53:49.287728Z","end":"2026-08-16T12:53:49.390774Z","steps":["trace[420695202] 'process raft request' (duration: 102.875307ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:49.567641Z","caller":"traceutil/trace.go:171","msg":"trace[201188427] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"129.821746ms","start":"2026-08-16T12:53:49.43779Z","end":"2026-08-16T12:53:49.567611Z","steps":["trace[201188427] 'process raft request' (duration: 128.7599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:50.82542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642534531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:51.325917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642534531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:51.826266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642534531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:52.887041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642534531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:53.245079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000843168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-16T12:53:53.245151Z","caller":"traceutil/trace.go:171","msg":"trace[917197364] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000940101s","start":"2026-08-16T12:53:51.244196Z","end":"2026-08-16T12:53:53.245136Z","steps":["trace[917197364] 'agreement among raft nodes before linearized reading' (duration: 2.000859739s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:53.245202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.244186Z","time spent":"2.000995592s","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/16 12:53:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-16T12:53:53.387598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642534531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:53.887861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642534531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:54.218177Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.936770425s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:53:54.219248Z","caller":"traceutil/trace.go:171","msg":"trace[177245538] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"2.586560731s","start":"2026-08-16T12:53:51.632583Z","end":"2026-08-16T12:53:54.219144Z","steps":["trace[177245538] 'process raft request' (duration: 2.586482129s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:54.219511Z","caller":"traceutil/trace.go:171","msg":"trace[582932979] linearizableReadLoop","detail":"{readStateIndex:1608; appliedIndex:1605; }","duration":"3.89516425s","start":"2026-08-16T12:53:50.324315Z","end":"2026-08-16T12:53:54.219479Z","steps":["trace[582932979] 'read index received' (duration: 3.894204787s)","trace[582932979] 'applied index is now lower than readState.Index' (duration: 957.983µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:54.219588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.632566Z","time spent":"2.586811906s","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:54.219643Z","caller":"traceutil/trace.go:171","msg":"trace[193836841] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"351.694925ms","start":"2026-08-16T12:53:53.86793Z","end":"2026-08-16T12:53:54.219625Z","steps":["trace[193836841] 'process raft request' (duration: 351.168552ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:54.219657Z","caller":"traceutil/trace.go:171","msg":"trace[845386353] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"3.938330742s","start":"2026-08-16T12:53:50.281262Z","end":"2026-08-16T12:53:54.219593Z","steps":["trace[845386353] 'process raft request' (duration: 3.937557544s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.219793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:53.867899Z","time spent":"351.783597ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:54.21985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.281238Z","time spent":"3.938509477s","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:54.219859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.888454466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-16T12:53:54.219865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.895518028s","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-08-16T12:53:54.21992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.118869527s","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-08-16T12:53:54.219942Z","caller":"traceutil/trace.go:171","msg":"trace[910088099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1542; }","duration":"3.895636481s","start":"2026-08-16T12:53:50.32429Z","end":"2026-08-16T12:53:54.219927Z","steps":["trace[910088099] 'agreement among raft nodes before linearized reading' (duration: 3.895375855s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.219979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.324279Z","time spent":"3.895693302s","remote":"127.0.0.1:45524","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-08-16T12:53:54.219964Z","caller":"traceutil/trace.go:171","msg":"trace[1344124918] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1542; }","duration":"2.118915268s","start":"2026-08-16T12:53:52.101039Z","end":"2026-08-16T12:53:54.219954Z","steps":["trace[1344124918] 'agreement among raft nodes before linearized reading' (duration: 2.118752644s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.10102Z","time spent":"2.118979649s","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-16T12:53:54.220028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.983998402s","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-08-16T12:53:54.220073Z","caller":"traceutil/trace.go:171","msg":"trace[1646154039] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:1542; }","duration":"2.984043174s","start":"2026-08-16T12:53:51.236017Z","end":"2026-08-16T12:53:54.22006Z","steps":["trace[1646154039] 'agreement among raft nodes before linearized reading' (duration: 2.983918951s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.886616771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T12:53:54.220105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.648768567s","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":"warn","ts":"2026-08-16T12:53:54.220111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.235954Z","time spent":"2.984147276s","remote":"127.0.0.1:45298","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-08-16T12:53:54.220133Z","caller":"traceutil/trace.go:171","msg":"trace[1883360811] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1542; }","duration":"2.648833959s","start":"2026-08-16T12:53:51.571292Z","end":"2026-08-16T12:53:54.220126Z","steps":["trace[1883360811] 'agreement among raft nodes before linearized reading' (duration: 2.648769258s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:54.220137Z","caller":"traceutil/trace.go:171","msg":"trace[282117117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1542; }","duration":"3.886683323s","start":"2026-08-16T12:53:50.333439Z","end":"2026-08-16T12:53:54.220123Z","steps":["trace[282117117] 'agreement among raft nodes before linearized reading' (duration: 3.88655943s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.571267Z","time spent":"2.64888709s","remote":"127.0.0.1:45524","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-08-16T12:53:54.220147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.120807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:53:54.220185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.333428Z","time spent":"3.886743204s","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-16T12:53:54.220211Z","caller":"traceutil/trace.go:171","msg":"trace[1806548694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1542; }","duration":"975.21833ms","start":"2026-08-16T12:53:53.244972Z","end":"2026-08-16T12:53:54.22019Z","steps":["trace[1806548694] 'agreement among raft nodes before linearized reading' (duration: 975.105677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.22021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329264442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-08-16T12:53:54.220234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33206779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:53:54.220253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:53.244956Z","time spent":"975.285251ms","remote":"127.0.0.1:45262","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-08-16T12:53:54.220268Z","caller":"traceutil/trace.go:171","msg":"trace[1336294177] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1542; }","duration":"1.332128671s","start":"2026-08-16T12:53:52.888127Z","end":"2026-08-16T12:53:54.220256Z","steps":["trace[1336294177] 'agreement among raft nodes before linearized reading' (duration: 1.332054729s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:54.220271Z","caller":"traceutil/trace.go:171","msg":"trace[1547740608] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1542; }","duration":"1.329328803s","start":"2026-08-16T12:53:52.890916Z","end":"2026-08-16T12:53:54.220245Z","steps":["trace[1547740608] 'agreement among raft nodes before linearized reading' (duration: 1.329155369s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.888124Z","time spent":"1.332160042s","remote":"127.0.0.1:57378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:53:54.220317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.890911Z","time spent":"1.329394624s","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-16T12:53:54.220362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139986507s","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":"warn","ts":"2026-08-16T12:53:54.22039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.984215867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:54.220413Z","caller":"traceutil/trace.go:171","msg":"trace[1558529131] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1542; }","duration":"1.1400585s","start":"2026-08-16T12:53:53.080338Z","end":"2026-08-16T12:53:54.220396Z","steps":["trace[1558529131] 'agreement among raft nodes before linearized reading' (duration: 1.139940237s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:54.220444Z","caller":"traceutil/trace.go:171","msg":"trace[568860985] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1542; }","duration":"2.984311849s","start":"2026-08-16T12:53:51.236115Z","end":"2026-08-16T12:53:54.220427Z","steps":["trace[568860985] 'agreement among raft nodes before linearized reading' (duration: 2.984228417s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:53.080325Z","time spent":"1.140118021s","remote":"127.0.0.1:45524","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-08-16T12:53:54.220495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.236097Z","time spent":"2.984378961s","remote":"127.0.0.1:34716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-16T12:53:54.219922Z","caller":"traceutil/trace.go:171","msg":"trace[763287383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1542; }","duration":"3.888571169s","start":"2026-08-16T12:53:50.331334Z","end":"2026-08-16T12:53:54.219906Z","steps":["trace[763287383] 'agreement among raft nodes before linearized reading' (duration: 3.888338763s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.331309Z","time spent":"3.889250135s","remote":"127.0.0.1:45524","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-08-16T12:53:54.220531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332434678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:53:54.221281Z","caller":"traceutil/trace.go:171","msg":"trace[45799280] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1542; }","duration":"1.333190217s","start":"2026-08-16T12:53:52.888076Z","end":"2026-08-16T12:53:54.221267Z","steps":["trace[45799280] 'agreement among raft nodes before linearized reading' (duration: 1.332338076s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.221318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.888045Z","time spent":"1.333259009s","remote":"127.0.0.1:34768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:53:54.221612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.853420722s","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-08-16T12:53:54.221687Z","caller":"traceutil/trace.go:171","msg":"trace[492335901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1542; }","duration":"3.853539865s","start":"2026-08-16T12:53:50.368138Z","end":"2026-08-16T12:53:54.221677Z","steps":["trace[492335901] 'agreement among raft nodes before linearized reading' (duration: 3.851810613s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.221714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.368116Z","time spent":"3.853591757s","remote":"127.0.0.1:45524","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-08-16T12:54:14.440124Z","caller":"traceutil/trace.go:171","msg":"trace[775396422] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"164.506932ms","start":"2026-08-16T12:54:14.27559Z","end":"2026-08-16T12:54:14.440097Z","steps":["trace[775396422] 'process raft request' (duration: 164.308607ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:14.455385Z","caller":"traceutil/trace.go:171","msg":"trace[738394272] transaction","detail":"{read_only:false; response_revision:1635; number_of_response:1; }","duration":"179.737053ms","start":"2026-08-16T12:54:14.275624Z","end":"2026-08-16T12:54:14.455361Z","steps":["trace[738394272] 'process raft request' (duration: 179.531328ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:14.455475Z","caller":"traceutil/trace.go:171","msg":"trace[99684983] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"179.655321ms","start":"2026-08-16T12:54:14.275793Z","end":"2026-08-16T12:54:14.455449Z","steps":["trace[99684983] 'process raft request' (duration: 179.480617ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:14.455617Z","caller":"traceutil/trace.go:171","msg":"trace[635697549] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1705; }","duration":"112.114714ms","start":"2026-08-16T12:54:14.343484Z","end":"2026-08-16T12:54:14.455599Z","steps":["trace[635697549] 'read index received' (duration: 96.505433ms)","trace[635697549] 'applied index is now lower than readState.Index' (duration: 15.608681ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:14.455702Z","caller":"traceutil/trace.go:171","msg":"trace[1536920571] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"152.309724ms","start":"2026-08-16T12:54:14.303383Z","end":"2026-08-16T12:54:14.455693Z","steps":["trace[1536920571] 'process raft request' (duration: 152.167651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:14.455827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.700141ms","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-08-16T12:54:14.45585Z","caller":"traceutil/trace.go:171","msg":"trace[1015054093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1637; }","duration":"106.754303ms","start":"2026-08-16T12:54:14.349089Z","end":"2026-08-16T12:54:14.455844Z","steps":["trace[1015054093] 'agreement among raft nodes before linearized reading' (duration: 106.64417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:14.455959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.460202ms","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-08-16T12:54:14.455981Z","caller":"traceutil/trace.go:171","msg":"trace[1775761386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1637; }","duration":"112.529793ms","start":"2026-08-16T12:54:14.343446Z","end":"2026-08-16T12:54:14.455975Z","steps":["trace[1775761386] 'agreement among raft nodes before linearized reading' (duration: 112.436581ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:20.671383Z","caller":"traceutil/trace.go:171","msg":"trace[192575130] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"127.752285ms","start":"2026-08-16T12:54:20.543604Z","end":"2026-08-16T12:54:20.671357Z","steps":["trace[192575130] 'process raft request' (duration: 127.669963ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:20.671606Z","caller":"traceutil/trace.go:171","msg":"trace[1520466691] transaction","detail":"{read_only:false; response_revision:1669; number_of_response:1; }","duration":"127.916689ms","start":"2026-08-16T12:54:20.543669Z","end":"2026-08-16T12:54:20.671586Z","steps":["trace[1520466691] 'process raft request' (duration: 127.652682ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:20.67201Z","caller":"traceutil/trace.go:171","msg":"trace[1008720364] linearizableReadLoop","detail":"{readStateIndex:1744; appliedIndex:1739; }","duration":"100.652384ms","start":"2026-08-16T12:54:20.571346Z","end":"2026-08-16T12:54:20.671999Z","steps":["trace[1008720364] 'read index received' (duration: 77.965471ms)","trace[1008720364] 'applied index is now lower than readState.Index' (duration: 22.686293ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:20.672056Z","caller":"traceutil/trace.go:171","msg":"trace[1326968405] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"127.790506ms","start":"2026-08-16T12:54:20.544231Z","end":"2026-08-16T12:54:20.672021Z","steps":["trace[1326968405] 'process raft request' (duration: 127.264883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:20.672112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.733326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:54:20.672162Z","caller":"traceutil/trace.go:171","msg":"trace[1938392900] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1671; }","duration":"100.816528ms","start":"2026-08-16T12:54:20.571317Z","end":"2026-08-16T12:54:20.672134Z","steps":["trace[1938392900] 'agreement among raft nodes before linearized reading' (duration: 100.724036ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:20.671433Z","caller":"traceutil/trace.go:171","msg":"trace[1992799876] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"129.433187ms","start":"2026-08-16T12:54:20.54196Z","end":"2026-08-16T12:54:20.671394Z","steps":["trace[1992799876] 'process raft request' (duration: 107.372969ms)","trace[1992799876] 'compare' (duration: 21.79737ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:20.672218Z","caller":"traceutil/trace.go:171","msg":"trace[830114370] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"126.258328ms","start":"2026-08-16T12:54:20.545937Z","end":"2026-08-16T12:54:20.672195Z","steps":["trace[830114370] 'process raft request' (duration: 126.027972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:22.886264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.168655ms","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-08-16T12:54:22.886346Z","caller":"traceutil/trace.go:171","msg":"trace[1137898269] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1674; }","duration":"210.305658ms","start":"2026-08-16T12:54:22.67602Z","end":"2026-08-16T12:54:22.886326Z","steps":["trace[1137898269] 'range keys from in-memory index tree' (duration: 209.910059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:22.886373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.964581ms","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-08-16T12:54:22.886448Z","caller":"traceutil/trace.go:171","msg":"trace[255623002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1674; }","duration":"210.076193ms","start":"2026-08-16T12:54:22.676355Z","end":"2026-08-16T12:54:22.886432Z","steps":["trace[255623002] 'range keys from in-memory index tree' (duration: 209.814297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:22.886554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.025112ms","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-08-16T12:54:22.886505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.257554ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:54:22.886625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.450086ms","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-08-16T12:54:22.886652Z","caller":"traceutil/trace.go:171","msg":"trace[1102207654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1674; }","duration":"209.492939ms","start":"2026-08-16T12:54:22.67715Z","end":"2026-08-16T12:54:22.886643Z","steps":["trace[1102207654] 'range keys from in-memory index tree' (duration: 209.343144ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:22.886665Z","caller":"traceutil/trace.go:171","msg":"trace[1389929165] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1674; }","duration":"118.477499ms","start":"2026-08-16T12:54:22.768173Z","end":"2026-08-16T12:54:22.88665Z","steps":["trace[1389929165] 'range keys from in-memory index tree' (duration: 118.222193ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:22.886661Z","caller":"traceutil/trace.go:171","msg":"trace[54579600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1674; }","duration":"210.135104ms","start":"2026-08-16T12:54:22.676479Z","end":"2026-08-16T12:54:22.886615Z","steps":["trace[54579600] 'range keys from in-memory index tree' (duration: 209.834857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:22.88667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.487543ms","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-08-16T12:54:22.886772Z","caller":"traceutil/trace.go:171","msg":"trace[728737132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1674; }","duration":"210.622336ms","start":"2026-08-16T12:54:22.676126Z","end":"2026-08-16T12:54:22.886748Z","steps":["trace[728737132] 'range keys from in-memory index tree' (duration: 210.325909ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:33.844268Z","caller":"traceutil/trace.go:171","msg":"trace[1973375459] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"627.514245ms","start":"2026-08-16T12:55:33.216724Z","end":"2026-08-16T12:55:33.844238Z","steps":["trace[1973375459] 'process raft request' (duration: 627.132646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:33.844441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:33.216704Z","time spent":"627.644298ms","remote":"127.0.0.1:45524","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-08-16T12:55:53.345348Z","caller":"traceutil/trace.go:171","msg":"trace[980680312] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"399.446701ms","start":"2026-08-16T12:55:52.945872Z","end":"2026-08-16T12:55:53.345318Z","steps":["trace[980680312] 'read index received' (duration: 399.211965ms)","trace[980680312] 'applied index is now lower than readState.Index' (duration: 233.486µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:53.345497Z","caller":"traceutil/trace.go:171","msg":"trace[24726081] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"462.673087ms","start":"2026-08-16T12:55:52.882764Z","end":"2026-08-16T12:55:53.345437Z","steps":["trace[24726081] 'process raft request' (duration: 462.406521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:53.345567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.684157ms","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-08-16T12:55:53.345605Z","caller":"traceutil/trace.go:171","msg":"trace[202388768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2119; }","duration":"399.78074ms","start":"2026-08-16T12:55:52.945815Z","end":"2026-08-16T12:55:53.345596Z","steps":["trace[202388768] 'agreement among raft nodes before linearized reading' (duration: 399.614806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:53.345677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:52.94579Z","time spent":"399.878101ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:55:53.34569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:52.882745Z","time spent":"462.832941ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:55.146929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022481642535965,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:55:55.356831Z","caller":"traceutil/trace.go:171","msg":"trace[7857728] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"710.306344ms","start":"2026-08-16T12:55:54.646505Z","end":"2026-08-16T12:55:55.356811Z","steps":["trace[7857728] 'read index received' (duration: 710.250072ms)","trace[7857728] 'applied index is now lower than readState.Index' (duration: 55.332µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:55.356956Z","caller":"traceutil/trace.go:171","msg":"trace[934841855] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"711.085993ms","start":"2026-08-16T12:55:54.645835Z","end":"2026-08-16T12:55:55.35692Z","steps":["trace[934841855] 'process raft request' (duration: 710.850578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.357093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.56658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-16T12:55:55.357146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.64582Z","time spent":"711.232956ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:55.357155Z","caller":"traceutil/trace.go:171","msg":"trace[1270421276] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2126; }","duration":"710.677162ms","start":"2026-08-16T12:55:54.64646Z","end":"2026-08-16T12:55:55.357138Z","steps":["trace[1270421276] 'agreement among raft nodes before linearized reading' (duration: 710.455187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.357212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.64644Z","time spent":"710.759605ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-16T12:55:55.358897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.864661ms","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-08-16T12:55:55.358979Z","caller":"traceutil/trace.go:171","msg":"trace[49979160] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2127; }","duration":"706.959083ms","start":"2026-08-16T12:55:54.652002Z","end":"2026-08-16T12:55:55.358961Z","steps":["trace[49979160] 'agreement among raft nodes before linearized reading' (duration: 706.791359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.359021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.651997Z","time spent":"707.016074ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-16T12:55:55.359525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.035409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:55:55.359547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.885707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-08-16T12:55:55.359599Z","caller":"traceutil/trace.go:171","msg":"trace[235067354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2127; }","duration":"483.14196ms","start":"2026-08-16T12:55:54.876437Z","end":"2026-08-16T12:55:55.359579Z","steps":["trace[235067354] 'agreement among raft nodes before linearized reading' (duration: 483.002277ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:55.359611Z","caller":"traceutil/trace.go:171","msg":"trace[873601447] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2127; }","duration":"456.99745ms","start":"2026-08-16T12:55:54.902595Z","end":"2026-08-16T12:55:55.359592Z","steps":["trace[873601447] 'agreement among raft nodes before linearized reading' (duration: 456.793505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.35965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.876421Z","time spent":"483.219303ms","remote":"127.0.0.1:45254","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-08-16T12:55:55.359799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.902575Z","time spent":"457.075402ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-16T12:55:55.360431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.572448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-08-16T12:55:55.36051Z","caller":"traceutil/trace.go:171","msg":"trace[313035801] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2127; }","duration":"603.69102ms","start":"2026-08-16T12:55:54.756799Z","end":"2026-08-16T12:55:55.36049Z","steps":["trace[313035801] 'agreement among raft nodes before linearized reading' (duration: 602.587334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.360561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.756785Z","time spent":"603.765252ms","remote":"127.0.0.1:45338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050290,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "}